2026-02-26 04:13:16,846 - INFO - Domain Default found not creating
2026-02-26 04:13:17,055 - INFO - Project ctest-TestMd5testsOnControl-01708255 not found, creating it
2026-02-26 04:13:17,634 - INFO - Created Project:ctest-TestMd5testsOnControl-01708255, ID : d3ed7d7c-9e55-4b17-afc2-0231b4c87666
2026-02-26 04:13:20,343 - DEBUG - Response for create_network : {'network': {'id': 'c9c10435-18bf-486a-b656-8877300ce796', 'name': 'test_vnv6sr', 'tenant_id': 'd3ed7d7c9e554b17afc20231b4c87666', 'project_id': 'd3ed7d7c9e554b17afc20231b4c87666', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-01708255', 'test_vnv6sr'], 'port_security_enabled': True, 'description': ''}}
2026-02-26 04:13:20,653 - DEBUG - Response for create_subnet : {'subnet': {'id': 'e9eba49a-4328-44b7-8419-f012071b63f7', 'name': '', 'tenant_id': 'd3ed7d7c9e554b17afc20231b4c87666', 'network_id': 'c9c10435-18bf-486a-b656-8877300ce796', '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': 'd3ed7d7c9e554b17afc20231b4c87666'}}
2026-02-26 04:13:20,941 - DEBUG - Response for create_subnet : {'subnet': {'id': '27ea046b-3161-4df8-8bbe-a4d5533dd496', 'name': '', 'tenant_id': 'd3ed7d7c9e554b17afc20231b4c87666', 'network_id': 'c9c10435-18bf-486a-b656-8877300ce796', 'ip_version': 4, 'cidr': '99.125.192.0/26', 'allocation_pools': [{'start': '99.125.192.2', 'end': '99.125.192.62'}], 'gateway_ip': '99.125.192.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '99.125.192.2', 'tags': [], 'project_id': 'd3ed7d7c9e554b17afc20231b4c87666'}}
2026-02-26 04:13:20,968 - INFO - Created VN test_vnv6sr
2026-02-26 04:13:20,979 - DEBUG - VN test_vnv6sr UUID is c9c10435-18bf-486a-b656-8877300ce796
2026-02-26 04:13:21,404 - DEBUG - Response for create_network : {'network': {'id': 'c33d1fb3-988e-439e-8262-6a837e932736', 'name': 'test_vnv6dn', 'tenant_id': 'd3ed7d7c9e554b17afc20231b4c87666', 'project_id': 'd3ed7d7c9e554b17afc20231b4c87666', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-01708255', 'test_vnv6dn'], 'port_security_enabled': True, 'description': ''}}
2026-02-26 04:13:21,700 - DEBUG - Response for create_subnet : {'subnet': {'id': '27b6422f-f67e-4eb0-b6fe-fb5cb9957e18', 'name': '', 'tenant_id': 'd3ed7d7c9e554b17afc20231b4c87666', 'network_id': 'c33d1fb3-988e-439e-8262-6a837e932736', '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': 'd3ed7d7c9e554b17afc20231b4c87666'}}
2026-02-26 04:13:21,925 - DEBUG - Response for create_subnet : {'subnet': {'id': '16d3d31b-e7d2-4aad-8487-3f5b42039c94', 'name': '', 'tenant_id': 'd3ed7d7c9e554b17afc20231b4c87666', 'network_id': 'c33d1fb3-988e-439e-8262-6a837e932736', 'ip_version': 4, 'cidr': '120.32.70.128/26', 'allocation_pools': [{'start': '120.32.70.130', 'end': '120.32.70.190'}], 'gateway_ip': '120.32.70.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '120.32.70.130', 'tags': [], 'project_id': 'd3ed7d7c9e554b17afc20231b4c87666'}}
2026-02-26 04:13:21,963 - INFO - Created VN test_vnv6dn
2026-02-26 04:13:21,977 - DEBUG - VN test_vnv6dn UUID is c33d1fb3-988e-439e-8262-6a837e932736
2026-02-26 04:13:22,287 - DEBUG - Services list from nova: [, , , ]
2026-02-26 04:13:23,656 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5402-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5402-2)
2026-02-26 04:13:25,503 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5402-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5402-1)
2026-02-26 04:13:25,503 - INFO - Waiting for VM source_vm to be up..
2026-02-26 04:13:25,615 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2026-02-26 04:13:30,713 - DEBUG - VM is in ACTIVE state now
2026-02-26 04:13:30,714 - INFO - VM name : source_vm
2026-02-26 04:13:30,814 - DEBUG - VM source_vm ID is fe68fa92-28f1-462a-b7d6-f2795dac332c
2026-02-26 04:13:30,843 - DEBUG - VM source_vm launched on Node an-jenkins-deploy-platform-ansible-os-5402-2
2026-02-26 04:13:30,929 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine/fe68fa92-28f1-462a-b7d6-f2795dac332c
2026-02-26 04:13:31,294 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine/fe68fa92-28f1-462a-b7d6-f2795dac332c
2026-02-26 04:13:31,335 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine-interface/52d63835-6298-4d44-b257-5fbb2851acbe
2026-02-26 04:13:34,524 - 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 1023ms')
2026-02-26 04:13:34,524 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM source_vm failed!
2026-02-26 04:13:34,543 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-01708255:test_vnv6sr is 2001::101:1 and allocation pool is NOT set
2026-02-26 04:13:34,543 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-01708255:test_vnv6sr is 99.125.192.1 and allocation pool is NOT set
2026-02-26 04:13:36,609 - 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.84 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.746 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.746/5.291/9.837/4.545 ms')
2026-02-26 04:13:36,609 - INFO - Ping to Metadata IP 169.254.0.3 of VM source_vm passed
2026-02-26 04:13:36,685 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-02-26 04:13:36,685 - DEBUG - Waiting to SSH to VM source_vm, IP 99.125.192.3, Port 22
2026-02-26 04:13:36,849 - DEBUG - VM source_vm is ready for SSH connections
2026-02-26 04:13:36,850 - INFO - Waiting for VM dest_vm to be up..
2026-02-26 04:13:36,945 - DEBUG - VM is in ACTIVE state now
2026-02-26 04:13:36,945 - INFO - VM name : dest_vm
2026-02-26 04:13:37,061 - DEBUG - VM dest_vm ID is 4aa6ab95-6608-498d-9245-31c01097413a
2026-02-26 04:13:37,062 - DEBUG - VM dest_vm launched on Node an-jenkins-deploy-platform-ansible-os-5402-1
2026-02-26 04:13:37,160 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine/4aa6ab95-6608-498d-9245-31c01097413a
2026-02-26 04:13:37,172 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine-interface/c498525c-1e73-405d-a5fe-c55f369ac0a6
2026-02-26 04:13:38,342 - 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.21 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.700 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.700/2.457/4.214/1.757 ms')
2026-02-26 04:13:38,342 - INFO - Ping to Metadata IP 169.254.0.3 of VM dest_vm passed
2026-02-26 04:13:38,424 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-02-26 04:13:38,425 - DEBUG - Waiting to SSH to VM dest_vm, IP 120.32.70.131, Port 22
2026-02-26 04:13:38,491 - 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': ''}
2026-02-26 04:13:38,614 - DEBUG - VM dest_vm is NOT ready for SSH connections, VM status: ACTIVE
2026-02-26 04:13:43,614 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-02-26 04:13:43,615 - DEBUG - Waiting to SSH to VM dest_vm, IP 120.32.70.131, Port 22
2026-02-26 04:13:43,784 - DEBUG - VM dest_vm is ready for SSH connections
2026-02-26 04:13:43,792 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-01708255: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-01708255: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]
2026-02-26 04:13:44,143 - INFO - Attaching policy allow_all to vn test_vnv6sr
2026-02-26 04:13:44,549 - INFO - Attaching policy allow_all to vn test_vnv6dn
2026-02-26 04:13:44,838 - INFO - Configure the policy with allow any
2026-02-26 04:13:45,214 - DEBUG - Response for create_network : {'network': {'id': '84434378-7a6a-4293-bea0-b44d203c2016', 'name': 'vn1', 'tenant_id': 'd3ed7d7c9e554b17afc20231b4c87666', 'project_id': 'd3ed7d7c9e554b17afc20231b4c87666', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-01708255', 'vn1'], 'port_security_enabled': True, 'description': ''}}
2026-02-26 04:13:45,489 - DEBUG - Response for create_subnet : {'subnet': {'id': 'de49e875-2d35-447f-b9d0-292b101aae36', 'name': '', 'tenant_id': 'd3ed7d7c9e554b17afc20231b4c87666', 'network_id': '84434378-7a6a-4293-bea0-b44d203c2016', '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': 'd3ed7d7c9e554b17afc20231b4c87666'}}
2026-02-26 04:13:45,524 - INFO - Created VN vn1
2026-02-26 04:13:45,539 - DEBUG - VN vn1 UUID is 84434378-7a6a-4293-bea0-b44d203c2016
2026-02-26 04:13:45,913 - DEBUG - Response for create_network : {'network': {'id': '274009ef-48bd-4514-898f-4e2112c9c42f', 'name': 'vn2', 'tenant_id': 'd3ed7d7c9e554b17afc20231b4c87666', 'project_id': 'd3ed7d7c9e554b17afc20231b4c87666', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-01708255', 'vn2'], 'port_security_enabled': True, 'description': ''}}
2026-02-26 04:13:46,197 - DEBUG - Response for create_subnet : {'subnet': {'id': '665a4307-0552-4daf-bd0d-09ade4537f1a', 'name': '', 'tenant_id': 'd3ed7d7c9e554b17afc20231b4c87666', 'network_id': '274009ef-48bd-4514-898f-4e2112c9c42f', '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': 'd3ed7d7c9e554b17afc20231b4c87666'}}
2026-02-26 04:13:46,228 - INFO - Created VN vn2
2026-02-26 04:13:46,243 - DEBUG - VN vn2 UUID is 274009ef-48bd-4514-898f-4e2112c9c42f
2026-02-26 04:13:46,359 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-01708255: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-01708255: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]
2026-02-26 04:13:46,702 - INFO - Attaching policy sec_grp_policy_ctest-random-09281659 to vn vn1
2026-02-26 04:13:47,073 - INFO - Attaching policy sec_grp_policy_ctest-random-09281659 to vn vn2
2026-02-26 04:13:48,955 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5402-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5402-2)
2026-02-26 04:13:51,012 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5402-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5402-1)
2026-02-26 04:13:51,078 - INFO - ================================================================================
2026-02-26 04:13:51,078 - INFO - STARTING TEST : test_check_per_peer_on_control
2026-02-26 04:13:51,078 - INFO - TEST DESCRIPTION :
Description: Verify per peer md5 and specific protocol on all ports and policy with allow all between VN's
2026-02-26 04:13:52,404 - DEBUG - Skipping xmpp flap check
2026-02-26 04:13:52,405 - INFO - Initial checks done. Running the testcase now
2026-02-26 04:13:52,405 - INFO -
2026-02-26 04:13:52,405 - INFO - config per peer with auth_data None
2026-02-26 04:13:53,050 - INFO - configure MD5 on host b7efc86d-db92-418f-92bc-ff33f470295c
2026-02-26 04:13:53,162 - INFO - config per peer with auth_data None
2026-02-26 04:13:53,791 - INFO - configure MD5 on host 0c457e02-0509-4841-beea-98f30b58e23e
2026-02-26 04:13:53,878 - INFO - config per peer with auth_data None
2026-02-26 04:13:54,538 - INFO - configure MD5 on host a8f6ba01-3ffd-485f-a70b-5d8c1625f4d7
2026-02-26 04:13:54,622 - INFO - check BGP peering is up before setting md5 config
2026-02-26 04:13:54,622 - INFO - Check BGP staus between peers
2026-02-26 04:13:54,636 - INFO - config per peer with auth_data {'key_items': [{'key': 'juniper', 'key_id': 0}], 'key_type': 'md5'}
2026-02-26 04:13:55,294 - INFO - Check BGP staus between peers
2026-02-26 04:13:55,314 - DEBUG - Check the BGP connection on 10.0.0.65
2026-02-26 04:13:55,314 - DEBUG - Check the BGP connection on 10.0.0.65
2026-02-26 04:14:05,315 - INFO - Check BGP staus between peers
2026-02-26 04:14:05,327 - INFO - remove md5 configured on host b7efc86d-db92-418f-92bc-ff33f470295c
2026-02-26 04:14:05,327 - INFO - config per peer with auth_data None
2026-02-26 04:14:05,950 - INFO - configure MD5 on host b7efc86d-db92-418f-92bc-ff33f470295c
2026-02-26 04:14:06,043 - INFO - remove md5 configured on host 0c457e02-0509-4841-beea-98f30b58e23e
2026-02-26 04:14:06,043 - INFO - config per peer with auth_data None
2026-02-26 04:14:06,710 - INFO - configure MD5 on host 0c457e02-0509-4841-beea-98f30b58e23e
2026-02-26 04:14:06,806 - INFO - remove md5 configured on host a8f6ba01-3ffd-485f-a70b-5d8c1625f4d7
2026-02-26 04:14:06,806 - INFO - config per peer with auth_data None
2026-02-26 04:14:07,430 - INFO - configure MD5 on host a8f6ba01-3ffd-485f-a70b-5d8c1625f4d7
2026-02-26 04:14:07,518 - INFO - Skip interface_detach for VM vn2_vm_0 in state BUILD
2026-02-26 04:14:07,518 - INFO - Deleting VM vn2_vm_0
2026-02-26 04:14:07,519 - ERROR - VM vn2_vm_0 has failed to come up
2026-02-26 04:14:07,519 - ERROR - Nova failed to get host of the VM
2026-02-26 04:14:07,519 - ERROR - VM vn2_vm_0 has failed to come up
2026-02-26 04:14:07,519 - ERROR - Nova failed to get host of the VM
2026-02-26 04:14:07,676 - INFO - Skip interface_detach for VM vn1_vm_0 in state BUILD
2026-02-26 04:14:07,676 - INFO - Deleting VM vn1_vm_0
2026-02-26 04:14:07,676 - ERROR - VM vn1_vm_0 has failed to come up
2026-02-26 04:14:07,676 - ERROR - Nova failed to get host of the VM
2026-02-26 04:14:07,676 - ERROR - VM vn1_vm_0 has failed to come up
2026-02-26 04:14:07,676 - ERROR - Nova failed to get host of the VM
2026-02-26 04:14:07,800 - INFO - Dettaching policy sec_grp_policy_ctest-random-09281659 from vn vn2
2026-02-26 04:14:07,986 - INFO - Dettaching policy sec_grp_policy_ctest-random-09281659 from vn vn1
2026-02-26 04:14:08,246 - INFO - Deleted policy sec_grp_policy_ctest-random-09281659
2026-02-26 04:14:08,247 - INFO - Deleting VN vn2
2026-02-26 04:14:08,360 - DEBUG - VN 274009ef-48bd-4514-898f-4e2112c9c42f still in use: Unable to complete operation on network 274009ef-48bd-4514-898f-4e2112c9c42f. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-b774c336-f5d8-4a5c-a334-d108c5e04aff']
2026-02-26 04:14:08,360 - WARNING - Deleting VN vn2 failed..Will retry
2026-02-26 04:14:10,535 - DEBUG - Response for deleting network ()
2026-02-26 04:14:10,535 - INFO - Deleting VN vn1
2026-02-26 04:14:10,740 - DEBUG - Response for deleting network ()
2026-02-26 04:14:10,740 - INFO - Dettaching policy allow_all from vn test_vnv6dn
2026-02-26 04:14:10,910 - INFO - Dettaching policy allow_all from vn test_vnv6sr
2026-02-26 04:14:11,185 - INFO - Deleted policy allow_all
2026-02-26 04:14:11,185 - INFO - Deleting VM dest_vm
2026-02-26 04:14:11,285 - INFO - Deleting VM source_vm
2026-02-26 04:14:11,371 - INFO - Deleting VN test_vnv6dn
2026-02-26 04:14:11,422 - DEBUG - VN c33d1fb3-988e-439e-8262-6a837e932736 still in use: Unable to complete operation on network c33d1fb3-988e-439e-8262-6a837e932736. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-ac5de33d-040a-46a8-a9ce-86f757161874']
2026-02-26 04:14:11,422 - WARNING - Deleting VN test_vnv6dn failed..Will retry
2026-02-26 04:14:13,632 - DEBUG - Response for deleting network ()
2026-02-26 04:14:13,632 - INFO - Deleting VN test_vnv6sr
2026-02-26 04:14:13,827 - DEBUG - Response for deleting network ()
2026-02-26 04:14:15,122 - DEBUG - Skipping xmpp flap check
2026-02-26 04:14:15,122 - INFO - END TEST : test_check_per_peer_on_control : PASSED[0:00:24]
2026-02-26 04:14:15,122 - INFO - --------------------------------------------------------------------------------
2026-02-26 04:14:15,429 - DEBUG - Response for create_network : {'network': {'id': '55aaee63-561b-4f33-b1cc-dd3c0f1cced5', 'name': 'test_vnv6sr', 'tenant_id': 'd3ed7d7c9e554b17afc20231b4c87666', 'project_id': 'd3ed7d7c9e554b17afc20231b4c87666', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-01708255', 'test_vnv6sr'], 'port_security_enabled': True, 'description': ''}}
2026-02-26 04:14:15,663 - DEBUG - Response for create_subnet : {'subnet': {'id': 'ffdbfb14-150e-4c62-a41c-75abfd5bedef', 'name': '', 'tenant_id': 'd3ed7d7c9e554b17afc20231b4c87666', 'network_id': '55aaee63-561b-4f33-b1cc-dd3c0f1cced5', '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': 'd3ed7d7c9e554b17afc20231b4c87666'}}
2026-02-26 04:14:15,948 - DEBUG - Response for create_subnet : {'subnet': {'id': '25989197-6370-4b0f-bd41-651cc1636fae', 'name': '', 'tenant_id': 'd3ed7d7c9e554b17afc20231b4c87666', 'network_id': '55aaee63-561b-4f33-b1cc-dd3c0f1cced5', 'ip_version': 4, 'cidr': '178.106.152.64/26', 'allocation_pools': [{'start': '178.106.152.66', 'end': '178.106.152.126'}], 'gateway_ip': '178.106.152.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '178.106.152.66', 'tags': [], 'project_id': 'd3ed7d7c9e554b17afc20231b4c87666'}}
2026-02-26 04:14:15,978 - INFO - Created VN test_vnv6sr
2026-02-26 04:14:15,992 - DEBUG - VN test_vnv6sr UUID is 55aaee63-561b-4f33-b1cc-dd3c0f1cced5
2026-02-26 04:14:16,386 - DEBUG - Response for create_network : {'network': {'id': 'b16c23c9-3428-4a5b-9b28-856ef5db5a2a', 'name': 'test_vnv6dn', 'tenant_id': 'd3ed7d7c9e554b17afc20231b4c87666', 'project_id': 'd3ed7d7c9e554b17afc20231b4c87666', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-01708255', 'test_vnv6dn'], 'port_security_enabled': True, 'description': ''}}
2026-02-26 04:14:16,589 - DEBUG - Response for create_subnet : {'subnet': {'id': 'f7b31309-4bda-4886-83ff-edfd12ba1258', 'name': '', 'tenant_id': 'd3ed7d7c9e554b17afc20231b4c87666', 'network_id': 'b16c23c9-3428-4a5b-9b28-856ef5db5a2a', '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': 'd3ed7d7c9e554b17afc20231b4c87666'}}
2026-02-26 04:14:16,844 - DEBUG - Response for create_subnet : {'subnet': {'id': 'de566534-f0df-4a62-849a-101076d339c5', 'name': '', 'tenant_id': 'd3ed7d7c9e554b17afc20231b4c87666', 'network_id': 'b16c23c9-3428-4a5b-9b28-856ef5db5a2a', 'ip_version': 4, 'cidr': '82.166.93.0/26', 'allocation_pools': [{'start': '82.166.93.2', 'end': '82.166.93.62'}], 'gateway_ip': '82.166.93.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '82.166.93.2', 'tags': [], 'project_id': 'd3ed7d7c9e554b17afc20231b4c87666'}}
2026-02-26 04:14:16,871 - INFO - Created VN test_vnv6dn
2026-02-26 04:14:16,886 - DEBUG - VN test_vnv6dn UUID is b16c23c9-3428-4a5b-9b28-856ef5db5a2a
2026-02-26 04:14:18,393 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5402-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5402-2)
2026-02-26 04:14:20,061 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5402-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5402-1)
2026-02-26 04:14:20,062 - INFO - Waiting for VM source_vm to be up..
2026-02-26 04:14:20,171 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2026-02-26 04:14:25,268 - DEBUG - VM is in ACTIVE state now
2026-02-26 04:14:25,269 - INFO - VM name : source_vm
2026-02-26 04:14:25,375 - DEBUG - VM source_vm ID is b04bd0fd-18da-4ce7-b576-22d7d3ed8b1b
2026-02-26 04:14:25,375 - DEBUG - VM source_vm launched on Node an-jenkins-deploy-platform-ansible-os-5402-2
2026-02-26 04:14:25,504 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine/b04bd0fd-18da-4ce7-b576-22d7d3ed8b1b
2026-02-26 04:14:25,517 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine-interface/944a9aac-c79e-4d66-9f79-5dcbbada1010
2026-02-26 04:14:28,732 - 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 1030ms')
2026-02-26 04:14:28,732 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM source_vm failed!
2026-02-26 04:14:28,749 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-01708255:test_vnv6sr is 2001::101:1 and allocation pool is NOT set
2026-02-26 04:14:28,749 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-01708255:test_vnv6sr is 178.106.152.65 and allocation pool is NOT set
2026-02-26 04:14:30,816 - 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.52 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.359 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.359/4.438/8.517/4.079 ms')
2026-02-26 04:14:30,816 - INFO - Ping to Metadata IP 169.254.0.3 of VM source_vm passed
2026-02-26 04:14:30,895 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-02-26 04:14:30,896 - DEBUG - Waiting to SSH to VM source_vm, IP 178.106.152.67, Port 22
2026-02-26 04:14:30,965 - 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': ''}
2026-02-26 04:14:31,062 - DEBUG - VM source_vm is NOT ready for SSH connections, VM status: ACTIVE
2026-02-26 04:14:36,062 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-02-26 04:14:36,062 - DEBUG - Waiting to SSH to VM source_vm, IP 178.106.152.67, Port 22
2026-02-26 04:14:36,232 - DEBUG - VM source_vm is ready for SSH connections
2026-02-26 04:14:36,232 - INFO - Waiting for VM dest_vm to be up..
2026-02-26 04:14:36,322 - DEBUG - VM is in ACTIVE state now
2026-02-26 04:14:36,322 - INFO - VM name : dest_vm
2026-02-26 04:14:36,418 - DEBUG - VM dest_vm ID is 5eff89ef-daee-4b1e-8c71-9296dbe0c0cf
2026-02-26 04:14:36,418 - DEBUG - VM dest_vm launched on Node an-jenkins-deploy-platform-ansible-os-5402-1
2026-02-26 04:14:36,543 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine/5eff89ef-daee-4b1e-8c71-9296dbe0c0cf
2026-02-26 04:14:36,559 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine-interface/781f1b4d-9099-4f1f-8425-62c44fc0607b
2026-02-26 04:14:37,731 - 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.49 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.440 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.440/1.966/3.492/1.526 ms')
2026-02-26 04:14:37,731 - INFO - Ping to Metadata IP 169.254.0.3 of VM dest_vm passed
2026-02-26 04:14:37,803 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-02-26 04:14:37,804 - DEBUG - Waiting to SSH to VM dest_vm, IP 82.166.93.3, Port 22
2026-02-26 04:14:37,980 - DEBUG - VM dest_vm is ready for SSH connections
2026-02-26 04:14:37,986 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-01708255: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-01708255: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]
2026-02-26 04:14:38,345 - INFO - Attaching policy allow_all to vn test_vnv6sr
2026-02-26 04:14:38,730 - INFO - Attaching policy allow_all to vn test_vnv6dn
2026-02-26 04:14:39,018 - INFO - Configure the policy with allow any
2026-02-26 04:14:39,330 - DEBUG - Response for create_network : {'network': {'id': '2d936c9e-bbcb-42ef-832a-474cb4c74c33', 'name': 'vn1', 'tenant_id': 'd3ed7d7c9e554b17afc20231b4c87666', 'project_id': 'd3ed7d7c9e554b17afc20231b4c87666', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-01708255', 'vn1'], 'port_security_enabled': True, 'description': ''}}
2026-02-26 04:14:39,590 - DEBUG - Response for create_subnet : {'subnet': {'id': 'a161fd04-303c-46f0-a8ab-d840a91f41c1', 'name': '', 'tenant_id': 'd3ed7d7c9e554b17afc20231b4c87666', 'network_id': '2d936c9e-bbcb-42ef-832a-474cb4c74c33', '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': 'd3ed7d7c9e554b17afc20231b4c87666'}}
2026-02-26 04:14:39,615 - INFO - Created VN vn1
2026-02-26 04:14:39,628 - DEBUG - VN vn1 UUID is 2d936c9e-bbcb-42ef-832a-474cb4c74c33
2026-02-26 04:14:40,002 - DEBUG - Response for create_network : {'network': {'id': '73406644-51fa-4d35-ae9b-3d2abec27eaf', 'name': 'vn2', 'tenant_id': 'd3ed7d7c9e554b17afc20231b4c87666', 'project_id': 'd3ed7d7c9e554b17afc20231b4c87666', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-01708255', 'vn2'], 'port_security_enabled': True, 'description': ''}}
2026-02-26 04:14:40,349 - DEBUG - Response for create_subnet : {'subnet': {'id': '1d73f271-7989-41a3-ac8b-a1af27330b36', 'name': '', 'tenant_id': 'd3ed7d7c9e554b17afc20231b4c87666', 'network_id': '73406644-51fa-4d35-ae9b-3d2abec27eaf', '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': 'd3ed7d7c9e554b17afc20231b4c87666'}}
2026-02-26 04:14:40,373 - INFO - Created VN vn2
2026-02-26 04:14:40,387 - DEBUG - VN vn2 UUID is 73406644-51fa-4d35-ae9b-3d2abec27eaf
2026-02-26 04:14:40,472 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-01708255: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-01708255: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]
2026-02-26 04:14:40,821 - INFO - Attaching policy sec_grp_policy_ctest-random-17588971 to vn vn1
2026-02-26 04:14:41,178 - INFO - Attaching policy sec_grp_policy_ctest-random-17588971 to vn vn2
2026-02-26 04:14:42,930 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5402-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5402-2)
2026-02-26 04:14:44,560 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5402-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5402-1)
2026-02-26 04:14:44,629 - INFO - ================================================================================
2026-02-26 04:14:44,629 - INFO - STARTING TEST : test_create_md5_on_control
2026-02-26 04:14:44,629 - INFO - TEST DESCRIPTION :
Description: Verify md5 with allow specific protocol on all ports and policy with allow all between VN's
2026-02-26 04:14:45,948 - DEBUG - Skipping xmpp flap check
2026-02-26 04:14:45,948 - INFO - Initial checks done. Running the testcase now
2026-02-26 04:14:45,948 - INFO -
2026-02-26 04:14:45,948 - INFO - config md5 on each host with auth_data None
2026-02-26 04:14:45,949 - INFO - config per peer with auth_data None
2026-02-26 04:14:46,543 - INFO - configure MD5 on host b7efc86d-db92-418f-92bc-ff33f470295c
2026-02-26 04:14:46,634 - INFO - config per peer with auth_data None
2026-02-26 04:14:47,291 - INFO - configure MD5 on host 0c457e02-0509-4841-beea-98f30b58e23e
2026-02-26 04:14:47,390 - INFO - config per peer with auth_data None
2026-02-26 04:14:48,031 - INFO - configure MD5 on host a8f6ba01-3ffd-485f-a70b-5d8c1625f4d7
2026-02-26 04:14:48,135 - INFO - check if BGP between peers are up before setting md5
2026-02-26 04:14:48,135 - INFO - Check BGP staus between peers
2026-02-26 04:14:48,150 - INFO - setting MD5 config on host b7efc86d-db92-418f-92bc-ff33f470295c
2026-02-26 04:14:48,150 - INFO - configure MD5 on host b7efc86d-db92-418f-92bc-ff33f470295c
2026-02-26 04:14:48,238 - INFO - setting MD5 config on host 0c457e02-0509-4841-beea-98f30b58e23e
2026-02-26 04:14:48,238 - INFO - configure MD5 on host 0c457e02-0509-4841-beea-98f30b58e23e
2026-02-26 04:14:48,318 - INFO - setting MD5 config on host a8f6ba01-3ffd-485f-a70b-5d8c1625f4d7
2026-02-26 04:14:48,318 - INFO - configure MD5 on host a8f6ba01-3ffd-485f-a70b-5d8c1625f4d7
2026-02-26 04:14:48,406 - INFO - check if BGP peering is up after md5 config
2026-02-26 04:14:48,407 - INFO - Check BGP staus between peers
2026-02-26 04:14:48,418 - DEBUG - Check the BGP connection on 10.0.0.65
2026-02-26 04:14:48,418 - DEBUG - Check the BGP connection on 10.0.0.65
2026-02-26 04:14:58,419 - INFO - Check BGP staus between peers
2026-02-26 04:14:58,431 - INFO - remove md5 configured on host b7efc86d-db92-418f-92bc-ff33f470295c
2026-02-26 04:14:58,436 - INFO - config per peer with auth_data None
2026-02-26 04:14:59,024 - INFO - configure MD5 on host b7efc86d-db92-418f-92bc-ff33f470295c
2026-02-26 04:14:59,115 - INFO - remove md5 configured on host 0c457e02-0509-4841-beea-98f30b58e23e
2026-02-26 04:14:59,115 - INFO - config per peer with auth_data None
2026-02-26 04:14:59,767 - INFO - configure MD5 on host 0c457e02-0509-4841-beea-98f30b58e23e
2026-02-26 04:14:59,858 - INFO - remove md5 configured on host a8f6ba01-3ffd-485f-a70b-5d8c1625f4d7
2026-02-26 04:14:59,859 - INFO - config per peer with auth_data None
2026-02-26 04:15:00,583 - INFO - configure MD5 on host a8f6ba01-3ffd-485f-a70b-5d8c1625f4d7
2026-02-26 04:15:00,686 - INFO - Skip interface_detach for VM vn2_vm_0 in state BUILD
2026-02-26 04:15:00,687 - INFO - Deleting VM vn2_vm_0
2026-02-26 04:15:00,687 - ERROR - VM vn2_vm_0 has failed to come up
2026-02-26 04:15:00,687 - ERROR - Nova failed to get host of the VM
2026-02-26 04:15:00,687 - ERROR - VM vn2_vm_0 has failed to come up
2026-02-26 04:15:00,687 - ERROR - Nova failed to get host of the VM
2026-02-26 04:15:00,803 - INFO - Skip interface_detach for VM vn1_vm_0 in state BUILD
2026-02-26 04:15:00,803 - INFO - Deleting VM vn1_vm_0
2026-02-26 04:15:00,804 - ERROR - VM vn1_vm_0 has failed to come up
2026-02-26 04:15:00,804 - ERROR - Nova failed to get host of the VM
2026-02-26 04:15:00,804 - ERROR - VM vn1_vm_0 has failed to come up
2026-02-26 04:15:00,804 - ERROR - Nova failed to get host of the VM
2026-02-26 04:15:00,907 - INFO - Dettaching policy sec_grp_policy_ctest-random-17588971 from vn vn2
2026-02-26 04:15:01,175 - INFO - Dettaching policy sec_grp_policy_ctest-random-17588971 from vn vn1
2026-02-26 04:15:01,469 - INFO - Deleted policy sec_grp_policy_ctest-random-17588971
2026-02-26 04:15:01,470 - INFO - Deleting VN vn2
2026-02-26 04:15:01,617 - DEBUG - VN 73406644-51fa-4d35-ae9b-3d2abec27eaf still in use: Unable to complete operation on network 73406644-51fa-4d35-ae9b-3d2abec27eaf. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-190d655a-e7e7-4428-bd91-bf7b3a66cfcc']
2026-02-26 04:15:01,617 - WARNING - Deleting VN vn2 failed..Will retry
2026-02-26 04:15:03,914 - DEBUG - Response for deleting network ()
2026-02-26 04:15:03,914 - INFO - Deleting VN vn1
2026-02-26 04:15:04,117 - DEBUG - Response for deleting network ()
2026-02-26 04:15:04,117 - INFO - Dettaching policy allow_all from vn test_vnv6dn
2026-02-26 04:15:04,596 - INFO - Dettaching policy allow_all from vn test_vnv6sr
2026-02-26 04:15:04,927 - INFO - Deleted policy allow_all
2026-02-26 04:15:04,927 - INFO - Deleting VM dest_vm
2026-02-26 04:15:05,018 - INFO - Deleting VM source_vm
2026-02-26 04:15:05,125 - INFO - Deleting VN test_vnv6dn
2026-02-26 04:15:05,181 - DEBUG - VN b16c23c9-3428-4a5b-9b28-856ef5db5a2a still in use: Unable to complete operation on network b16c23c9-3428-4a5b-9b28-856ef5db5a2a. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-25935bd5-9979-499f-b28d-bb2b5cb33538']
2026-02-26 04:15:05,181 - WARNING - Deleting VN test_vnv6dn failed..Will retry
2026-02-26 04:15:07,360 - DEBUG - Response for deleting network ()
2026-02-26 04:15:07,360 - INFO - Deleting VN test_vnv6sr
2026-02-26 04:15:07,624 - DEBUG - Response for deleting network ()
2026-02-26 04:15:08,925 - DEBUG - Skipping xmpp flap check
2026-02-26 04:15:08,925 - INFO - END TEST : test_create_md5_on_control : PASSED[0:00:24]
2026-02-26 04:15:08,925 - INFO - --------------------------------------------------------------------------------
2026-02-26 04:15:09,772 - INFO - Deleted project: ctest-TestMd5testsOnControl-01708255, ID : d3ed7d7c-9e55-4b17-afc2-0231b4c87666