2026-04-02 04:16:45,879 - INFO - Domain Default found not creating
2026-04-02 04:16:46,048 - INFO - Project ctest-TestMd5testsOnControl-58034221 not found, creating it
2026-04-02 04:16:46,669 - INFO - Created Project:ctest-TestMd5testsOnControl-58034221, ID : c07371f2-a6ca-49d4-b157-e2ac4a74c16d
2026-04-02 04:16:49,208 - DEBUG - Response for create_network : {'network': {'id': '6f9fb18a-fec4-4254-b98d-303222439090', 'name': 'test_vnv6sr', 'tenant_id': 'c07371f2a6ca49d4b157e2ac4a74c16d', 'project_id': 'c07371f2a6ca49d4b157e2ac4a74c16d', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-58034221', 'test_vnv6sr'], 'port_security_enabled': True, 'description': ''}}
2026-04-02 04:16:49,445 - DEBUG - Response for create_subnet : {'subnet': {'id': '450997f2-5e98-4bf4-97a4-a5ce4842acf9', 'name': '', 'tenant_id': 'c07371f2a6ca49d4b157e2ac4a74c16d', 'network_id': '6f9fb18a-fec4-4254-b98d-303222439090', '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': 'c07371f2a6ca49d4b157e2ac4a74c16d'}}
2026-04-02 04:16:49,679 - DEBUG - Response for create_subnet : {'subnet': {'id': '6fa3836b-5824-475e-b8d8-eb2486798e27', 'name': '', 'tenant_id': 'c07371f2a6ca49d4b157e2ac4a74c16d', 'network_id': '6f9fb18a-fec4-4254-b98d-303222439090', 'ip_version': 4, 'cidr': '77.196.211.128/26', 'allocation_pools': [{'start': '77.196.211.130', 'end': '77.196.211.190'}], 'gateway_ip': '77.196.211.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '77.196.211.130', 'tags': [], 'project_id': 'c07371f2a6ca49d4b157e2ac4a74c16d'}}
2026-04-02 04:16:49,728 - INFO - Created VN test_vnv6sr
2026-04-02 04:16:49,749 - DEBUG - VN test_vnv6sr UUID is 6f9fb18a-fec4-4254-b98d-303222439090
2026-04-02 04:16:50,222 - DEBUG - Response for create_network : {'network': {'id': 'c380bc03-d7a4-4b0b-90ea-aa2d28b001f0', 'name': 'test_vnv6dn', 'tenant_id': 'c07371f2a6ca49d4b157e2ac4a74c16d', 'project_id': 'c07371f2a6ca49d4b157e2ac4a74c16d', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-58034221', 'test_vnv6dn'], 'port_security_enabled': True, 'description': ''}}
2026-04-02 04:16:50,445 - DEBUG - Response for create_subnet : {'subnet': {'id': 'c81df8b2-32c8-491d-a81a-be2fdf302303', 'name': '', 'tenant_id': 'c07371f2a6ca49d4b157e2ac4a74c16d', 'network_id': 'c380bc03-d7a4-4b0b-90ea-aa2d28b001f0', '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': 'c07371f2a6ca49d4b157e2ac4a74c16d'}}
2026-04-02 04:16:50,650 - DEBUG - Response for create_subnet : {'subnet': {'id': '010d065a-4150-46c4-a9c4-7b50986f9a59', 'name': '', 'tenant_id': 'c07371f2a6ca49d4b157e2ac4a74c16d', 'network_id': 'c380bc03-d7a4-4b0b-90ea-aa2d28b001f0', 'ip_version': 4, 'cidr': '91.125.24.64/26', 'allocation_pools': [{'start': '91.125.24.66', 'end': '91.125.24.126'}], 'gateway_ip': '91.125.24.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '91.125.24.66', 'tags': [], 'project_id': 'c07371f2a6ca49d4b157e2ac4a74c16d'}}
2026-04-02 04:16:50,687 - INFO - Created VN test_vnv6dn
2026-04-02 04:16:50,704 - DEBUG - VN test_vnv6dn UUID is c380bc03-d7a4-4b0b-90ea-aa2d28b001f0
2026-04-02 04:16:51,037 - DEBUG - Services list from nova: [, , , ]
2026-04-02 04:16:52,283 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5575-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5575-2)
2026-04-02 04:16:53,767 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5575-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5575-1)
2026-04-02 04:16:53,767 - INFO - Waiting for VM source_vm to be up..
2026-04-02 04:16:53,866 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2026-04-02 04:16:58,952 - DEBUG - VM is in ACTIVE state now
2026-04-02 04:16:58,952 - INFO - VM name : source_vm
2026-04-02 04:16:59,039 - DEBUG - VM source_vm ID is f22dd24e-5624-4e5a-b37f-82808e96b760
2026-04-02 04:16:59,063 - DEBUG - VM source_vm launched on Node an-jenkins-deploy-platform-ansible-os-5575-2
2026-04-02 04:16:59,153 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine/f22dd24e-5624-4e5a-b37f-82808e96b760
2026-04-02 04:16:59,456 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine/f22dd24e-5624-4e5a-b37f-82808e96b760
2026-04-02 04:16:59,491 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine-interface/a2c98552-215e-44a3-96fe-10aae2e6fd84
2026-04-02 04:17:02,673 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1007ms')
2026-04-02 04:17:02,673 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM source_vm failed!
2026-04-02 04:17:02,690 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-58034221:test_vnv6sr is 2001::101:1 and allocation pool is NOT set
2026-04-02 04:17:02,691 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-58034221:test_vnv6sr is 77.196.211.129 and allocation pool is NOT set
2026-04-02 04:17:04,749 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n64 bytes from 169.254.0.4: icmp_seq=1 ttl=63 time=7.02 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.396 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 0.396/3.706/7.017/3.310 ms')
2026-04-02 04:17:04,749 - INFO - Ping to Metadata IP 169.254.0.4 of VM source_vm passed
2026-04-02 04:17:04,819 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-02 04:17:04,819 - DEBUG - Waiting to SSH to VM source_vm, IP 77.196.211.131, Port 22
2026-04-02 04:17:04,980 - DEBUG - VM source_vm is ready for SSH connections
2026-04-02 04:17:04,980 - INFO - Waiting for VM dest_vm to be up..
2026-04-02 04:17:05,067 - DEBUG - VM is in ACTIVE state now
2026-04-02 04:17:05,067 - INFO - VM name : dest_vm
2026-04-02 04:17:05,146 - DEBUG - VM dest_vm ID is 97431e9c-8789-4f0d-8ced-02f85b357a5f
2026-04-02 04:17:05,146 - DEBUG - VM dest_vm launched on Node an-jenkins-deploy-platform-ansible-os-5575-1
2026-04-02 04:17:05,232 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine/97431e9c-8789-4f0d-8ced-02f85b357a5f
2026-04-02 04:17:05,245 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine-interface/2b1296a3-e641-4a21-826c-b025c137bd8c
2026-04-02 04:17:06,419 - 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=9.99 ms\r\n64 bytes from 169.254.0.5: icmp_seq=2 ttl=63 time=3.44 ms\r\n\r\n--- 169.254.0.5 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1000ms\r\nrtt min/avg/max/mdev = 3.441/6.714/9.987/3.273 ms')
2026-04-02 04:17:06,419 - INFO - Ping to Metadata IP 169.254.0.5 of VM dest_vm passed
2026-04-02 04:17:06,498 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-02 04:17:06,498 - DEBUG - Waiting to SSH to VM dest_vm, IP 91.125.24.67, Port 22
2026-04-02 04:17:06,563 - 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-04-02 04:17:06,643 - DEBUG - VM dest_vm is NOT ready for SSH connections, VM status: ACTIVE
2026-04-02 04:17:11,644 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-02 04:17:11,644 - DEBUG - Waiting to SSH to VM dest_vm, IP 91.125.24.67, Port 22
2026-04-02 04:17:11,813 - DEBUG - VM dest_vm is ready for SSH connections
2026-04-02 04:17:11,820 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-58034221: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-58034221: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-04-02 04:17:12,193 - INFO - Attaching policy allow_all to vn test_vnv6sr
2026-04-02 04:17:12,573 - INFO - Attaching policy allow_all to vn test_vnv6dn
2026-04-02 04:17:12,877 - INFO - Configure the policy with allow any
2026-04-02 04:17:13,207 - DEBUG - Response for create_network : {'network': {'id': 'f3830d67-b8c0-4353-87c1-5fecdc35609a', 'name': 'vn1', 'tenant_id': 'c07371f2a6ca49d4b157e2ac4a74c16d', 'project_id': 'c07371f2a6ca49d4b157e2ac4a74c16d', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-58034221', 'vn1'], 'port_security_enabled': True, 'description': ''}}
2026-04-02 04:17:13,494 - DEBUG - Response for create_subnet : {'subnet': {'id': 'a0b8889c-5036-438b-a881-daa4e29e0899', 'name': '', 'tenant_id': 'c07371f2a6ca49d4b157e2ac4a74c16d', 'network_id': 'f3830d67-b8c0-4353-87c1-5fecdc35609a', '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': 'c07371f2a6ca49d4b157e2ac4a74c16d'}}
2026-04-02 04:17:13,517 - INFO - Created VN vn1
2026-04-02 04:17:13,536 - DEBUG - VN vn1 UUID is f3830d67-b8c0-4353-87c1-5fecdc35609a
2026-04-02 04:17:13,921 - DEBUG - Response for create_network : {'network': {'id': '9b3d4a8e-33d1-4b91-a148-269f763fed17', 'name': 'vn2', 'tenant_id': 'c07371f2a6ca49d4b157e2ac4a74c16d', 'project_id': 'c07371f2a6ca49d4b157e2ac4a74c16d', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-58034221', 'vn2'], 'port_security_enabled': True, 'description': ''}}
2026-04-02 04:17:14,175 - DEBUG - Response for create_subnet : {'subnet': {'id': 'acfc8460-fa1a-426c-a52b-18e16330d740', 'name': '', 'tenant_id': 'c07371f2a6ca49d4b157e2ac4a74c16d', 'network_id': '9b3d4a8e-33d1-4b91-a148-269f763fed17', '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': 'c07371f2a6ca49d4b157e2ac4a74c16d'}}
2026-04-02 04:17:14,202 - INFO - Created VN vn2
2026-04-02 04:17:14,216 - DEBUG - VN vn2 UUID is 9b3d4a8e-33d1-4b91-a148-269f763fed17
2026-04-02 04:17:14,309 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-58034221: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-58034221: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-04-02 04:17:14,681 - INFO - Attaching policy sec_grp_policy_ctest-random-94842808 to vn vn1
2026-04-02 04:17:15,055 - INFO - Attaching policy sec_grp_policy_ctest-random-94842808 to vn vn2
2026-04-02 04:17:16,727 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5575-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5575-2)
2026-04-02 04:17:18,167 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5575-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5575-1)
2026-04-02 04:17:18,231 - INFO - ================================================================================
2026-04-02 04:17:18,231 - INFO - STARTING TEST : test_check_per_peer_on_control
2026-04-02 04:17:18,231 - INFO - TEST DESCRIPTION :
Description: Verify per peer md5 and specific protocol on all ports and policy with allow all between VN's
2026-04-02 04:17:19,573 - DEBUG - Skipping xmpp flap check
2026-04-02 04:17:19,573 - INFO - Initial checks done. Running the testcase now
2026-04-02 04:17:19,573 - INFO -
2026-04-02 04:17:19,573 - INFO - config per peer with auth_data None
2026-04-02 04:17:20,265 - INFO - configure MD5 on host c360d22c-e388-437a-addd-fc0009693981
2026-04-02 04:17:20,385 - INFO - config per peer with auth_data None
2026-04-02 04:17:21,193 - INFO - configure MD5 on host 8f98e8d4-f075-4ce4-b8d9-c09d3c0c95d5
2026-04-02 04:17:21,293 - INFO - config per peer with auth_data None
2026-04-02 04:17:22,041 - INFO - configure MD5 on host c3eb084d-bc2c-4055-af41-2a181ffbd27b
2026-04-02 04:17:22,149 - INFO - check BGP peering is up before setting md5 config
2026-04-02 04:17:22,149 - INFO - Check BGP staus between peers
2026-04-02 04:17:22,160 - INFO - config per peer with auth_data {'key_items': [{'key': 'juniper', 'key_id': 0}], 'key_type': 'md5'}
2026-04-02 04:17:22,869 - INFO - Check BGP staus between peers
2026-04-02 04:17:22,879 - DEBUG - Check the BGP connection on 10.0.0.73
2026-04-02 04:17:22,879 - DEBUG - Check the BGP connection on 10.0.0.73
2026-04-02 04:17:32,879 - INFO - Check BGP staus between peers
2026-04-02 04:17:32,891 - INFO - remove md5 configured on host c360d22c-e388-437a-addd-fc0009693981
2026-04-02 04:17:32,891 - INFO - config per peer with auth_data None
2026-04-02 04:17:33,493 - INFO - configure MD5 on host c360d22c-e388-437a-addd-fc0009693981
2026-04-02 04:17:33,601 - INFO - remove md5 configured on host 8f98e8d4-f075-4ce4-b8d9-c09d3c0c95d5
2026-04-02 04:17:33,601 - INFO - config per peer with auth_data None
2026-04-02 04:17:34,269 - INFO - configure MD5 on host 8f98e8d4-f075-4ce4-b8d9-c09d3c0c95d5
2026-04-02 04:17:34,385 - INFO - remove md5 configured on host c3eb084d-bc2c-4055-af41-2a181ffbd27b
2026-04-02 04:17:34,386 - INFO - config per peer with auth_data None
2026-04-02 04:17:35,029 - INFO - configure MD5 on host c3eb084d-bc2c-4055-af41-2a181ffbd27b
2026-04-02 04:17:35,133 - INFO - Skip interface_detach for VM vn2_vm_0 in state BUILD
2026-04-02 04:17:35,133 - INFO - Deleting VM vn2_vm_0
2026-04-02 04:17:35,134 - ERROR - VM vn2_vm_0 has failed to come up
2026-04-02 04:17:35,134 - ERROR - Nova failed to get host of the VM
2026-04-02 04:17:35,134 - ERROR - VM vn2_vm_0 has failed to come up
2026-04-02 04:17:35,134 - ERROR - Nova failed to get host of the VM
2026-04-02 04:17:35,198 - INFO - Skip interface_detach for VM vn1_vm_0 in state BUILD
2026-04-02 04:17:35,198 - INFO - Deleting VM vn1_vm_0
2026-04-02 04:17:35,198 - ERROR - VM vn1_vm_0 has failed to come up
2026-04-02 04:17:35,198 - ERROR - Nova failed to get host of the VM
2026-04-02 04:17:35,198 - ERROR - VM vn1_vm_0 has failed to come up
2026-04-02 04:17:35,198 - ERROR - Nova failed to get host of the VM
2026-04-02 04:17:35,265 - INFO - Dettaching policy sec_grp_policy_ctest-random-94842808 from vn vn2
2026-04-02 04:17:35,449 - INFO - Dettaching policy sec_grp_policy_ctest-random-94842808 from vn vn1
2026-04-02 04:17:35,717 - INFO - Deleted policy sec_grp_policy_ctest-random-94842808
2026-04-02 04:17:35,717 - INFO - Deleting VN vn2
2026-04-02 04:17:35,768 - DEBUG - VN 9b3d4a8e-33d1-4b91-a148-269f763fed17 still in use: Unable to complete operation on network 9b3d4a8e-33d1-4b91-a148-269f763fed17. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-8bec04fd-7d94-4361-aa01-a0b0346b694f']
2026-04-02 04:17:35,768 - WARNING - Deleting VN vn2 failed..Will retry
2026-04-02 04:17:37,947 - DEBUG - Response for deleting network ()
2026-04-02 04:17:37,947 - INFO - Deleting VN vn1
2026-04-02 04:17:38,179 - DEBUG - Response for deleting network ()
2026-04-02 04:17:38,179 - INFO - Dettaching policy allow_all from vn test_vnv6dn
2026-04-02 04:17:38,385 - INFO - Dettaching policy allow_all from vn test_vnv6sr
2026-04-02 04:17:38,656 - INFO - Deleted policy allow_all
2026-04-02 04:17:38,656 - INFO - Deleting VM dest_vm
2026-04-02 04:17:38,726 - INFO - Deleting VM source_vm
2026-04-02 04:17:38,790 - INFO - Deleting VN test_vnv6dn
2026-04-02 04:17:38,834 - DEBUG - VN c380bc03-d7a4-4b0b-90ea-aa2d28b001f0 still in use: Unable to complete operation on network c380bc03-d7a4-4b0b-90ea-aa2d28b001f0. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-8e6a240d-e923-4115-bea8-69a9350c4d12']
2026-04-02 04:17:38,835 - WARNING - Deleting VN test_vnv6dn failed..Will retry
2026-04-02 04:17:41,040 - DEBUG - Response for deleting network ()
2026-04-02 04:17:41,041 - INFO - Deleting VN test_vnv6sr
2026-04-02 04:17:41,220 - DEBUG - Response for deleting network ()
2026-04-02 04:17:42,469 - DEBUG - Skipping xmpp flap check
2026-04-02 04:17:42,470 - INFO - END TEST : test_check_per_peer_on_control : PASSED[0:00:24]
2026-04-02 04:17:42,470 - INFO - --------------------------------------------------------------------------------
2026-04-02 04:17:42,776 - DEBUG - Response for create_network : {'network': {'id': 'ecf2abea-cfd6-4f2f-947f-58004dc2d100', 'name': 'test_vnv6sr', 'tenant_id': 'c07371f2a6ca49d4b157e2ac4a74c16d', 'project_id': 'c07371f2a6ca49d4b157e2ac4a74c16d', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-58034221', 'test_vnv6sr'], 'port_security_enabled': True, 'description': ''}}
2026-04-02 04:17:43,062 - DEBUG - Response for create_subnet : {'subnet': {'id': '237f3fe5-4670-4955-81dd-f8f8cc81f5b2', 'name': '', 'tenant_id': 'c07371f2a6ca49d4b157e2ac4a74c16d', 'network_id': 'ecf2abea-cfd6-4f2f-947f-58004dc2d100', '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': 'c07371f2a6ca49d4b157e2ac4a74c16d'}}
2026-04-02 04:17:43,296 - DEBUG - Response for create_subnet : {'subnet': {'id': 'bb97220a-e6db-4efa-824a-af29eec89c95', 'name': '', 'tenant_id': 'c07371f2a6ca49d4b157e2ac4a74c16d', 'network_id': 'ecf2abea-cfd6-4f2f-947f-58004dc2d100', 'ip_version': 4, 'cidr': '148.100.127.0/26', 'allocation_pools': [{'start': '148.100.127.2', 'end': '148.100.127.62'}], 'gateway_ip': '148.100.127.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '148.100.127.2', 'tags': [], 'project_id': 'c07371f2a6ca49d4b157e2ac4a74c16d'}}
2026-04-02 04:17:43,325 - INFO - Created VN test_vnv6sr
2026-04-02 04:17:43,338 - DEBUG - VN test_vnv6sr UUID is ecf2abea-cfd6-4f2f-947f-58004dc2d100
2026-04-02 04:17:43,763 - DEBUG - Response for create_network : {'network': {'id': '548ebdaa-0a0d-4227-892e-90e6d8c3f035', 'name': 'test_vnv6dn', 'tenant_id': 'c07371f2a6ca49d4b157e2ac4a74c16d', 'project_id': 'c07371f2a6ca49d4b157e2ac4a74c16d', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-58034221', 'test_vnv6dn'], 'port_security_enabled': True, 'description': ''}}
2026-04-02 04:17:44,002 - DEBUG - Response for create_subnet : {'subnet': {'id': '6c4af40b-cc42-4d20-841c-59309d4dc4a1', 'name': '', 'tenant_id': 'c07371f2a6ca49d4b157e2ac4a74c16d', 'network_id': '548ebdaa-0a0d-4227-892e-90e6d8c3f035', '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': 'c07371f2a6ca49d4b157e2ac4a74c16d'}}
2026-04-02 04:17:44,207 - DEBUG - Response for create_subnet : {'subnet': {'id': '05c37db0-5c9e-4fb4-9a4c-fef41ef59210', 'name': '', 'tenant_id': 'c07371f2a6ca49d4b157e2ac4a74c16d', 'network_id': '548ebdaa-0a0d-4227-892e-90e6d8c3f035', 'ip_version': 4, 'cidr': '88.107.181.64/26', 'allocation_pools': [{'start': '88.107.181.66', 'end': '88.107.181.126'}], 'gateway_ip': '88.107.181.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '88.107.181.66', 'tags': [], 'project_id': 'c07371f2a6ca49d4b157e2ac4a74c16d'}}
2026-04-02 04:17:44,240 - INFO - Created VN test_vnv6dn
2026-04-02 04:17:44,254 - DEBUG - VN test_vnv6dn UUID is 548ebdaa-0a0d-4227-892e-90e6d8c3f035
2026-04-02 04:17:45,572 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5575-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5575-2)
2026-04-02 04:17:47,090 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5575-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5575-1)
2026-04-02 04:17:47,091 - INFO - Waiting for VM source_vm to be up..
2026-04-02 04:17:47,208 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2026-04-02 04:17:52,308 - DEBUG - VM is in ACTIVE state now
2026-04-02 04:17:52,309 - INFO - VM name : source_vm
2026-04-02 04:17:52,389 - DEBUG - VM source_vm ID is 6445c6e4-cfa5-4b63-ab78-a8c3aa10409e
2026-04-02 04:17:52,389 - DEBUG - VM source_vm launched on Node an-jenkins-deploy-platform-ansible-os-5575-2
2026-04-02 04:17:52,467 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine/6445c6e4-cfa5-4b63-ab78-a8c3aa10409e
2026-04-02 04:17:52,476 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine-interface/cab7741c-2d83-4320-8093-143231644236
2026-04-02 04:17:55,659 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1031ms')
2026-04-02 04:17:55,660 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM source_vm failed!
2026-04-02 04:17:55,678 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-58034221:test_vnv6sr is 2001::101:1 and allocation pool is NOT set
2026-04-02 04:17:55,678 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-58034221:test_vnv6sr is 148.100.127.1 and allocation pool is NOT set
2026-04-02 04:17:57,749 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n64 bytes from 169.254.0.4: icmp_seq=1 ttl=63 time=8.26 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.371 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 0.371/4.315/8.260/3.944 ms')
2026-04-02 04:17:57,749 - INFO - Ping to Metadata IP 169.254.0.4 of VM source_vm passed
2026-04-02 04:17:57,819 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-02 04:17:57,819 - DEBUG - Waiting to SSH to VM source_vm, IP 148.100.127.3, Port 22
2026-04-02 04:17:57,991 - DEBUG - VM source_vm is ready for SSH connections
2026-04-02 04:17:57,991 - INFO - Waiting for VM dest_vm to be up..
2026-04-02 04:17:58,082 - DEBUG - VM is in ACTIVE state now
2026-04-02 04:17:58,082 - INFO - VM name : dest_vm
2026-04-02 04:17:58,168 - DEBUG - VM dest_vm ID is 2b8086d2-88e7-44a4-995a-2ea78eefba5c
2026-04-02 04:17:58,168 - DEBUG - VM dest_vm launched on Node an-jenkins-deploy-platform-ansible-os-5575-1
2026-04-02 04:17:58,256 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine/2b8086d2-88e7-44a4-995a-2ea78eefba5c
2026-04-02 04:17:58,268 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine-interface/7e4fa5ac-88c1-4ee1-aae0-0c03a15e83dc
2026-04-02 04:18:01,438 - 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=2 ttl=63 time=15.5 ms\r\n\r\n--- 169.254.0.5 ping statistics ---\r\n2 packets transmitted, 1 received, 50% packet loss, time 1004ms\r\nrtt min/avg/max/mdev = 15.545/15.545/15.545/0.000 ms')
2026-04-02 04:18:01,438 - INFO - Ping to Metadata IP 169.254.0.5 of VM dest_vm passed
2026-04-02 04:18:01,516 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-02 04:18:01,516 - DEBUG - Waiting to SSH to VM dest_vm, IP 88.107.181.67, Port 22
2026-04-02 04:18:01,584 - 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-04-02 04:18:01,670 - DEBUG - VM dest_vm is NOT ready for SSH connections, VM status: ACTIVE
2026-04-02 04:18:06,671 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-02 04:18:06,671 - DEBUG - Waiting to SSH to VM dest_vm, IP 88.107.181.67, Port 22
2026-04-02 04:18:06,839 - DEBUG - VM dest_vm is ready for SSH connections
2026-04-02 04:18:06,846 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-58034221: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-58034221: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-04-02 04:18:07,202 - INFO - Attaching policy allow_all to vn test_vnv6sr
2026-04-02 04:18:07,596 - INFO - Attaching policy allow_all to vn test_vnv6dn
2026-04-02 04:18:07,963 - INFO - Configure the policy with allow any
2026-04-02 04:18:08,296 - DEBUG - Response for create_network : {'network': {'id': '7e92c019-ddc5-4660-ba24-735569d80bec', 'name': 'vn1', 'tenant_id': 'c07371f2a6ca49d4b157e2ac4a74c16d', 'project_id': 'c07371f2a6ca49d4b157e2ac4a74c16d', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-58034221', 'vn1'], 'port_security_enabled': True, 'description': ''}}
2026-04-02 04:18:08,525 - DEBUG - Response for create_subnet : {'subnet': {'id': '50acc66e-ecb2-4ba5-aba9-80e611111b66', 'name': '', 'tenant_id': 'c07371f2a6ca49d4b157e2ac4a74c16d', 'network_id': '7e92c019-ddc5-4660-ba24-735569d80bec', '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': 'c07371f2a6ca49d4b157e2ac4a74c16d'}}
2026-04-02 04:18:08,552 - INFO - Created VN vn1
2026-04-02 04:18:08,566 - DEBUG - VN vn1 UUID is 7e92c019-ddc5-4660-ba24-735569d80bec
2026-04-02 04:18:08,941 - DEBUG - Response for create_network : {'network': {'id': '8a4eab08-1845-4847-b752-19e9081c540c', 'name': 'vn2', 'tenant_id': 'c07371f2a6ca49d4b157e2ac4a74c16d', 'project_id': 'c07371f2a6ca49d4b157e2ac4a74c16d', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-58034221', 'vn2'], 'port_security_enabled': True, 'description': ''}}
2026-04-02 04:18:09,193 - DEBUG - Response for create_subnet : {'subnet': {'id': '108582a6-019d-4f66-a286-0ea10024b912', 'name': '', 'tenant_id': 'c07371f2a6ca49d4b157e2ac4a74c16d', 'network_id': '8a4eab08-1845-4847-b752-19e9081c540c', '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': 'c07371f2a6ca49d4b157e2ac4a74c16d'}}
2026-04-02 04:18:09,222 - INFO - Created VN vn2
2026-04-02 04:18:09,236 - DEBUG - VN vn2 UUID is 8a4eab08-1845-4847-b752-19e9081c540c
2026-04-02 04:18:09,326 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-58034221: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-58034221: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-04-02 04:18:09,670 - INFO - Attaching policy sec_grp_policy_ctest-random-13722772 to vn vn1
2026-04-02 04:18:10,045 - INFO - Attaching policy sec_grp_policy_ctest-random-13722772 to vn vn2
2026-04-02 04:18:11,658 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5575-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5575-2)
2026-04-02 04:18:13,152 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5575-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5575-1)
2026-04-02 04:18:13,215 - INFO - ================================================================================
2026-04-02 04:18:13,215 - INFO - STARTING TEST : test_create_md5_on_control
2026-04-02 04:18:13,216 - INFO - TEST DESCRIPTION :
Description: Verify md5 with allow specific protocol on all ports and policy with allow all between VN's
2026-04-02 04:18:14,514 - DEBUG - Skipping xmpp flap check
2026-04-02 04:18:14,514 - INFO - Initial checks done. Running the testcase now
2026-04-02 04:18:14,514 - INFO -
2026-04-02 04:18:14,514 - INFO - config md5 on each host with auth_data None
2026-04-02 04:18:14,514 - INFO - config per peer with auth_data None
2026-04-02 04:18:15,121 - INFO - configure MD5 on host c360d22c-e388-437a-addd-fc0009693981
2026-04-02 04:18:15,213 - INFO - config per peer with auth_data None
2026-04-02 04:18:15,857 - INFO - configure MD5 on host 8f98e8d4-f075-4ce4-b8d9-c09d3c0c95d5
2026-04-02 04:18:15,961 - INFO - config per peer with auth_data None
2026-04-02 04:18:16,645 - INFO - configure MD5 on host c3eb084d-bc2c-4055-af41-2a181ffbd27b
2026-04-02 04:18:16,757 - INFO - check if BGP between peers are up before setting md5
2026-04-02 04:18:16,757 - INFO - Check BGP staus between peers
2026-04-02 04:18:16,769 - INFO - setting MD5 config on host c360d22c-e388-437a-addd-fc0009693981
2026-04-02 04:18:16,769 - INFO - configure MD5 on host c360d22c-e388-437a-addd-fc0009693981
2026-04-02 04:18:16,869 - INFO - setting MD5 config on host 8f98e8d4-f075-4ce4-b8d9-c09d3c0c95d5
2026-04-02 04:18:16,869 - INFO - configure MD5 on host 8f98e8d4-f075-4ce4-b8d9-c09d3c0c95d5
2026-04-02 04:18:16,949 - INFO - setting MD5 config on host c3eb084d-bc2c-4055-af41-2a181ffbd27b
2026-04-02 04:18:16,949 - INFO - configure MD5 on host c3eb084d-bc2c-4055-af41-2a181ffbd27b
2026-04-02 04:18:17,037 - INFO - check if BGP peering is up after md5 config
2026-04-02 04:18:17,038 - INFO - Check BGP staus between peers
2026-04-02 04:18:17,050 - DEBUG - Check the BGP connection on 10.0.0.73
2026-04-02 04:18:17,051 - DEBUG - Check the BGP connection on 10.0.0.73
2026-04-02 04:18:27,051 - INFO - Check BGP staus between peers
2026-04-02 04:18:27,060 - INFO - remove md5 configured on host c360d22c-e388-437a-addd-fc0009693981
2026-04-02 04:18:27,060 - INFO - config per peer with auth_data None
2026-04-02 04:18:27,659 - INFO - configure MD5 on host c360d22c-e388-437a-addd-fc0009693981
2026-04-02 04:18:27,765 - INFO - remove md5 configured on host 8f98e8d4-f075-4ce4-b8d9-c09d3c0c95d5
2026-04-02 04:18:27,765 - INFO - config per peer with auth_data None
2026-04-02 04:18:28,403 - INFO - configure MD5 on host 8f98e8d4-f075-4ce4-b8d9-c09d3c0c95d5
2026-04-02 04:18:28,485 - INFO - remove md5 configured on host c3eb084d-bc2c-4055-af41-2a181ffbd27b
2026-04-02 04:18:28,486 - INFO - config per peer with auth_data None
2026-04-02 04:18:29,130 - INFO - configure MD5 on host c3eb084d-bc2c-4055-af41-2a181ffbd27b
2026-04-02 04:18:29,229 - INFO - Skip interface_detach for VM vn2_vm_0 in state BUILD
2026-04-02 04:18:29,229 - INFO - Deleting VM vn2_vm_0
2026-04-02 04:18:29,229 - ERROR - VM vn2_vm_0 has failed to come up
2026-04-02 04:18:29,229 - ERROR - Nova failed to get host of the VM
2026-04-02 04:18:29,230 - ERROR - VM vn2_vm_0 has failed to come up
2026-04-02 04:18:29,230 - ERROR - Nova failed to get host of the VM
2026-04-02 04:18:29,299 - INFO - Skip interface_detach for VM vn1_vm_0 in state BUILD
2026-04-02 04:18:29,299 - INFO - Deleting VM vn1_vm_0
2026-04-02 04:18:29,300 - ERROR - VM vn1_vm_0 has failed to come up
2026-04-02 04:18:29,300 - ERROR - Nova failed to get host of the VM
2026-04-02 04:18:29,300 - ERROR - VM vn1_vm_0 has failed to come up
2026-04-02 04:18:29,300 - ERROR - Nova failed to get host of the VM
2026-04-02 04:18:29,367 - INFO - Dettaching policy sec_grp_policy_ctest-random-13722772 from vn vn2
2026-04-02 04:18:29,560 - INFO - Dettaching policy sec_grp_policy_ctest-random-13722772 from vn vn1
2026-04-02 04:18:29,854 - INFO - Deleted policy sec_grp_policy_ctest-random-13722772
2026-04-02 04:18:29,854 - INFO - Deleting VN vn2
2026-04-02 04:18:29,924 - DEBUG - VN 8a4eab08-1845-4847-b752-19e9081c540c still in use: Unable to complete operation on network 8a4eab08-1845-4847-b752-19e9081c540c. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-5ad34739-9366-43ca-b3bf-7ad122de6b2b']
2026-04-02 04:18:29,924 - WARNING - Deleting VN vn2 failed..Will retry
2026-04-02 04:18:32,096 - DEBUG - Response for deleting network ()
2026-04-02 04:18:32,096 - INFO - Deleting VN vn1
2026-04-02 04:18:32,265 - DEBUG - Response for deleting network ()
2026-04-02 04:18:32,265 - INFO - Dettaching policy allow_all from vn test_vnv6dn
2026-04-02 04:18:32,484 - INFO - Dettaching policy allow_all from vn test_vnv6sr
2026-04-02 04:18:32,752 - INFO - Deleted policy allow_all
2026-04-02 04:18:32,752 - INFO - Deleting VM dest_vm
2026-04-02 04:18:32,824 - INFO - Deleting VM source_vm
2026-04-02 04:18:32,894 - INFO - Deleting VN test_vnv6dn
2026-04-02 04:18:32,939 - DEBUG - VN 548ebdaa-0a0d-4227-892e-90e6d8c3f035 still in use: Unable to complete operation on network 548ebdaa-0a0d-4227-892e-90e6d8c3f035. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-7898b1ad-18f8-476c-9c91-f9f0941b0e60']
2026-04-02 04:18:32,940 - WARNING - Deleting VN test_vnv6dn failed..Will retry
2026-04-02 04:18:35,140 - DEBUG - Response for deleting network ()
2026-04-02 04:18:35,140 - INFO - Deleting VN test_vnv6sr
2026-04-02 04:18:35,357 - DEBUG - Response for deleting network ()
2026-04-02 04:18:36,633 - DEBUG - Skipping xmpp flap check
2026-04-02 04:18:36,633 - INFO - END TEST : test_create_md5_on_control : PASSED[0:00:23]
2026-04-02 04:18:36,633 - INFO - --------------------------------------------------------------------------------
2026-04-02 04:18:37,399 - INFO - Deleted project: ctest-TestMd5testsOnControl-58034221, ID : c07371f2-a6ca-49d4-b157-e2ac4a74c16d