2025-08-07 20:55:52,862 - INFO - Domain Default found not creating
2025-08-07 20:55:53,041 - INFO - Project ctest-TestMd5testsOnControl-17438693 not found, creating it
2025-08-07 20:55:53,653 - INFO - Created Project:ctest-TestMd5testsOnControl-17438693, ID : 07b2556b-652f-4bc0-8953-bf9ba7d84b24
2025-08-07 20:55:56,879 - DEBUG - Response for create_network : {'network': {'id': '07c66986-b2f4-4328-9bf2-c088ca7b120c', 'name': 'test_vnv6sr', 'tenant_id': '07b2556b652f4bc08953bf9ba7d84b24', 'project_id': '07b2556b652f4bc08953bf9ba7d84b24', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-17438693', 'test_vnv6sr'], 'port_security_enabled': True, 'description': ''}}
2025-08-07 20:55:57,200 - DEBUG - Response for create_subnet : {'subnet': {'id': 'd3e03063-5ba5-4fd2-a4c0-e5806186f6e5', 'name': '', 'tenant_id': '07b2556b652f4bc08953bf9ba7d84b24', 'network_id': '07c66986-b2f4-4328-9bf2-c088ca7b120c', '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': '07b2556b652f4bc08953bf9ba7d84b24'}}
2025-08-07 20:55:57,505 - DEBUG - Response for create_subnet : {'subnet': {'id': '9aa5df93-bf3e-439d-be68-d301ec74c941', 'name': '', 'tenant_id': '07b2556b652f4bc08953bf9ba7d84b24', 'network_id': '07c66986-b2f4-4328-9bf2-c088ca7b120c', 'ip_version': 4, 'cidr': '216.248.32.192/26', 'allocation_pools': [{'start': '216.248.32.194', 'end': '216.248.32.254'}], 'gateway_ip': '216.248.32.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '216.248.32.194', 'tags': [], 'project_id': '07b2556b652f4bc08953bf9ba7d84b24'}}
2025-08-07 20:55:57,531 - INFO - Created VN test_vnv6sr
2025-08-07 20:55:57,549 - DEBUG - VN test_vnv6sr UUID is 07c66986-b2f4-4328-9bf2-c088ca7b120c
2025-08-07 20:55:58,013 - DEBUG - Response for create_network : {'network': {'id': '2d51f4e6-79fe-4fe6-ade1-7cef5fbb99da', 'name': 'test_vnv6dn', 'tenant_id': '07b2556b652f4bc08953bf9ba7d84b24', 'project_id': '07b2556b652f4bc08953bf9ba7d84b24', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-17438693', 'test_vnv6dn'], 'port_security_enabled': True, 'description': ''}}
2025-08-07 20:55:58,296 - DEBUG - Response for create_subnet : {'subnet': {'id': '769a1061-33d8-4b5d-a7e5-14ee18a51a2b', 'name': '', 'tenant_id': '07b2556b652f4bc08953bf9ba7d84b24', 'network_id': '2d51f4e6-79fe-4fe6-ade1-7cef5fbb99da', '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': '07b2556b652f4bc08953bf9ba7d84b24'}}
2025-08-07 20:55:58,583 - DEBUG - Response for create_subnet : {'subnet': {'id': 'e6ee1e81-4bd5-445d-a90f-a5993c54c607', 'name': '', 'tenant_id': '07b2556b652f4bc08953bf9ba7d84b24', 'network_id': '2d51f4e6-79fe-4fe6-ade1-7cef5fbb99da', 'ip_version': 4, 'cidr': '183.255.57.0/26', 'allocation_pools': [{'start': '183.255.57.2', 'end': '183.255.57.62'}], 'gateway_ip': '183.255.57.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '183.255.57.2', 'tags': [], 'project_id': '07b2556b652f4bc08953bf9ba7d84b24'}}
2025-08-07 20:55:58,610 - INFO - Created VN test_vnv6dn
2025-08-07 20:55:58,628 - DEBUG - VN test_vnv6dn UUID is 2d51f4e6-79fe-4fe6-ade1-7cef5fbb99da
2025-08-07 20:55:58,967 - DEBUG - Services list from nova: [, , , ]
2025-08-07 20:56:00,191 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3775-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3775-2)
2025-08-07 20:56:01,872 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3775-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3775-1)
2025-08-07 20:56:01,872 - INFO - Waiting for VM source_vm to be up..
2025-08-07 20:56:02,016 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2025-08-07 20:56:07,157 - DEBUG - VM is in ACTIVE state now
2025-08-07 20:56:07,158 - INFO - VM name : source_vm
2025-08-07 20:56:07,261 - DEBUG - VM source_vm ID is e51ea030-c517-48ff-bbfb-65834ee2e4ef
2025-08-07 20:56:07,287 - DEBUG - VM source_vm launched on Node an-jenkins-deploy-platform-ansible-os-3775-2
2025-08-07 20:56:07,384 - DEBUG - Requesting: http://10.0.0.241:8082/virtual-machine/e51ea030-c517-48ff-bbfb-65834ee2e4ef
2025-08-07 20:56:07,692 - DEBUG - Requesting: http://10.0.0.241:8082/virtual-machine/e51ea030-c517-48ff-bbfb-65834ee2e4ef
2025-08-07 20:56:07,731 - DEBUG - Requesting: http://10.0.0.241:8082/virtual-machine-interface/97a5e237-5d35-4ea6-917f-f05589f5952b
2025-08-07 20:56:10,982 - 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 1026ms')
2025-08-07 20:56:10,983 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM source_vm failed!
2025-08-07 20:56:11,033 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-17438693:test_vnv6sr is 2001::101:1 and allocation pool is NOT set
2025-08-07 20:56:11,033 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-17438693:test_vnv6sr is 216.248.32.193 and allocation pool is NOT set
2025-08-07 20:56:13,100 - 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.86 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.638 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.638/2.247/3.856/1.609 ms')
2025-08-07 20:56:13,101 - INFO - Ping to Metadata IP 169.254.0.3 of VM source_vm passed
2025-08-07 20:56:13,231 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-07 20:56:13,231 - DEBUG - Waiting to SSH to VM source_vm, IP 216.248.32.195, Port 22
2025-08-07 20:56:13,402 - DEBUG - VM source_vm is ready for SSH connections
2025-08-07 20:56:13,402 - INFO - Waiting for VM dest_vm to be up..
2025-08-07 20:56:13,510 - DEBUG - VM is in ACTIVE state now
2025-08-07 20:56:13,511 - INFO - VM name : dest_vm
2025-08-07 20:56:13,613 - DEBUG - VM dest_vm ID is bf6b7c72-f62f-4bf5-a3aa-0df3103c2940
2025-08-07 20:56:13,613 - DEBUG - VM dest_vm launched on Node an-jenkins-deploy-platform-ansible-os-3775-1
2025-08-07 20:56:13,717 - DEBUG - Requesting: http://10.0.0.241:8082/virtual-machine/bf6b7c72-f62f-4bf5-a3aa-0df3103c2940
2025-08-07 20:56:13,732 - DEBUG - Requesting: http://10.0.0.241:8082/virtual-machine-interface/2528384d-a436-45ff-9aba-916d9b6aa287
2025-08-07 20:56:15,040 - 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.08 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=2.18 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 = 2.178/5.628/9.079/3.450 ms')
2025-08-07 20:56:15,040 - INFO - Ping to Metadata IP 169.254.0.3 of VM dest_vm passed
2025-08-07 20:56:15,163 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-07 20:56:15,163 - DEBUG - Waiting to SSH to VM dest_vm, IP 183.255.57.3, Port 22
2025-08-07 20:56:15,378 - DEBUG - VM dest_vm is ready for SSH connections
2025-08-07 20:56:15,386 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-17438693: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-17438693:test_vnv6dn, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = 0, end_port = -1], action_list = simple_action = pass, gateway_name = None, apply_service = [], service_properties = None, mirror_to = None, assign_routing_instance = None, log = False, alert = False, qos_action = None, host_based_service = False, ethertype = None, created = None, last_modified = None, description = None]
2025-08-07 20:56:15,772 - INFO - Attaching policy allow_all to vn test_vnv6sr
2025-08-07 20:56:16,201 - INFO - Attaching policy allow_all to vn test_vnv6dn
2025-08-07 20:56:16,529 - INFO - Configure the policy with allow any
2025-08-07 20:56:16,953 - DEBUG - Response for create_network : {'network': {'id': '26a5d624-fcba-43da-a1fa-0c061aa188e2', 'name': 'vn1', 'tenant_id': '07b2556b652f4bc08953bf9ba7d84b24', 'project_id': '07b2556b652f4bc08953bf9ba7d84b24', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-17438693', 'vn1'], 'port_security_enabled': True, 'description': ''}}
2025-08-07 20:56:17,260 - DEBUG - Response for create_subnet : {'subnet': {'id': 'ab7bc521-f54d-42e3-b6e0-a88d30b2d4b5', 'name': '', 'tenant_id': '07b2556b652f4bc08953bf9ba7d84b24', 'network_id': '26a5d624-fcba-43da-a1fa-0c061aa188e2', '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': '07b2556b652f4bc08953bf9ba7d84b24'}}
2025-08-07 20:56:17,288 - INFO - Created VN vn1
2025-08-07 20:56:17,306 - DEBUG - VN vn1 UUID is 26a5d624-fcba-43da-a1fa-0c061aa188e2
2025-08-07 20:56:17,689 - DEBUG - Response for create_network : {'network': {'id': '41947f6c-78e3-4a74-a04c-2523012302f1', 'name': 'vn2', 'tenant_id': '07b2556b652f4bc08953bf9ba7d84b24', 'project_id': '07b2556b652f4bc08953bf9ba7d84b24', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-17438693', 'vn2'], 'port_security_enabled': True, 'description': ''}}
2025-08-07 20:56:17,931 - DEBUG - Response for create_subnet : {'subnet': {'id': 'bd9635f2-eae5-4a2c-889e-2b8a064c36ce', 'name': '', 'tenant_id': '07b2556b652f4bc08953bf9ba7d84b24', 'network_id': '41947f6c-78e3-4a74-a04c-2523012302f1', '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': '07b2556b652f4bc08953bf9ba7d84b24'}}
2025-08-07 20:56:17,958 - INFO - Created VN vn2
2025-08-07 20:56:17,970 - DEBUG - VN vn2 UUID is 41947f6c-78e3-4a74-a04c-2523012302f1
2025-08-07 20:56:18,062 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-17438693: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-17438693:vn2, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = 0, end_port = -1], action_list = simple_action = pass, gateway_name = None, apply_service = [], service_properties = None, mirror_to = None, assign_routing_instance = None, log = False, alert = False, qos_action = None, host_based_service = False, ethertype = None, created = None, last_modified = None, description = None]
2025-08-07 20:56:18,451 - INFO - Attaching policy sec_grp_policy_ctest-random-97606131 to vn vn1
2025-08-07 20:56:18,846 - INFO - Attaching policy sec_grp_policy_ctest-random-97606131 to vn vn2
2025-08-07 20:56:20,738 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3775-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3775-2)
2025-08-07 20:56:22,410 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3775-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3775-1)
2025-08-07 20:56:22,477 - INFO - ================================================================================
2025-08-07 20:56:22,477 - INFO - STARTING TEST : test_check_per_peer_on_control
2025-08-07 20:56:22,477 - INFO - TEST DESCRIPTION :
Description: Verify per peer md5 and specific protocol on all ports and policy with allow all between VN's
2025-08-07 20:56:23,829 - DEBUG - Skipping xmpp flap check
2025-08-07 20:56:23,829 - INFO - Initial checks done. Running the testcase now
2025-08-07 20:56:23,829 - INFO -
2025-08-07 20:56:23,829 - INFO - config per peer with auth_data None
2025-08-07 20:56:24,431 - INFO - configure MD5 on host c32746c9-f2c5-437b-bdcd-2c4eb3d75e97
2025-08-07 20:56:24,515 - INFO - config per peer with auth_data None
2025-08-07 20:56:25,186 - INFO - configure MD5 on host 2ee2c1a2-2045-4996-9f60-9e77439927cd
2025-08-07 20:56:25,279 - INFO - config per peer with auth_data None
2025-08-07 20:56:25,931 - INFO - configure MD5 on host fe8a5d22-718f-4d50-8a12-1fc5e062a886
2025-08-07 20:56:26,019 - INFO - check BGP peering is up before setting md5 config
2025-08-07 20:56:26,019 - INFO - Check BGP staus between peers
2025-08-07 20:56:26,033 - INFO - config per peer with auth_data {'key_items': [{'key': 'juniper', 'key_id': 0}], 'key_type': 'md5'}
2025-08-07 20:56:26,775 - INFO - Check BGP staus between peers
2025-08-07 20:56:26,793 - DEBUG - Check the BGP connection on 10.0.0.19
2025-08-07 20:56:26,793 - DEBUG - Check the BGP connection on 10.0.0.19
2025-08-07 20:56:36,794 - INFO - Check BGP staus between peers
2025-08-07 20:56:36,809 - INFO - remove md5 configured on host c32746c9-f2c5-437b-bdcd-2c4eb3d75e97
2025-08-07 20:56:36,809 - INFO - config per peer with auth_data None
2025-08-07 20:56:37,432 - INFO - configure MD5 on host c32746c9-f2c5-437b-bdcd-2c4eb3d75e97
2025-08-07 20:56:37,547 - INFO - remove md5 configured on host 2ee2c1a2-2045-4996-9f60-9e77439927cd
2025-08-07 20:56:37,547 - INFO - config per peer with auth_data None
2025-08-07 20:56:38,187 - INFO - configure MD5 on host 2ee2c1a2-2045-4996-9f60-9e77439927cd
2025-08-07 20:56:38,291 - INFO - remove md5 configured on host fe8a5d22-718f-4d50-8a12-1fc5e062a886
2025-08-07 20:56:38,291 - INFO - config per peer with auth_data None
2025-08-07 20:56:38,927 - INFO - configure MD5 on host fe8a5d22-718f-4d50-8a12-1fc5e062a886
2025-08-07 20:56:39,019 - INFO - Skip interface_detach for VM vn2_vm_0 in state BUILD
2025-08-07 20:56:39,020 - INFO - Deleting VM vn2_vm_0
2025-08-07 20:56:39,020 - ERROR - VM vn2_vm_0 has failed to come up
2025-08-07 20:56:39,020 - ERROR - Nova failed to get host of the VM
2025-08-07 20:56:39,020 - ERROR - VM vn2_vm_0 has failed to come up
2025-08-07 20:56:39,020 - ERROR - Nova failed to get host of the VM
2025-08-07 20:56:39,102 - INFO - Skip interface_detach for VM vn1_vm_0 in state BUILD
2025-08-07 20:56:39,103 - INFO - Deleting VM vn1_vm_0
2025-08-07 20:56:39,103 - ERROR - VM vn1_vm_0 has failed to come up
2025-08-07 20:56:39,103 - ERROR - Nova failed to get host of the VM
2025-08-07 20:56:39,103 - ERROR - VM vn1_vm_0 has failed to come up
2025-08-07 20:56:39,103 - ERROR - Nova failed to get host of the VM
2025-08-07 20:56:39,185 - INFO - Dettaching policy sec_grp_policy_ctest-random-97606131 from vn vn2
2025-08-07 20:56:39,391 - INFO - Dettaching policy sec_grp_policy_ctest-random-97606131 from vn vn1
2025-08-07 20:56:39,693 - INFO - Deleted policy sec_grp_policy_ctest-random-97606131
2025-08-07 20:56:39,694 - INFO - Deleting VN vn2
2025-08-07 20:56:39,761 - DEBUG - VN 41947f6c-78e3-4a74-a04c-2523012302f1 still in use: Unable to complete operation on network 41947f6c-78e3-4a74-a04c-2523012302f1. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-8d764d91-52ee-4923-b1ca-27be2daa81c9']
2025-08-07 20:56:39,762 - WARNING - Deleting VN vn2 failed..Will retry
2025-08-07 20:56:41,974 - DEBUG - Response for deleting network ()
2025-08-07 20:56:41,974 - INFO - Deleting VN vn1
2025-08-07 20:56:42,212 - DEBUG - Response for deleting network ()
2025-08-07 20:56:42,212 - INFO - Dettaching policy allow_all from vn test_vnv6dn
2025-08-07 20:56:42,477 - INFO - Dettaching policy allow_all from vn test_vnv6sr
2025-08-07 20:56:42,764 - INFO - Deleted policy allow_all
2025-08-07 20:56:42,764 - INFO - Deleting VM dest_vm
2025-08-07 20:56:42,846 - INFO - Deleting VM source_vm
2025-08-07 20:56:42,933 - INFO - Deleting VN test_vnv6dn
2025-08-07 20:56:43,006 - DEBUG - VN 2d51f4e6-79fe-4fe6-ade1-7cef5fbb99da still in use: Unable to complete operation on network 2d51f4e6-79fe-4fe6-ade1-7cef5fbb99da. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-e4abbc0f-8de0-4e4a-9a33-abd6cbce3d55']
2025-08-07 20:56:43,006 - WARNING - Deleting VN test_vnv6dn failed..Will retry
2025-08-07 20:56:45,219 - DEBUG - Response for deleting network ()
2025-08-07 20:56:45,220 - INFO - Deleting VN test_vnv6sr
2025-08-07 20:56:45,420 - DEBUG - Response for deleting network ()
2025-08-07 20:56:46,740 - DEBUG - Skipping xmpp flap check
2025-08-07 20:56:46,741 - INFO - END TEST : test_check_per_peer_on_control : PASSED[0:00:24]
2025-08-07 20:56:46,741 - INFO - --------------------------------------------------------------------------------
2025-08-07 20:56:47,063 - DEBUG - Response for create_network : {'network': {'id': 'c05b6990-dbcb-457c-b40a-3a86221f1476', 'name': 'test_vnv6sr', 'tenant_id': '07b2556b652f4bc08953bf9ba7d84b24', 'project_id': '07b2556b652f4bc08953bf9ba7d84b24', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-17438693', 'test_vnv6sr'], 'port_security_enabled': True, 'description': ''}}
2025-08-07 20:56:47,315 - DEBUG - Response for create_subnet : {'subnet': {'id': '399c6ec6-4fab-43a2-8c93-15199f87c926', 'name': '', 'tenant_id': '07b2556b652f4bc08953bf9ba7d84b24', 'network_id': 'c05b6990-dbcb-457c-b40a-3a86221f1476', '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': '07b2556b652f4bc08953bf9ba7d84b24'}}
2025-08-07 20:56:47,572 - DEBUG - Response for create_subnet : {'subnet': {'id': '39fdce80-ec7a-422a-bb1e-4ed19334952e', 'name': '', 'tenant_id': '07b2556b652f4bc08953bf9ba7d84b24', 'network_id': 'c05b6990-dbcb-457c-b40a-3a86221f1476', 'ip_version': 4, 'cidr': '218.136.229.128/26', 'allocation_pools': [{'start': '218.136.229.130', 'end': '218.136.229.190'}], 'gateway_ip': '218.136.229.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '218.136.229.130', 'tags': [], 'project_id': '07b2556b652f4bc08953bf9ba7d84b24'}}
2025-08-07 20:56:47,608 - INFO - Created VN test_vnv6sr
2025-08-07 20:56:47,626 - DEBUG - VN test_vnv6sr UUID is c05b6990-dbcb-457c-b40a-3a86221f1476
2025-08-07 20:56:48,086 - DEBUG - Response for create_network : {'network': {'id': '4aab96e8-19e5-4048-b0de-51f5068ae9af', 'name': 'test_vnv6dn', 'tenant_id': '07b2556b652f4bc08953bf9ba7d84b24', 'project_id': '07b2556b652f4bc08953bf9ba7d84b24', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-17438693', 'test_vnv6dn'], 'port_security_enabled': True, 'description': ''}}
2025-08-07 20:56:48,347 - DEBUG - Response for create_subnet : {'subnet': {'id': 'b3f0c593-bdbe-42d4-880f-557b6bf290f1', 'name': '', 'tenant_id': '07b2556b652f4bc08953bf9ba7d84b24', 'network_id': '4aab96e8-19e5-4048-b0de-51f5068ae9af', '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': '07b2556b652f4bc08953bf9ba7d84b24'}}
2025-08-07 20:56:48,635 - DEBUG - Response for create_subnet : {'subnet': {'id': 'dbfb48ea-f4bd-40b6-a5d2-8fc43c979477', 'name': '', 'tenant_id': '07b2556b652f4bc08953bf9ba7d84b24', 'network_id': '4aab96e8-19e5-4048-b0de-51f5068ae9af', 'ip_version': 4, 'cidr': '20.213.59.128/26', 'allocation_pools': [{'start': '20.213.59.130', 'end': '20.213.59.190'}], 'gateway_ip': '20.213.59.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '20.213.59.130', 'tags': [], 'project_id': '07b2556b652f4bc08953bf9ba7d84b24'}}
2025-08-07 20:56:48,677 - INFO - Created VN test_vnv6dn
2025-08-07 20:56:48,694 - DEBUG - VN test_vnv6dn UUID is 4aab96e8-19e5-4048-b0de-51f5068ae9af
2025-08-07 20:56:50,113 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3775-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3775-2)
2025-08-07 20:56:51,690 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3775-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3775-1)
2025-08-07 20:56:51,690 - INFO - Waiting for VM source_vm to be up..
2025-08-07 20:56:51,800 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2025-08-07 20:56:56,900 - DEBUG - VM is in ACTIVE state now
2025-08-07 20:56:56,900 - INFO - VM name : source_vm
2025-08-07 20:56:56,997 - DEBUG - VM source_vm ID is 69bde6d1-c794-4181-b8b3-48990da784a0
2025-08-07 20:56:56,997 - DEBUG - VM source_vm launched on Node an-jenkins-deploy-platform-ansible-os-3775-2
2025-08-07 20:56:57,102 - DEBUG - Requesting: http://10.0.0.241:8082/virtual-machine/69bde6d1-c794-4181-b8b3-48990da784a0
2025-08-07 20:56:57,117 - DEBUG - Requesting: http://10.0.0.241:8082/virtual-machine-interface/30dd644e-a271-43ef-8e31-e988cd97eccc
2025-08-07 20:57:00,355 - 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 1013ms')
2025-08-07 20:57:00,355 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM source_vm failed!
2025-08-07 20:57:00,372 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-17438693:test_vnv6sr is 2001::101:1 and allocation pool is NOT set
2025-08-07 20:57:00,372 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-17438693:test_vnv6sr is 218.136.229.129 and allocation pool is NOT set
2025-08-07 20:57:02,440 - 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.39 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=2.32 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 = 2.322/5.855/9.389/3.533 ms')
2025-08-07 20:57:02,440 - INFO - Ping to Metadata IP 169.254.0.3 of VM source_vm passed
2025-08-07 20:57:02,565 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-07 20:57:02,565 - DEBUG - Waiting to SSH to VM source_vm, IP 218.136.229.131, Port 22
2025-08-07 20:57:02,635 - DEBUG - Error on ssh to cirros@169.254.0.3:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2025-08-07 20:57:02,742 - DEBUG - VM source_vm is NOT ready for SSH connections, VM status: ACTIVE
2025-08-07 20:57:07,743 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-07 20:57:07,743 - DEBUG - Waiting to SSH to VM source_vm, IP 218.136.229.131, Port 22
2025-08-07 20:57:07,900 - DEBUG - VM source_vm is ready for SSH connections
2025-08-07 20:57:07,900 - INFO - Waiting for VM dest_vm to be up..
2025-08-07 20:57:07,990 - DEBUG - VM is in ACTIVE state now
2025-08-07 20:57:07,990 - INFO - VM name : dest_vm
2025-08-07 20:57:08,093 - DEBUG - VM dest_vm ID is 26643f96-275e-4504-8e27-08354060ef01
2025-08-07 20:57:08,093 - DEBUG - VM dest_vm launched on Node an-jenkins-deploy-platform-ansible-os-3775-1
2025-08-07 20:57:08,187 - DEBUG - Requesting: http://10.0.0.241:8082/virtual-machine/26643f96-275e-4504-8e27-08354060ef01
2025-08-07 20:57:08,201 - DEBUG - Requesting: http://10.0.0.241:8082/virtual-machine-interface/a4851134-9b3c-405b-91b7-64add8d56ac5
2025-08-07 20:57:09,446 - 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.93 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.875 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.875/2.400/3.925/1.525 ms')
2025-08-07 20:57:09,446 - INFO - Ping to Metadata IP 169.254.0.3 of VM dest_vm passed
2025-08-07 20:57:09,563 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-07 20:57:09,563 - DEBUG - Waiting to SSH to VM dest_vm, IP 20.213.59.131, Port 22
2025-08-07 20:57:09,781 - DEBUG - VM dest_vm is ready for SSH connections
2025-08-07 20:57:09,791 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-17438693: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-17438693:test_vnv6dn, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = 0, end_port = -1], action_list = simple_action = pass, gateway_name = None, apply_service = [], service_properties = None, mirror_to = None, assign_routing_instance = None, log = False, alert = False, qos_action = None, host_based_service = False, ethertype = None, created = None, last_modified = None, description = None]
2025-08-07 20:57:10,154 - INFO - Attaching policy allow_all to vn test_vnv6sr
2025-08-07 20:57:10,543 - INFO - Attaching policy allow_all to vn test_vnv6dn
2025-08-07 20:57:10,848 - INFO - Configure the policy with allow any
2025-08-07 20:57:11,203 - DEBUG - Response for create_network : {'network': {'id': '694c1c1d-7096-4095-9c85-1b4514e22700', 'name': 'vn1', 'tenant_id': '07b2556b652f4bc08953bf9ba7d84b24', 'project_id': '07b2556b652f4bc08953bf9ba7d84b24', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-17438693', 'vn1'], 'port_security_enabled': True, 'description': ''}}
2025-08-07 20:57:11,421 - DEBUG - Response for create_subnet : {'subnet': {'id': 'f332a3e0-066e-42ec-af9b-b16c8822d6d4', 'name': '', 'tenant_id': '07b2556b652f4bc08953bf9ba7d84b24', 'network_id': '694c1c1d-7096-4095-9c85-1b4514e22700', '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': '07b2556b652f4bc08953bf9ba7d84b24'}}
2025-08-07 20:57:11,450 - INFO - Created VN vn1
2025-08-07 20:57:11,467 - DEBUG - VN vn1 UUID is 694c1c1d-7096-4095-9c85-1b4514e22700
2025-08-07 20:57:11,868 - DEBUG - Response for create_network : {'network': {'id': '111c1102-35cb-441d-b6ed-0d70a745ecbf', 'name': 'vn2', 'tenant_id': '07b2556b652f4bc08953bf9ba7d84b24', 'project_id': '07b2556b652f4bc08953bf9ba7d84b24', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-17438693', 'vn2'], 'port_security_enabled': True, 'description': ''}}
2025-08-07 20:57:12,123 - DEBUG - Response for create_subnet : {'subnet': {'id': 'e3148dec-b098-435d-b694-1713765aba11', 'name': '', 'tenant_id': '07b2556b652f4bc08953bf9ba7d84b24', 'network_id': '111c1102-35cb-441d-b6ed-0d70a745ecbf', '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': '07b2556b652f4bc08953bf9ba7d84b24'}}
2025-08-07 20:57:12,150 - INFO - Created VN vn2
2025-08-07 20:57:12,165 - DEBUG - VN vn2 UUID is 111c1102-35cb-441d-b6ed-0d70a745ecbf
2025-08-07 20:57:12,269 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-17438693: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-17438693:vn2, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = 0, end_port = -1], action_list = simple_action = pass, gateway_name = None, apply_service = [], service_properties = None, mirror_to = None, assign_routing_instance = None, log = False, alert = False, qos_action = None, host_based_service = False, ethertype = None, created = None, last_modified = None, description = None]
2025-08-07 20:57:12,619 - INFO - Attaching policy sec_grp_policy_ctest-random-14717384 to vn vn1
2025-08-07 20:57:12,988 - INFO - Attaching policy sec_grp_policy_ctest-random-14717384 to vn vn2
2025-08-07 20:57:14,681 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3775-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3775-2)
2025-08-07 20:57:16,271 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3775-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3775-1)
2025-08-07 20:57:16,336 - INFO - ================================================================================
2025-08-07 20:57:16,337 - INFO - STARTING TEST : test_create_md5_on_control
2025-08-07 20:57:16,337 - INFO - TEST DESCRIPTION :
Description: Verify md5 with allow specific protocol on all ports and policy with allow all between VN's
2025-08-07 20:57:17,644 - DEBUG - Skipping xmpp flap check
2025-08-07 20:57:17,644 - INFO - Initial checks done. Running the testcase now
2025-08-07 20:57:17,644 - INFO -
2025-08-07 20:57:17,644 - INFO - config md5 on each host with auth_data None
2025-08-07 20:57:17,644 - INFO - config per peer with auth_data None
2025-08-07 20:57:18,299 - INFO - configure MD5 on host c32746c9-f2c5-437b-bdcd-2c4eb3d75e97
2025-08-07 20:57:18,395 - INFO - config per peer with auth_data None
2025-08-07 20:57:19,015 - INFO - configure MD5 on host 2ee2c1a2-2045-4996-9f60-9e77439927cd
2025-08-07 20:57:19,131 - INFO - config per peer with auth_data None
2025-08-07 20:57:19,767 - INFO - configure MD5 on host fe8a5d22-718f-4d50-8a12-1fc5e062a886
2025-08-07 20:57:19,867 - INFO - check if BGP between peers are up before setting md5
2025-08-07 20:57:19,867 - INFO - Check BGP staus between peers
2025-08-07 20:57:19,883 - INFO - setting MD5 config on host c32746c9-f2c5-437b-bdcd-2c4eb3d75e97
2025-08-07 20:57:19,883 - INFO - configure MD5 on host c32746c9-f2c5-437b-bdcd-2c4eb3d75e97
2025-08-07 20:57:19,971 - INFO - setting MD5 config on host 2ee2c1a2-2045-4996-9f60-9e77439927cd
2025-08-07 20:57:19,971 - INFO - configure MD5 on host 2ee2c1a2-2045-4996-9f60-9e77439927cd
2025-08-07 20:57:20,059 - INFO - setting MD5 config on host fe8a5d22-718f-4d50-8a12-1fc5e062a886
2025-08-07 20:57:20,059 - INFO - configure MD5 on host fe8a5d22-718f-4d50-8a12-1fc5e062a886
2025-08-07 20:57:20,147 - INFO - check if BGP peering is up after md5 config
2025-08-07 20:57:20,147 - INFO - Check BGP staus between peers
2025-08-07 20:57:20,161 - DEBUG - Check the BGP connection on 10.0.0.19
2025-08-07 20:57:20,161 - DEBUG - Check the BGP connection on 10.0.0.19
2025-08-07 20:57:30,162 - INFO - Check BGP staus between peers
2025-08-07 20:57:30,176 - INFO - remove md5 configured on host c32746c9-f2c5-437b-bdcd-2c4eb3d75e97
2025-08-07 20:57:30,176 - INFO - config per peer with auth_data None
2025-08-07 20:57:30,747 - INFO - configure MD5 on host c32746c9-f2c5-437b-bdcd-2c4eb3d75e97
2025-08-07 20:57:30,835 - INFO - remove md5 configured on host 2ee2c1a2-2045-4996-9f60-9e77439927cd
2025-08-07 20:57:30,835 - INFO - config per peer with auth_data None
2025-08-07 20:57:31,535 - INFO - configure MD5 on host 2ee2c1a2-2045-4996-9f60-9e77439927cd
2025-08-07 20:57:31,631 - INFO - remove md5 configured on host fe8a5d22-718f-4d50-8a12-1fc5e062a886
2025-08-07 20:57:31,631 - INFO - config per peer with auth_data None
2025-08-07 20:57:32,303 - INFO - configure MD5 on host fe8a5d22-718f-4d50-8a12-1fc5e062a886
2025-08-07 20:57:32,399 - INFO - Skip interface_detach for VM vn2_vm_0 in state BUILD
2025-08-07 20:57:32,399 - INFO - Deleting VM vn2_vm_0
2025-08-07 20:57:32,399 - ERROR - VM vn2_vm_0 has failed to come up
2025-08-07 20:57:32,399 - ERROR - Nova failed to get host of the VM
2025-08-07 20:57:32,399 - ERROR - VM vn2_vm_0 has failed to come up
2025-08-07 20:57:32,399 - ERROR - Nova failed to get host of the VM
2025-08-07 20:57:32,483 - INFO - Skip interface_detach for VM vn1_vm_0 in state BUILD
2025-08-07 20:57:32,484 - INFO - Deleting VM vn1_vm_0
2025-08-07 20:57:32,484 - ERROR - VM vn1_vm_0 has failed to come up
2025-08-07 20:57:32,485 - ERROR - Nova failed to get host of the VM
2025-08-07 20:57:32,485 - ERROR - VM vn1_vm_0 has failed to come up
2025-08-07 20:57:32,485 - ERROR - Nova failed to get host of the VM
2025-08-07 20:57:32,572 - INFO - Dettaching policy sec_grp_policy_ctest-random-14717384 from vn vn2
2025-08-07 20:57:32,796 - INFO - Dettaching policy sec_grp_policy_ctest-random-14717384 from vn vn1
2025-08-07 20:57:33,129 - INFO - Deleted policy sec_grp_policy_ctest-random-14717384
2025-08-07 20:57:33,129 - INFO - Deleting VN vn2
2025-08-07 20:57:33,184 - DEBUG - VN 111c1102-35cb-441d-b6ed-0d70a745ecbf still in use: Unable to complete operation on network 111c1102-35cb-441d-b6ed-0d70a745ecbf. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-58c2e1a9-1fda-4404-978d-330560b1003c']
2025-08-07 20:57:33,184 - WARNING - Deleting VN vn2 failed..Will retry
2025-08-07 20:57:35,355 - DEBUG - Response for deleting network ()
2025-08-07 20:57:35,356 - INFO - Deleting VN vn1
2025-08-07 20:57:35,560 - DEBUG - Response for deleting network ()
2025-08-07 20:57:35,560 - INFO - Dettaching policy allow_all from vn test_vnv6dn
2025-08-07 20:57:35,780 - INFO - Dettaching policy allow_all from vn test_vnv6sr
2025-08-07 20:57:36,101 - INFO - Deleted policy allow_all
2025-08-07 20:57:36,101 - INFO - Deleting VM dest_vm
2025-08-07 20:57:36,184 - INFO - Deleting VM source_vm
2025-08-07 20:57:36,264 - INFO - Deleting VN test_vnv6dn
2025-08-07 20:57:36,316 - DEBUG - VN 4aab96e8-19e5-4048-b0de-51f5068ae9af still in use: Unable to complete operation on network 4aab96e8-19e5-4048-b0de-51f5068ae9af. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-1e2256d7-8d69-4d31-b234-9301182cc179']
2025-08-07 20:57:36,316 - WARNING - Deleting VN test_vnv6dn failed..Will retry
2025-08-07 20:57:38,510 - DEBUG - Response for deleting network ()
2025-08-07 20:57:38,511 - INFO - Deleting VN test_vnv6sr
2025-08-07 20:57:38,747 - DEBUG - Response for deleting network ()
2025-08-07 20:57:40,072 - DEBUG - Skipping xmpp flap check
2025-08-07 20:57:40,072 - INFO - END TEST : test_create_md5_on_control : PASSED[0:00:24]
2025-08-07 20:57:40,072 - INFO - --------------------------------------------------------------------------------
2025-08-07 20:57:40,821 - INFO - Deleted project: ctest-TestMd5testsOnControl-17438693, ID : 07b2556b-652f-4bc0-8953-bf9ba7d84b24