2026-05-25 04:19:53,768 - INFO - Domain Default found not creating
2026-05-25 04:19:54,006 - INFO - Project ctest-TestMd5testsOnControl-35531579 not found, creating it
2026-05-25 04:19:54,624 - INFO - Created Project:ctest-TestMd5testsOnControl-35531579, ID : 11251c9e-b88d-4840-a6df-94ab941b7f64
2026-05-25 04:19:57,225 - DEBUG - Response for create_network : {'network': {'id': 'db38e5bc-f107-4ae6-b37a-b3c6d96d00ab', 'name': 'test_vnv6sr', 'tenant_id': '11251c9eb88d4840a6df94ab941b7f64', 'project_id': '11251c9eb88d4840a6df94ab941b7f64', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-35531579', 'test_vnv6sr'], 'port_security_enabled': True, 'description': ''}}
2026-05-25 04:19:57,478 - DEBUG - Response for create_subnet : {'subnet': {'id': 'a30009d1-ee50-4265-abf8-7207ba58465f', 'name': '', 'tenant_id': '11251c9eb88d4840a6df94ab941b7f64', 'network_id': 'db38e5bc-f107-4ae6-b37a-b3c6d96d00ab', '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': '11251c9eb88d4840a6df94ab941b7f64'}}
2026-05-25 04:19:57,742 - DEBUG - Response for create_subnet : {'subnet': {'id': '955a3b29-ebe4-461e-affe-904285912408', 'name': '', 'tenant_id': '11251c9eb88d4840a6df94ab941b7f64', 'network_id': 'db38e5bc-f107-4ae6-b37a-b3c6d96d00ab', 'ip_version': 4, 'cidr': '47.54.78.64/26', 'allocation_pools': [{'start': '47.54.78.66', 'end': '47.54.78.126'}], 'gateway_ip': '47.54.78.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '47.54.78.66', 'tags': [], 'project_id': '11251c9eb88d4840a6df94ab941b7f64'}}
2026-05-25 04:19:57,790 - INFO - Created VN test_vnv6sr
2026-05-25 04:19:57,809 - DEBUG - VN test_vnv6sr UUID is db38e5bc-f107-4ae6-b37a-b3c6d96d00ab
2026-05-25 04:19:58,289 - DEBUG - Response for create_network : {'network': {'id': 'ac59d87d-4ce7-439f-9132-806f10dc70d5', 'name': 'test_vnv6dn', 'tenant_id': '11251c9eb88d4840a6df94ab941b7f64', 'project_id': '11251c9eb88d4840a6df94ab941b7f64', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-35531579', 'test_vnv6dn'], 'port_security_enabled': True, 'description': ''}}
2026-05-25 04:19:58,544 - DEBUG - Response for create_subnet : {'subnet': {'id': '7d7e078f-199b-4f25-9a35-9ceaa7af7237', 'name': '', 'tenant_id': '11251c9eb88d4840a6df94ab941b7f64', 'network_id': 'ac59d87d-4ce7-439f-9132-806f10dc70d5', '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': '11251c9eb88d4840a6df94ab941b7f64'}}
2026-05-25 04:19:58,769 - DEBUG - Response for create_subnet : {'subnet': {'id': 'bbd1aafb-3643-4007-868b-87104f061008', 'name': '', 'tenant_id': '11251c9eb88d4840a6df94ab941b7f64', 'network_id': 'ac59d87d-4ce7-439f-9132-806f10dc70d5', 'ip_version': 4, 'cidr': '66.41.175.0/26', 'allocation_pools': [{'start': '66.41.175.2', 'end': '66.41.175.62'}], 'gateway_ip': '66.41.175.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '66.41.175.2', 'tags': [], 'project_id': '11251c9eb88d4840a6df94ab941b7f64'}}
2026-05-25 04:19:58,806 - INFO - Created VN test_vnv6dn
2026-05-25 04:19:58,825 - DEBUG - VN test_vnv6dn UUID is ac59d87d-4ce7-439f-9132-806f10dc70d5
2026-05-25 04:19:59,142 - DEBUG - Services list from nova: [, , , ]
2026-05-25 04:20:00,658 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5822-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5822-2)
2026-05-25 04:20:02,545 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5822-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5822-1)
2026-05-25 04:20:02,545 - INFO - Waiting for VM source_vm to be up..
2026-05-25 04:20:02,683 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2026-05-25 04:20:07,815 - DEBUG - VM is in ACTIVE state now
2026-05-25 04:20:07,816 - INFO - VM name : source_vm
2026-05-25 04:20:07,928 - DEBUG - VM source_vm ID is 576b3aec-2e90-4658-87ee-b698be0632a9
2026-05-25 04:20:07,959 - DEBUG - VM source_vm launched on Node an-jenkins-deploy-platform-ansible-os-5822-2
2026-05-25 04:20:08,068 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine/576b3aec-2e90-4658-87ee-b698be0632a9
2026-05-25 04:20:08,393 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine/576b3aec-2e90-4658-87ee-b698be0632a9
2026-05-25 04:20:08,443 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine-interface/c0b75d31-b86c-41ef-88fb-41032eb52c9c
2026-05-25 04:20:11,649 - 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 1029ms')
2026-05-25 04:20:11,650 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM source_vm failed!
2026-05-25 04:20:11,669 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-35531579:test_vnv6sr is 2001::101:1 and allocation pool is NOT set
2026-05-25 04:20:11,669 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-35531579:test_vnv6sr is 47.54.78.65 and allocation pool is NOT set
2026-05-25 04:20:15,739 - 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=2 ttl=63 time=4.91 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 1 received, 50% packet loss, time 1004ms\r\nrtt min/avg/max/mdev = 4.910/4.910/4.910/0.000 ms')
2026-05-25 04:20:15,739 - INFO - Ping to Metadata IP 169.254.0.3 of VM source_vm passed
2026-05-25 04:20:15,817 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-05-25 04:20:15,817 - DEBUG - Waiting to SSH to VM source_vm, IP 47.54.78.67, Port 22
2026-05-25 04:20:15,992 - DEBUG - VM source_vm is ready for SSH connections
2026-05-25 04:20:15,992 - INFO - Waiting for VM dest_vm to be up..
2026-05-25 04:20:16,233 - DEBUG - VM is in ACTIVE state now
2026-05-25 04:20:16,234 - INFO - VM name : dest_vm
2026-05-25 04:20:16,342 - DEBUG - VM dest_vm ID is 2a21b40e-7a92-41a1-a074-de0ed5da2599
2026-05-25 04:20:16,342 - DEBUG - VM dest_vm launched on Node an-jenkins-deploy-platform-ansible-os-5822-1
2026-05-25 04:20:16,452 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine/2a21b40e-7a92-41a1-a074-de0ed5da2599
2026-05-25 04:20:16,467 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine-interface/81ed587f-d1c0-4183-a8ac-3696c903ad6e
2026-05-25 04:20:17,653 - DEBUG - (True, 'PING 169.254.0.5 (169.254.0.5) 56(84) bytes of data.\r\n64 bytes from 169.254.0.5: icmp_seq=1 ttl=63 time=5.82 ms\r\n64 bytes from 169.254.0.5: icmp_seq=2 ttl=63 time=0.797 ms\r\n\r\n--- 169.254.0.5 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 0.797/3.307/5.817/2.510 ms')
2026-05-25 04:20:17,653 - INFO - Ping to Metadata IP 169.254.0.5 of VM dest_vm passed
2026-05-25 04:20:17,734 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-05-25 04:20:17,734 - DEBUG - Waiting to SSH to VM dest_vm, IP 66.41.175.3, Port 22
2026-05-25 04:20:17,901 - DEBUG - VM dest_vm is ready for SSH connections
2026-05-25 04:20:17,909 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-35531579: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-35531579: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-05-25 04:20:18,269 - INFO - Attaching policy allow_all to vn test_vnv6sr
2026-05-25 04:20:18,712 - INFO - Attaching policy allow_all to vn test_vnv6dn
2026-05-25 04:20:19,021 - INFO - Configure the policy with allow any
2026-05-25 04:20:19,370 - DEBUG - Response for create_network : {'network': {'id': '1747cc5e-c98c-4b72-86fe-d6d08da8db0e', 'name': 'vn1', 'tenant_id': '11251c9eb88d4840a6df94ab941b7f64', 'project_id': '11251c9eb88d4840a6df94ab941b7f64', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-35531579', 'vn1'], 'port_security_enabled': True, 'description': ''}}
2026-05-25 04:20:19,610 - DEBUG - Response for create_subnet : {'subnet': {'id': 'afab2ab0-7fe0-413c-9acf-bc410290f1a3', 'name': '', 'tenant_id': '11251c9eb88d4840a6df94ab941b7f64', 'network_id': '1747cc5e-c98c-4b72-86fe-d6d08da8db0e', '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': '11251c9eb88d4840a6df94ab941b7f64'}}
2026-05-25 04:20:19,641 - INFO - Created VN vn1
2026-05-25 04:20:19,659 - DEBUG - VN vn1 UUID is 1747cc5e-c98c-4b72-86fe-d6d08da8db0e
2026-05-25 04:20:20,154 - DEBUG - Response for create_network : {'network': {'id': 'b351cfbd-a6b9-4b6e-b6d6-1de1e42a9f03', 'name': 'vn2', 'tenant_id': '11251c9eb88d4840a6df94ab941b7f64', 'project_id': '11251c9eb88d4840a6df94ab941b7f64', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-35531579', 'vn2'], 'port_security_enabled': True, 'description': ''}}
2026-05-25 04:20:20,395 - DEBUG - Response for create_subnet : {'subnet': {'id': '19c1a882-b040-4a7d-8702-8c923023b26d', 'name': '', 'tenant_id': '11251c9eb88d4840a6df94ab941b7f64', 'network_id': 'b351cfbd-a6b9-4b6e-b6d6-1de1e42a9f03', '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': '11251c9eb88d4840a6df94ab941b7f64'}}
2026-05-25 04:20:20,423 - INFO - Created VN vn2
2026-05-25 04:20:20,440 - DEBUG - VN vn2 UUID is b351cfbd-a6b9-4b6e-b6d6-1de1e42a9f03
2026-05-25 04:20:20,546 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-35531579: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-35531579: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-05-25 04:20:20,905 - INFO - Attaching policy sec_grp_policy_ctest-random-68134149 to vn vn1
2026-05-25 04:20:21,309 - INFO - Attaching policy sec_grp_policy_ctest-random-68134149 to vn vn2
2026-05-25 04:20:23,159 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5822-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5822-2)
2026-05-25 04:20:24,866 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5822-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5822-1)
2026-05-25 04:20:24,934 - INFO - ================================================================================
2026-05-25 04:20:24,934 - INFO - STARTING TEST : test_check_per_peer_on_control
2026-05-25 04:20:24,934 - INFO - TEST DESCRIPTION :
Description: Verify per peer md5 and specific protocol on all ports and policy with allow all between VN's
2026-05-25 04:20:26,269 - DEBUG - Skipping xmpp flap check
2026-05-25 04:20:26,269 - INFO - Initial checks done. Running the testcase now
2026-05-25 04:20:26,269 - INFO -
2026-05-25 04:20:26,269 - INFO - config per peer with auth_data None
2026-05-25 04:20:26,920 - INFO - configure MD5 on host eb21edc3-c38f-4c15-a003-6da0a513bd0f
2026-05-25 04:20:27,024 - INFO - config per peer with auth_data None
2026-05-25 04:20:27,760 - INFO - configure MD5 on host e36923a5-4ff8-49bd-be07-04c3acd3e848
2026-05-25 04:20:27,860 - INFO - config per peer with auth_data None
2026-05-25 04:20:28,524 - INFO - configure MD5 on host 1ae6a29c-45e6-48cc-9dc8-c69c570ae4a5
2026-05-25 04:20:28,628 - INFO - check BGP peering is up before setting md5 config
2026-05-25 04:20:28,628 - INFO - Check BGP staus between peers
2026-05-25 04:20:28,645 - INFO - config per peer with auth_data {'key_items': [{'key': 'juniper', 'key_id': 0}], 'key_type': 'md5'}
2026-05-25 04:20:29,296 - INFO - Check BGP staus between peers
2026-05-25 04:20:29,321 - DEBUG - Check the BGP connection on 10.0.0.73
2026-05-25 04:20:29,321 - DEBUG - Check the BGP connection on 10.0.0.73
2026-05-25 04:20:39,322 - INFO - Check BGP staus between peers
2026-05-25 04:20:39,336 - INFO - remove md5 configured on host eb21edc3-c38f-4c15-a003-6da0a513bd0f
2026-05-25 04:20:39,336 - INFO - config per peer with auth_data None
2026-05-25 04:20:39,959 - INFO - configure MD5 on host eb21edc3-c38f-4c15-a003-6da0a513bd0f
2026-05-25 04:20:40,052 - INFO - remove md5 configured on host e36923a5-4ff8-49bd-be07-04c3acd3e848
2026-05-25 04:20:40,052 - INFO - config per peer with auth_data None
2026-05-25 04:20:40,708 - INFO - configure MD5 on host e36923a5-4ff8-49bd-be07-04c3acd3e848
2026-05-25 04:20:40,796 - INFO - remove md5 configured on host 1ae6a29c-45e6-48cc-9dc8-c69c570ae4a5
2026-05-25 04:20:40,796 - INFO - config per peer with auth_data None
2026-05-25 04:20:41,455 - INFO - configure MD5 on host 1ae6a29c-45e6-48cc-9dc8-c69c570ae4a5
2026-05-25 04:20:41,560 - INFO - Skip interface_detach for VM vn2_vm_0 in state BUILD
2026-05-25 04:20:41,560 - INFO - Deleting VM vn2_vm_0
2026-05-25 04:20:41,561 - ERROR - VM vn2_vm_0 has failed to come up
2026-05-25 04:20:41,561 - ERROR - Nova failed to get host of the VM
2026-05-25 04:20:41,561 - ERROR - VM vn2_vm_0 has failed to come up
2026-05-25 04:20:41,561 - ERROR - Nova failed to get host of the VM
2026-05-25 04:20:41,663 - INFO - Skip interface_detach for VM vn1_vm_0 in state BUILD
2026-05-25 04:20:41,664 - INFO - Deleting VM vn1_vm_0
2026-05-25 04:20:41,664 - ERROR - VM vn1_vm_0 has failed to come up
2026-05-25 04:20:41,664 - ERROR - Nova failed to get host of the VM
2026-05-25 04:20:41,664 - ERROR - VM vn1_vm_0 has failed to come up
2026-05-25 04:20:41,664 - ERROR - Nova failed to get host of the VM
2026-05-25 04:20:41,772 - INFO - Dettaching policy sec_grp_policy_ctest-random-68134149 from vn vn2
2026-05-25 04:20:41,959 - INFO - Dettaching policy sec_grp_policy_ctest-random-68134149 from vn vn1
2026-05-25 04:20:42,278 - INFO - Deleted policy sec_grp_policy_ctest-random-68134149
2026-05-25 04:20:42,279 - INFO - Deleting VN vn2
2026-05-25 04:20:42,347 - DEBUG - VN b351cfbd-a6b9-4b6e-b6d6-1de1e42a9f03 still in use: Unable to complete operation on network b351cfbd-a6b9-4b6e-b6d6-1de1e42a9f03. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-5e61985f-e9df-424f-9535-a6e03e38f680']
2026-05-25 04:20:42,347 - WARNING - Deleting VN vn2 failed..Will retry
2026-05-25 04:20:44,661 - DEBUG - Response for deleting network ()
2026-05-25 04:20:44,661 - INFO - Deleting VN vn1
2026-05-25 04:20:44,874 - DEBUG - Response for deleting network ()
2026-05-25 04:20:44,874 - INFO - Dettaching policy allow_all from vn test_vnv6dn
2026-05-25 04:20:45,081 - INFO - Dettaching policy allow_all from vn test_vnv6sr
2026-05-25 04:20:45,359 - INFO - Deleted policy allow_all
2026-05-25 04:20:45,360 - INFO - Deleting VM dest_vm
2026-05-25 04:20:45,459 - INFO - Deleting VM source_vm
2026-05-25 04:20:45,551 - INFO - Deleting VN test_vnv6dn
2026-05-25 04:20:45,634 - DEBUG - VN ac59d87d-4ce7-439f-9132-806f10dc70d5 still in use: Unable to complete operation on network ac59d87d-4ce7-439f-9132-806f10dc70d5. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-e6ff2516-a7fc-465f-890b-f8edad1803f4']
2026-05-25 04:20:45,634 - WARNING - Deleting VN test_vnv6dn failed..Will retry
2026-05-25 04:20:47,845 - DEBUG - Response for deleting network ()
2026-05-25 04:20:47,846 - INFO - Deleting VN test_vnv6sr
2026-05-25 04:20:48,068 - DEBUG - Response for deleting network ()
2026-05-25 04:20:49,355 - DEBUG - Skipping xmpp flap check
2026-05-25 04:20:49,355 - INFO - END TEST : test_check_per_peer_on_control : PASSED[0:00:25]
2026-05-25 04:20:49,355 - INFO - --------------------------------------------------------------------------------
2026-05-25 04:20:49,711 - DEBUG - Response for create_network : {'network': {'id': '10b1876a-5224-4e55-930e-5f9f83c9efd5', 'name': 'test_vnv6sr', 'tenant_id': '11251c9eb88d4840a6df94ab941b7f64', 'project_id': '11251c9eb88d4840a6df94ab941b7f64', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-35531579', 'test_vnv6sr'], 'port_security_enabled': True, 'description': ''}}
2026-05-25 04:20:50,003 - DEBUG - Response for create_subnet : {'subnet': {'id': 'd749c441-ff18-434e-9d9f-a8a0c6285a3f', 'name': '', 'tenant_id': '11251c9eb88d4840a6df94ab941b7f64', 'network_id': '10b1876a-5224-4e55-930e-5f9f83c9efd5', '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': '11251c9eb88d4840a6df94ab941b7f64'}}
2026-05-25 04:20:50,322 - DEBUG - Response for create_subnet : {'subnet': {'id': '6657ebe4-7b74-4e40-b341-a45b4f346b58', 'name': '', 'tenant_id': '11251c9eb88d4840a6df94ab941b7f64', 'network_id': '10b1876a-5224-4e55-930e-5f9f83c9efd5', 'ip_version': 4, 'cidr': '57.101.251.192/26', 'allocation_pools': [{'start': '57.101.251.194', 'end': '57.101.251.254'}], 'gateway_ip': '57.101.251.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '57.101.251.194', 'tags': [], 'project_id': '11251c9eb88d4840a6df94ab941b7f64'}}
2026-05-25 04:20:50,357 - INFO - Created VN test_vnv6sr
2026-05-25 04:20:50,376 - DEBUG - VN test_vnv6sr UUID is 10b1876a-5224-4e55-930e-5f9f83c9efd5
2026-05-25 04:20:50,841 - DEBUG - Response for create_network : {'network': {'id': 'dae50aba-47e4-4606-a5a7-fced419eac3c', 'name': 'test_vnv6dn', 'tenant_id': '11251c9eb88d4840a6df94ab941b7f64', 'project_id': '11251c9eb88d4840a6df94ab941b7f64', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-35531579', 'test_vnv6dn'], 'port_security_enabled': True, 'description': ''}}
2026-05-25 04:20:51,127 - DEBUG - Response for create_subnet : {'subnet': {'id': 'f3576057-77ac-4768-9159-646b30b4107e', 'name': '', 'tenant_id': '11251c9eb88d4840a6df94ab941b7f64', 'network_id': 'dae50aba-47e4-4606-a5a7-fced419eac3c', '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': '11251c9eb88d4840a6df94ab941b7f64'}}
2026-05-25 04:20:51,402 - DEBUG - Response for create_subnet : {'subnet': {'id': '0a7a91a0-6a5e-4950-b239-151467d20808', 'name': '', 'tenant_id': '11251c9eb88d4840a6df94ab941b7f64', 'network_id': 'dae50aba-47e4-4606-a5a7-fced419eac3c', 'ip_version': 4, 'cidr': '41.232.88.64/26', 'allocation_pools': [{'start': '41.232.88.66', 'end': '41.232.88.126'}], 'gateway_ip': '41.232.88.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '41.232.88.66', 'tags': [], 'project_id': '11251c9eb88d4840a6df94ab941b7f64'}}
2026-05-25 04:20:51,434 - INFO - Created VN test_vnv6dn
2026-05-25 04:20:51,449 - DEBUG - VN test_vnv6dn UUID is dae50aba-47e4-4606-a5a7-fced419eac3c
2026-05-25 04:20:53,036 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5822-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5822-2)
2026-05-25 04:20:54,644 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5822-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5822-1)
2026-05-25 04:20:54,644 - INFO - Waiting for VM source_vm to be up..
2026-05-25 04:20:54,769 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2026-05-25 04:20:59,901 - DEBUG - VM is in ACTIVE state now
2026-05-25 04:20:59,901 - INFO - VM name : source_vm
2026-05-25 04:21:00,047 - DEBUG - VM source_vm ID is d613adca-f7fc-4b5c-afeb-08bba6c3d404
2026-05-25 04:21:00,048 - DEBUG - VM source_vm launched on Node an-jenkins-deploy-platform-ansible-os-5822-2
2026-05-25 04:21:00,199 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine/d613adca-f7fc-4b5c-afeb-08bba6c3d404
2026-05-25 04:21:00,215 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine-interface/af14d3cd-dc9f-41c6-bb7e-cbeb36be2f40
2026-05-25 04:21:03,414 - 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 1020ms')
2026-05-25 04:21:03,414 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM source_vm failed!
2026-05-25 04:21:03,435 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-35531579:test_vnv6sr is 2001::101:1 and allocation pool is NOT set
2026-05-25 04:21:03,435 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-35531579:test_vnv6sr is 57.101.251.193 and allocation pool is NOT set
2026-05-25 04:21:05,506 - 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=23.3 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=3.34 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.335/13.299/23.264/9.964 ms')
2026-05-25 04:21:05,507 - INFO - Ping to Metadata IP 169.254.0.3 of VM source_vm passed
2026-05-25 04:21:05,587 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-05-25 04:21:05,588 - DEBUG - Waiting to SSH to VM source_vm, IP 57.101.251.195, Port 22
2026-05-25 04:21:05,769 - DEBUG - VM source_vm is ready for SSH connections
2026-05-25 04:21:05,770 - INFO - Waiting for VM dest_vm to be up..
2026-05-25 04:21:05,900 - DEBUG - VM is in ACTIVE state now
2026-05-25 04:21:05,900 - INFO - VM name : dest_vm
2026-05-25 04:21:06,043 - DEBUG - VM dest_vm ID is 0099896d-3722-436e-80fb-6ac6fe564e1e
2026-05-25 04:21:06,043 - DEBUG - VM dest_vm launched on Node an-jenkins-deploy-platform-ansible-os-5822-1
2026-05-25 04:21:06,163 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine/0099896d-3722-436e-80fb-6ac6fe564e1e
2026-05-25 04:21:06,176 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine-interface/b105e9c1-e5ca-4510-89df-9392f77134eb
2026-05-25 04:21:07,364 - DEBUG - (True, 'PING 169.254.0.5 (169.254.0.5) 56(84) bytes of data.\r\n64 bytes from 169.254.0.5: icmp_seq=1 ttl=63 time=5.47 ms\r\n64 bytes from 169.254.0.5: icmp_seq=2 ttl=63 time=2.45 ms\r\n\r\n--- 169.254.0.5 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 2.454/3.961/5.468/1.507 ms')
2026-05-25 04:21:07,365 - INFO - Ping to Metadata IP 169.254.0.5 of VM dest_vm passed
2026-05-25 04:21:07,445 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-05-25 04:21:07,445 - DEBUG - Waiting to SSH to VM dest_vm, IP 41.232.88.67, Port 22
2026-05-25 04:21:07,513 - DEBUG - Error on ssh to cirros@169.254.0.5:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.5/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.5/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.5/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2026-05-25 04:21:07,625 - DEBUG - VM dest_vm is NOT ready for SSH connections, VM status: ACTIVE
2026-05-25 04:21:12,626 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-05-25 04:21:12,626 - DEBUG - Waiting to SSH to VM dest_vm, IP 41.232.88.67, Port 22
2026-05-25 04:21:12,805 - DEBUG - VM dest_vm is ready for SSH connections
2026-05-25 04:21:12,813 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-35531579: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-35531579: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-05-25 04:21:13,215 - INFO - Attaching policy allow_all to vn test_vnv6sr
2026-05-25 04:21:13,616 - INFO - Attaching policy allow_all to vn test_vnv6dn
2026-05-25 04:21:13,965 - INFO - Configure the policy with allow any
2026-05-25 04:21:14,376 - DEBUG - Response for create_network : {'network': {'id': '7a3044a9-0d1a-4e35-af66-a3f8b44457ac', 'name': 'vn1', 'tenant_id': '11251c9eb88d4840a6df94ab941b7f64', 'project_id': '11251c9eb88d4840a6df94ab941b7f64', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-35531579', 'vn1'], 'port_security_enabled': True, 'description': ''}}
2026-05-25 04:21:14,651 - DEBUG - Response for create_subnet : {'subnet': {'id': '73665c93-ee27-4185-9ed5-3435d8ad91c9', 'name': '', 'tenant_id': '11251c9eb88d4840a6df94ab941b7f64', 'network_id': '7a3044a9-0d1a-4e35-af66-a3f8b44457ac', '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': '11251c9eb88d4840a6df94ab941b7f64'}}
2026-05-25 04:21:14,684 - INFO - Created VN vn1
2026-05-25 04:21:14,700 - DEBUG - VN vn1 UUID is 7a3044a9-0d1a-4e35-af66-a3f8b44457ac
2026-05-25 04:21:15,178 - DEBUG - Response for create_network : {'network': {'id': '1b07a583-0931-476e-9e62-79f0e45d519d', 'name': 'vn2', 'tenant_id': '11251c9eb88d4840a6df94ab941b7f64', 'project_id': '11251c9eb88d4840a6df94ab941b7f64', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-35531579', 'vn2'], 'port_security_enabled': True, 'description': ''}}
2026-05-25 04:21:15,514 - DEBUG - Response for create_subnet : {'subnet': {'id': 'e0d43b8d-5c29-4410-9b80-aca54f32cb6e', 'name': '', 'tenant_id': '11251c9eb88d4840a6df94ab941b7f64', 'network_id': '1b07a583-0931-476e-9e62-79f0e45d519d', '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': '11251c9eb88d4840a6df94ab941b7f64'}}
2026-05-25 04:21:15,541 - INFO - Created VN vn2
2026-05-25 04:21:15,558 - DEBUG - VN vn2 UUID is 1b07a583-0931-476e-9e62-79f0e45d519d
2026-05-25 04:21:15,661 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-35531579: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-35531579: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-05-25 04:21:16,026 - INFO - Attaching policy sec_grp_policy_ctest-random-49553682 to vn vn1
2026-05-25 04:21:16,429 - INFO - Attaching policy sec_grp_policy_ctest-random-49553682 to vn vn2
2026-05-25 04:21:18,304 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5822-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5822-2)
2026-05-25 04:21:20,436 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5822-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5822-1)
2026-05-25 04:21:20,509 - INFO - ================================================================================
2026-05-25 04:21:20,509 - INFO - STARTING TEST : test_create_md5_on_control
2026-05-25 04:21:20,510 - INFO - TEST DESCRIPTION :
Description: Verify md5 with allow specific protocol on all ports and policy with allow all between VN's
2026-05-25 04:21:21,846 - DEBUG - Skipping xmpp flap check
2026-05-25 04:21:21,846 - INFO - Initial checks done. Running the testcase now
2026-05-25 04:21:21,847 - INFO -
2026-05-25 04:21:21,847 - INFO - config md5 on each host with auth_data None
2026-05-25 04:21:21,847 - INFO - config per peer with auth_data None
2026-05-25 04:21:22,523 - INFO - configure MD5 on host eb21edc3-c38f-4c15-a003-6da0a513bd0f
2026-05-25 04:21:22,648 - INFO - config per peer with auth_data None
2026-05-25 04:21:23,334 - INFO - configure MD5 on host e36923a5-4ff8-49bd-be07-04c3acd3e848
2026-05-25 04:21:23,448 - INFO - config per peer with auth_data None
2026-05-25 04:21:24,108 - INFO - configure MD5 on host 1ae6a29c-45e6-48cc-9dc8-c69c570ae4a5
2026-05-25 04:21:24,228 - INFO - check if BGP between peers are up before setting md5
2026-05-25 04:21:24,228 - INFO - Check BGP staus between peers
2026-05-25 04:21:24,244 - INFO - setting MD5 config on host eb21edc3-c38f-4c15-a003-6da0a513bd0f
2026-05-25 04:21:24,244 - INFO - configure MD5 on host eb21edc3-c38f-4c15-a003-6da0a513bd0f
2026-05-25 04:21:24,331 - INFO - setting MD5 config on host e36923a5-4ff8-49bd-be07-04c3acd3e848
2026-05-25 04:21:24,332 - INFO - configure MD5 on host e36923a5-4ff8-49bd-be07-04c3acd3e848
2026-05-25 04:21:24,424 - INFO - setting MD5 config on host 1ae6a29c-45e6-48cc-9dc8-c69c570ae4a5
2026-05-25 04:21:24,424 - INFO - configure MD5 on host 1ae6a29c-45e6-48cc-9dc8-c69c570ae4a5
2026-05-25 04:21:24,512 - INFO - check if BGP peering is up after md5 config
2026-05-25 04:21:24,512 - INFO - Check BGP staus between peers
2026-05-25 04:21:24,525 - DEBUG - Check the BGP connection on 10.0.0.73
2026-05-25 04:21:24,525 - DEBUG - Check the BGP connection on 10.0.0.73
2026-05-25 04:21:34,526 - INFO - Check BGP staus between peers
2026-05-25 04:21:34,541 - INFO - remove md5 configured on host eb21edc3-c38f-4c15-a003-6da0a513bd0f
2026-05-25 04:21:34,541 - INFO - config per peer with auth_data None
2026-05-25 04:21:35,168 - INFO - configure MD5 on host eb21edc3-c38f-4c15-a003-6da0a513bd0f
2026-05-25 04:21:35,272 - INFO - remove md5 configured on host e36923a5-4ff8-49bd-be07-04c3acd3e848
2026-05-25 04:21:35,272 - INFO - config per peer with auth_data None
2026-05-25 04:21:35,980 - INFO - configure MD5 on host e36923a5-4ff8-49bd-be07-04c3acd3e848
2026-05-25 04:21:36,085 - INFO - remove md5 configured on host 1ae6a29c-45e6-48cc-9dc8-c69c570ae4a5
2026-05-25 04:21:36,085 - INFO - config per peer with auth_data None
2026-05-25 04:21:36,820 - INFO - configure MD5 on host 1ae6a29c-45e6-48cc-9dc8-c69c570ae4a5
2026-05-25 04:21:36,912 - INFO - Skip interface_detach for VM vn2_vm_0 in state BUILD
2026-05-25 04:21:36,912 - INFO - Deleting VM vn2_vm_0
2026-05-25 04:21:36,913 - ERROR - VM vn2_vm_0 has failed to come up
2026-05-25 04:21:36,913 - ERROR - Nova failed to get host of the VM
2026-05-25 04:21:36,913 - ERROR - VM vn2_vm_0 has failed to come up
2026-05-25 04:21:36,913 - ERROR - Nova failed to get host of the VM
2026-05-25 04:21:37,042 - INFO - Skip interface_detach for VM vn1_vm_0 in state BUILD
2026-05-25 04:21:37,042 - INFO - Deleting VM vn1_vm_0
2026-05-25 04:21:37,042 - ERROR - VM vn1_vm_0 has failed to come up
2026-05-25 04:21:37,043 - ERROR - Nova failed to get host of the VM
2026-05-25 04:21:37,043 - ERROR - VM vn1_vm_0 has failed to come up
2026-05-25 04:21:37,043 - ERROR - Nova failed to get host of the VM
2026-05-25 04:21:37,140 - INFO - Dettaching policy sec_grp_policy_ctest-random-49553682 from vn vn2
2026-05-25 04:21:37,329 - INFO - Dettaching policy sec_grp_policy_ctest-random-49553682 from vn vn1
2026-05-25 04:21:37,604 - INFO - Deleted policy sec_grp_policy_ctest-random-49553682
2026-05-25 04:21:37,604 - INFO - Deleting VN vn2
2026-05-25 04:21:37,673 - DEBUG - VN 1b07a583-0931-476e-9e62-79f0e45d519d still in use: Unable to complete operation on network 1b07a583-0931-476e-9e62-79f0e45d519d. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-c79d4f13-c475-40ff-9976-ea1cfb244a90']
2026-05-25 04:21:37,673 - WARNING - Deleting VN vn2 failed..Will retry
2026-05-25 04:21:39,864 - DEBUG - Response for deleting network ()
2026-05-25 04:21:39,864 - INFO - Deleting VN vn1
2026-05-25 04:21:40,062 - DEBUG - Response for deleting network ()
2026-05-25 04:21:40,062 - INFO - Dettaching policy allow_all from vn test_vnv6dn
2026-05-25 04:21:40,262 - INFO - Dettaching policy allow_all from vn test_vnv6sr
2026-05-25 04:21:40,570 - INFO - Deleted policy allow_all
2026-05-25 04:21:40,570 - INFO - Deleting VM dest_vm
2026-05-25 04:21:40,670 - INFO - Deleting VM source_vm
2026-05-25 04:21:40,763 - INFO - Deleting VN test_vnv6dn
2026-05-25 04:21:40,823 - DEBUG - VN dae50aba-47e4-4606-a5a7-fced419eac3c still in use: Unable to complete operation on network dae50aba-47e4-4606-a5a7-fced419eac3c. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-239e9b95-8b31-4057-a44b-a9c2c1fabd5b']
2026-05-25 04:21:40,823 - WARNING - Deleting VN test_vnv6dn failed..Will retry
2026-05-25 04:21:43,067 - DEBUG - Response for deleting network ()
2026-05-25 04:21:43,067 - INFO - Deleting VN test_vnv6sr
2026-05-25 04:21:43,338 - DEBUG - Response for deleting network ()
2026-05-25 04:21:44,641 - DEBUG - Skipping xmpp flap check
2026-05-25 04:21:44,642 - INFO - END TEST : test_create_md5_on_control : PASSED[0:00:24]
2026-05-25 04:21:44,642 - INFO - --------------------------------------------------------------------------------
2026-05-25 04:21:45,485 - INFO - Deleted project: ctest-TestMd5testsOnControl-35531579, ID : 11251c9e-b88d-4840-a6df-94ab941b7f64