2025-08-04 04:26:05,754 - INFO - Domain Default found not creating 2025-08-04 04:26:05,919 - INFO - Project ctest-TestMd5testsOnControl-32132481 not found, creating it 2025-08-04 04:26:06,528 - INFO - Created Project:ctest-TestMd5testsOnControl-32132481, ID : 2c9ecb08-8f85-421d-9726-bc7b4f714051 2025-08-04 04:26:08,907 - DEBUG - Response for create_network : {'network': {'id': 'a668cb7b-d000-4013-92f7-02c6fe29c4e9', 'name': 'test_vnv6sr', 'tenant_id': '2c9ecb088f85421d9726bc7b4f714051', 'project_id': '2c9ecb088f85421d9726bc7b4f714051', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-32132481', 'test_vnv6sr'], 'port_security_enabled': True, 'description': ''}} 2025-08-04 04:26:09,274 - DEBUG - Response for create_subnet : {'subnet': {'id': '3309f63a-759a-4219-8626-f3c8bec47400', 'name': '', 'tenant_id': '2c9ecb088f85421d9726bc7b4f714051', 'network_id': 'a668cb7b-d000-4013-92f7-02c6fe29c4e9', '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': '2c9ecb088f85421d9726bc7b4f714051'}} 2025-08-04 04:26:09,454 - DEBUG - Response for create_subnet : {'subnet': {'id': 'bd85f826-7ca5-4d6b-84e5-74606d95b996', 'name': '', 'tenant_id': '2c9ecb088f85421d9726bc7b4f714051', 'network_id': 'a668cb7b-d000-4013-92f7-02c6fe29c4e9', 'ip_version': 4, 'cidr': '70.205.58.128/26', 'allocation_pools': [{'start': '70.205.58.130', 'end': '70.205.58.190'}], 'gateway_ip': '70.205.58.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '70.205.58.130', 'tags': [], 'project_id': '2c9ecb088f85421d9726bc7b4f714051'}} 2025-08-04 04:26:09,484 - INFO - Created VN test_vnv6sr 2025-08-04 04:26:09,498 - DEBUG - VN test_vnv6sr UUID is a668cb7b-d000-4013-92f7-02c6fe29c4e9 2025-08-04 04:26:10,003 - DEBUG - Response for create_network : {'network': {'id': '41fbc5ff-9c46-4a85-a516-f2023c9f5e4c', 'name': 'test_vnv6dn', 'tenant_id': '2c9ecb088f85421d9726bc7b4f714051', 'project_id': '2c9ecb088f85421d9726bc7b4f714051', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-32132481', 'test_vnv6dn'], 'port_security_enabled': True, 'description': ''}} 2025-08-04 04:26:10,273 - DEBUG - Response for create_subnet : {'subnet': {'id': 'f69189ea-b972-4c83-8c5c-ac502fedda73', 'name': '', 'tenant_id': '2c9ecb088f85421d9726bc7b4f714051', 'network_id': '41fbc5ff-9c46-4a85-a516-f2023c9f5e4c', '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': '2c9ecb088f85421d9726bc7b4f714051'}} 2025-08-04 04:26:10,475 - DEBUG - Response for create_subnet : {'subnet': {'id': '3cec5232-9df2-4f4b-8fdf-3738cc892186', 'name': '', 'tenant_id': '2c9ecb088f85421d9726bc7b4f714051', 'network_id': '41fbc5ff-9c46-4a85-a516-f2023c9f5e4c', 'ip_version': 4, 'cidr': '222.181.102.192/26', 'allocation_pools': [{'start': '222.181.102.194', 'end': '222.181.102.254'}], 'gateway_ip': '222.181.102.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '222.181.102.194', 'tags': [], 'project_id': '2c9ecb088f85421d9726bc7b4f714051'}} 2025-08-04 04:26:10,510 - INFO - Created VN test_vnv6dn 2025-08-04 04:26:10,529 - DEBUG - VN test_vnv6dn UUID is 41fbc5ff-9c46-4a85-a516-f2023c9f5e4c 2025-08-04 04:26:10,779 - DEBUG - Services list from nova: [, , , ] 2025-08-04 04:26:12,109 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3750-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3750-2) 2025-08-04 04:26:13,849 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3750-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3750-1) 2025-08-04 04:26:13,849 - INFO - Waiting for VM source_vm to be up.. 2025-08-04 04:26:14,068 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-08-04 04:26:19,156 - DEBUG - VM is in ACTIVE state now 2025-08-04 04:26:19,157 - INFO - VM name : source_vm 2025-08-04 04:26:19,247 - DEBUG - VM source_vm ID is afaf5405-e602-4f98-abf6-ced4e8d472ad 2025-08-04 04:26:19,276 - DEBUG - VM source_vm launched on Node an-jenkins-deploy-platform-ansible-os-3750-2 2025-08-04 04:26:19,362 - DEBUG - Requesting: http://10.0.0.61:8082/virtual-machine/afaf5405-e602-4f98-abf6-ced4e8d472ad 2025-08-04 04:26:19,671 - DEBUG - Requesting: http://10.0.0.61:8082/virtual-machine/afaf5405-e602-4f98-abf6-ced4e8d472ad 2025-08-04 04:26:19,706 - DEBUG - Requesting: http://10.0.0.61:8082/virtual-machine-interface/5303baa6-6bdf-4d8f-a6ef-f4e68ee95e5f 2025-08-04 04:26:22,909 - 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 1017ms') 2025-08-04 04:26:22,909 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM source_vm failed! 2025-08-04 04:26:22,929 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-32132481:test_vnv6sr is 2001::101:1 and allocation pool is NOT set 2025-08-04 04:26:22,929 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-32132481:test_vnv6sr is 70.205.58.129 and allocation pool is NOT set 2025-08-04 04:26:24,994 - 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=2.67 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.428 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.428/1.551/2.674/1.123 ms') 2025-08-04 04:26:24,994 - INFO - Ping to Metadata IP 169.254.0.3 of VM source_vm passed 2025-08-04 04:26:25,079 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-08-04 04:26:25,079 - DEBUG - Waiting to SSH to VM source_vm, IP 70.205.58.131, Port 22 2025-08-04 04:26:25,245 - DEBUG - VM source_vm is ready for SSH connections 2025-08-04 04:26:25,245 - INFO - Waiting for VM dest_vm to be up.. 2025-08-04 04:26:25,393 - DEBUG - VM is in ACTIVE state now 2025-08-04 04:26:25,393 - INFO - VM name : dest_vm 2025-08-04 04:26:25,519 - DEBUG - VM dest_vm ID is ddbd2f35-5baf-4669-9dc6-25842836deae 2025-08-04 04:26:25,519 - DEBUG - VM dest_vm launched on Node an-jenkins-deploy-platform-ansible-os-3750-1 2025-08-04 04:26:25,630 - DEBUG - Requesting: http://10.0.0.61:8082/virtual-machine/ddbd2f35-5baf-4669-9dc6-25842836deae 2025-08-04 04:26:25,645 - DEBUG - Requesting: http://10.0.0.61:8082/virtual-machine-interface/36ad18b5-afc4-4ae6-8905-cd1a349c930c 2025-08-04 04:26:26,848 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n64 bytes from 169.254.0.3: icmp_seq=1 ttl=63 time=4.52 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.441 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.441/2.480/4.520/2.039 ms') 2025-08-04 04:26:26,848 - INFO - Ping to Metadata IP 169.254.0.3 of VM dest_vm passed 2025-08-04 04:26:26,932 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-08-04 04:26:26,932 - DEBUG - Waiting to SSH to VM dest_vm, IP 222.181.102.195, Port 22 2025-08-04 04:26:26,999 - 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-04 04:26:27,088 - DEBUG - VM dest_vm is NOT ready for SSH connections, VM status: ACTIVE 2025-08-04 04:26:32,089 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-08-04 04:26:32,089 - DEBUG - Waiting to SSH to VM dest_vm, IP 222.181.102.195, Port 22 2025-08-04 04:26:32,262 - DEBUG - VM dest_vm is ready for SSH connections 2025-08-04 04:26:32,270 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-32132481: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-32132481: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-04 04:26:32,685 - INFO - Attaching policy allow_all to vn test_vnv6sr 2025-08-04 04:26:33,095 - INFO - Attaching policy allow_all to vn test_vnv6dn 2025-08-04 04:26:33,440 - INFO - Configure the policy with allow any 2025-08-04 04:26:33,846 - DEBUG - Response for create_network : {'network': {'id': 'e7579187-666f-47a4-aa65-a62e84371fde', 'name': 'vn1', 'tenant_id': '2c9ecb088f85421d9726bc7b4f714051', 'project_id': '2c9ecb088f85421d9726bc7b4f714051', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-32132481', 'vn1'], 'port_security_enabled': True, 'description': ''}} 2025-08-04 04:26:34,155 - DEBUG - Response for create_subnet : {'subnet': {'id': '7b8601f5-c84a-427f-9ff6-0a9be52fbc82', 'name': '', 'tenant_id': '2c9ecb088f85421d9726bc7b4f714051', 'network_id': 'e7579187-666f-47a4-aa65-a62e84371fde', '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': '2c9ecb088f85421d9726bc7b4f714051'}} 2025-08-04 04:26:34,187 - INFO - Created VN vn1 2025-08-04 04:26:34,216 - DEBUG - VN vn1 UUID is e7579187-666f-47a4-aa65-a62e84371fde 2025-08-04 04:26:34,667 - DEBUG - Response for create_network : {'network': {'id': '1d5410e6-9d60-45e9-a2a8-2a3c3006f89e', 'name': 'vn2', 'tenant_id': '2c9ecb088f85421d9726bc7b4f714051', 'project_id': '2c9ecb088f85421d9726bc7b4f714051', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-32132481', 'vn2'], 'port_security_enabled': True, 'description': ''}} 2025-08-04 04:26:34,929 - DEBUG - Response for create_subnet : {'subnet': {'id': 'b39b81dd-8ed7-4d2c-bb95-8ba5fd238ecd', 'name': '', 'tenant_id': '2c9ecb088f85421d9726bc7b4f714051', 'network_id': '1d5410e6-9d60-45e9-a2a8-2a3c3006f89e', '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': '2c9ecb088f85421d9726bc7b4f714051'}} 2025-08-04 04:26:34,956 - INFO - Created VN vn2 2025-08-04 04:26:34,970 - DEBUG - VN vn2 UUID is 1d5410e6-9d60-45e9-a2a8-2a3c3006f89e 2025-08-04 04:26:35,069 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-32132481: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-32132481: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-04 04:26:35,433 - INFO - Attaching policy sec_grp_policy_ctest-random-38105478 to vn vn1 2025-08-04 04:26:35,845 - INFO - Attaching policy sec_grp_policy_ctest-random-38105478 to vn vn2 2025-08-04 04:26:37,573 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3750-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3750-2) 2025-08-04 04:26:39,370 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3750-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3750-1) 2025-08-04 04:26:39,442 - INFO - ================================================================================ 2025-08-04 04:26:39,442 - INFO - STARTING TEST : test_check_per_peer_on_control 2025-08-04 04:26:39,442 - INFO - TEST DESCRIPTION : Description: Verify per peer md5 and specific protocol on all ports and policy with allow all between VN's 2025-08-04 04:26:40,783 - DEBUG - Skipping xmpp flap check 2025-08-04 04:26:40,783 - INFO - Initial checks done. Running the testcase now 2025-08-04 04:26:40,784 - INFO - 2025-08-04 04:26:40,784 - INFO - config per peer with auth_data None 2025-08-04 04:26:41,452 - INFO - configure MD5 on host 3300bead-4dad-45e7-b23f-291db5d82960 2025-08-04 04:26:41,576 - INFO - config per peer with auth_data None 2025-08-04 04:26:42,340 - INFO - configure MD5 on host baf5f5d6-1fbe-487a-9fee-cf752d438b84 2025-08-04 04:26:42,444 - INFO - config per peer with auth_data None 2025-08-04 04:26:43,116 - INFO - configure MD5 on host c9c05325-8486-48ca-9f05-bd481b4427bf 2025-08-04 04:26:43,232 - INFO - check BGP peering is up before setting md5 config 2025-08-04 04:26:43,232 - INFO - Check BGP staus between peers 2025-08-04 04:26:43,245 - INFO - config per peer with auth_data {'key_items': [{'key': 'juniper', 'key_id': 0}], 'key_type': 'md5'} 2025-08-04 04:26:43,975 - INFO - Check BGP staus between peers 2025-08-04 04:26:43,992 - DEBUG - Check the BGP connection on 10.0.0.27 2025-08-04 04:26:43,992 - DEBUG - Check the BGP connection on 10.0.0.27 2025-08-04 04:26:53,993 - INFO - Check BGP staus between peers 2025-08-04 04:26:54,006 - INFO - remove md5 configured on host 3300bead-4dad-45e7-b23f-291db5d82960 2025-08-04 04:26:54,006 - INFO - config per peer with auth_data None 2025-08-04 04:26:54,628 - INFO - configure MD5 on host 3300bead-4dad-45e7-b23f-291db5d82960 2025-08-04 04:26:54,756 - INFO - remove md5 configured on host baf5f5d6-1fbe-487a-9fee-cf752d438b84 2025-08-04 04:26:54,756 - INFO - config per peer with auth_data None 2025-08-04 04:26:55,448 - INFO - configure MD5 on host baf5f5d6-1fbe-487a-9fee-cf752d438b84 2025-08-04 04:26:55,564 - INFO - remove md5 configured on host c9c05325-8486-48ca-9f05-bd481b4427bf 2025-08-04 04:26:55,564 - INFO - config per peer with auth_data None 2025-08-04 04:26:56,248 - INFO - configure MD5 on host c9c05325-8486-48ca-9f05-bd481b4427bf 2025-08-04 04:26:56,364 - INFO - Skip interface_detach for VM vn2_vm_0 in state BUILD 2025-08-04 04:26:56,364 - INFO - Deleting VM vn2_vm_0 2025-08-04 04:26:56,364 - ERROR - VM vn2_vm_0 has failed to come up 2025-08-04 04:26:56,364 - ERROR - Nova failed to get host of the VM 2025-08-04 04:26:56,364 - ERROR - VM vn2_vm_0 has failed to come up 2025-08-04 04:26:56,364 - ERROR - Nova failed to get host of the VM 2025-08-04 04:26:56,454 - INFO - Skip interface_detach for VM vn1_vm_0 in state BUILD 2025-08-04 04:26:56,454 - INFO - Deleting VM vn1_vm_0 2025-08-04 04:26:56,454 - ERROR - VM vn1_vm_0 has failed to come up 2025-08-04 04:26:56,455 - ERROR - Nova failed to get host of the VM 2025-08-04 04:26:56,455 - ERROR - VM vn1_vm_0 has failed to come up 2025-08-04 04:26:56,455 - ERROR - Nova failed to get host of the VM 2025-08-04 04:26:56,528 - INFO - Dettaching policy sec_grp_policy_ctest-random-38105478 from vn vn2 2025-08-04 04:26:56,745 - INFO - Dettaching policy sec_grp_policy_ctest-random-38105478 from vn vn1 2025-08-04 04:26:57,023 - INFO - Deleted policy sec_grp_policy_ctest-random-38105478 2025-08-04 04:26:57,023 - INFO - Deleting VN vn2 2025-08-04 04:26:57,143 - DEBUG - VN 1d5410e6-9d60-45e9-a2a8-2a3c3006f89e still in use: Unable to complete operation on network 1d5410e6-9d60-45e9-a2a8-2a3c3006f89e. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-075f0e95-a6a2-455a-b1be-246c4eb12f5d'] 2025-08-04 04:26:57,143 - WARNING - Deleting VN vn2 failed..Will retry 2025-08-04 04:26:59,350 - DEBUG - Response for deleting network () 2025-08-04 04:26:59,350 - INFO - Deleting VN vn1 2025-08-04 04:26:59,553 - DEBUG - Response for deleting network () 2025-08-04 04:26:59,553 - INFO - Dettaching policy allow_all from vn test_vnv6dn 2025-08-04 04:26:59,820 - INFO - Dettaching policy allow_all from vn test_vnv6sr 2025-08-04 04:27:00,083 - INFO - Deleted policy allow_all 2025-08-04 04:27:00,083 - INFO - Deleting VM dest_vm 2025-08-04 04:27:00,165 - INFO - Deleting VM source_vm 2025-08-04 04:27:00,256 - INFO - Deleting VN test_vnv6dn 2025-08-04 04:27:00,307 - DEBUG - VN 41fbc5ff-9c46-4a85-a516-f2023c9f5e4c still in use: Unable to complete operation on network 41fbc5ff-9c46-4a85-a516-f2023c9f5e4c. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-61ec307e-f0e9-4682-9a4e-d5a9485044ac'] 2025-08-04 04:27:00,308 - WARNING - Deleting VN test_vnv6dn failed..Will retry 2025-08-04 04:27:02,514 - DEBUG - Response for deleting network () 2025-08-04 04:27:02,514 - INFO - Deleting VN test_vnv6sr 2025-08-04 04:27:02,697 - DEBUG - Response for deleting network () 2025-08-04 04:27:03,998 - DEBUG - Skipping xmpp flap check
2025-08-04 04:27:03,998 - INFO - END TEST : test_check_per_peer_on_control : PASSED[0:00:24]
2025-08-04 04:27:03,998 - INFO - -------------------------------------------------------------------------------- 2025-08-04 04:27:04,303 - DEBUG - Response for create_network : {'network': {'id': '56876a68-123e-4518-be6a-1d154674f575', 'name': 'test_vnv6sr', 'tenant_id': '2c9ecb088f85421d9726bc7b4f714051', 'project_id': '2c9ecb088f85421d9726bc7b4f714051', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-32132481', 'test_vnv6sr'], 'port_security_enabled': True, 'description': ''}} 2025-08-04 04:27:04,598 - DEBUG - Response for create_subnet : {'subnet': {'id': '3477f39b-a338-45db-bda7-8ad427f72a2a', 'name': '', 'tenant_id': '2c9ecb088f85421d9726bc7b4f714051', 'network_id': '56876a68-123e-4518-be6a-1d154674f575', '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': '2c9ecb088f85421d9726bc7b4f714051'}} 2025-08-04 04:27:04,864 - DEBUG - Response for create_subnet : {'subnet': {'id': '429e4507-9e05-4e63-b2e5-5a22a142eb91', 'name': '', 'tenant_id': '2c9ecb088f85421d9726bc7b4f714051', 'network_id': '56876a68-123e-4518-be6a-1d154674f575', 'ip_version': 4, 'cidr': '152.236.8.0/26', 'allocation_pools': [{'start': '152.236.8.2', 'end': '152.236.8.62'}], 'gateway_ip': '152.236.8.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '152.236.8.2', 'tags': [], 'project_id': '2c9ecb088f85421d9726bc7b4f714051'}} 2025-08-04 04:27:04,892 - INFO - Created VN test_vnv6sr 2025-08-04 04:27:04,909 - DEBUG - VN test_vnv6sr UUID is 56876a68-123e-4518-be6a-1d154674f575 2025-08-04 04:27:05,344 - DEBUG - Response for create_network : {'network': {'id': '318b4548-6ca4-44d1-bf2e-073f96b1db73', 'name': 'test_vnv6dn', 'tenant_id': '2c9ecb088f85421d9726bc7b4f714051', 'project_id': '2c9ecb088f85421d9726bc7b4f714051', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-32132481', 'test_vnv6dn'], 'port_security_enabled': True, 'description': ''}} 2025-08-04 04:27:05,594 - DEBUG - Response for create_subnet : {'subnet': {'id': '2dd96a01-262a-4c8d-a642-98273349d2ce', 'name': '', 'tenant_id': '2c9ecb088f85421d9726bc7b4f714051', 'network_id': '318b4548-6ca4-44d1-bf2e-073f96b1db73', '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': '2c9ecb088f85421d9726bc7b4f714051'}} 2025-08-04 04:27:05,845 - DEBUG - Response for create_subnet : {'subnet': {'id': '1e4f7c78-914e-4db4-899e-db50b38bbb81', 'name': '', 'tenant_id': '2c9ecb088f85421d9726bc7b4f714051', 'network_id': '318b4548-6ca4-44d1-bf2e-073f96b1db73', 'ip_version': 4, 'cidr': '174.100.42.0/26', 'allocation_pools': [{'start': '174.100.42.2', 'end': '174.100.42.62'}], 'gateway_ip': '174.100.42.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '174.100.42.2', 'tags': [], 'project_id': '2c9ecb088f85421d9726bc7b4f714051'}} 2025-08-04 04:27:05,871 - INFO - Created VN test_vnv6dn 2025-08-04 04:27:05,887 - DEBUG - VN test_vnv6dn UUID is 318b4548-6ca4-44d1-bf2e-073f96b1db73 2025-08-04 04:27:07,246 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3750-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3750-2) 2025-08-04 04:27:08,757 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3750-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3750-1) 2025-08-04 04:27:08,758 - INFO - Waiting for VM source_vm to be up.. 2025-08-04 04:27:08,864 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-08-04 04:27:13,951 - DEBUG - VM is in ACTIVE state now 2025-08-04 04:27:13,951 - INFO - VM name : source_vm 2025-08-04 04:27:14,046 - DEBUG - VM source_vm ID is 8865ec09-2693-4b46-a608-45899c46dc25 2025-08-04 04:27:14,047 - DEBUG - VM source_vm launched on Node an-jenkins-deploy-platform-ansible-os-3750-2 2025-08-04 04:27:14,138 - DEBUG - Requesting: http://10.0.0.61:8082/virtual-machine/8865ec09-2693-4b46-a608-45899c46dc25 2025-08-04 04:27:14,152 - DEBUG - Requesting: http://10.0.0.61:8082/virtual-machine-interface/01e10f2d-f163-44c0-a39f-857c61946b48 2025-08-04 04:27:17,343 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1011ms') 2025-08-04 04:27:17,343 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM source_vm failed! 2025-08-04 04:27:17,362 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-32132481:test_vnv6sr is 2001::101:1 and allocation pool is NOT set 2025-08-04 04:27:17,362 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-32132481:test_vnv6sr is 152.236.8.1 and allocation pool is NOT set 2025-08-04 04:27:19,431 - 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.95 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=3.79 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.794/3.874/3.954/0.080 ms') 2025-08-04 04:27:19,431 - INFO - Ping to Metadata IP 169.254.0.3 of VM source_vm passed 2025-08-04 04:27:19,506 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-08-04 04:27:19,506 - DEBUG - Waiting to SSH to VM source_vm, IP 152.236.8.3, Port 22 2025-08-04 04:27:19,676 - DEBUG - VM source_vm is ready for SSH connections 2025-08-04 04:27:19,676 - INFO - Waiting for VM dest_vm to be up.. 2025-08-04 04:27:19,788 - DEBUG - VM is in ACTIVE state now 2025-08-04 04:27:19,788 - INFO - VM name : dest_vm 2025-08-04 04:27:19,883 - DEBUG - VM dest_vm ID is 02e82c84-0471-4026-b9b9-a4583ae4f7de 2025-08-04 04:27:19,883 - DEBUG - VM dest_vm launched on Node an-jenkins-deploy-platform-ansible-os-3750-1 2025-08-04 04:27:19,974 - DEBUG - Requesting: http://10.0.0.61:8082/virtual-machine/02e82c84-0471-4026-b9b9-a4583ae4f7de 2025-08-04 04:27:19,987 - DEBUG - Requesting: http://10.0.0.61:8082/virtual-machine-interface/6a8e81c9-5426-4fac-9a18-6b0dc7019d8a 2025-08-04 04:27:21,182 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n64 bytes from 169.254.0.3: icmp_seq=1 ttl=63 time=5.78 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=2.20 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 = 2.196/3.988/5.781/1.792 ms') 2025-08-04 04:27:21,182 - INFO - Ping to Metadata IP 169.254.0.3 of VM dest_vm passed 2025-08-04 04:27:21,260 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-08-04 04:27:21,260 - DEBUG - Waiting to SSH to VM dest_vm, IP 174.100.42.3, Port 22 2025-08-04 04:27:21,433 - DEBUG - VM dest_vm is ready for SSH connections 2025-08-04 04:27:21,443 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-32132481: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-32132481: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-04 04:27:21,805 - INFO - Attaching policy allow_all to vn test_vnv6sr 2025-08-04 04:27:22,209 - INFO - Attaching policy allow_all to vn test_vnv6dn 2025-08-04 04:27:22,495 - INFO - Configure the policy with allow any 2025-08-04 04:27:22,843 - DEBUG - Response for create_network : {'network': {'id': 'aa9fc4a6-aaa2-4851-aa90-38ba5a598359', 'name': 'vn1', 'tenant_id': '2c9ecb088f85421d9726bc7b4f714051', 'project_id': '2c9ecb088f85421d9726bc7b4f714051', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-32132481', 'vn1'], 'port_security_enabled': True, 'description': ''}} 2025-08-04 04:27:23,097 - DEBUG - Response for create_subnet : {'subnet': {'id': 'de9fa5c7-f58c-4e03-9ff6-92b7b9fa3d0b', 'name': '', 'tenant_id': '2c9ecb088f85421d9726bc7b4f714051', 'network_id': 'aa9fc4a6-aaa2-4851-aa90-38ba5a598359', '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': '2c9ecb088f85421d9726bc7b4f714051'}} 2025-08-04 04:27:23,122 - INFO - Created VN vn1 2025-08-04 04:27:23,138 - DEBUG - VN vn1 UUID is aa9fc4a6-aaa2-4851-aa90-38ba5a598359 2025-08-04 04:27:23,534 - DEBUG - Response for create_network : {'network': {'id': '9f953e65-f5fe-4a37-a117-b132c8f46b03', 'name': 'vn2', 'tenant_id': '2c9ecb088f85421d9726bc7b4f714051', 'project_id': '2c9ecb088f85421d9726bc7b4f714051', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-32132481', 'vn2'], 'port_security_enabled': True, 'description': ''}} 2025-08-04 04:27:23,854 - DEBUG - Response for create_subnet : {'subnet': {'id': '9dd2085c-c761-47d5-bca6-20b8a7a75783', 'name': '', 'tenant_id': '2c9ecb088f85421d9726bc7b4f714051', 'network_id': '9f953e65-f5fe-4a37-a117-b132c8f46b03', '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': '2c9ecb088f85421d9726bc7b4f714051'}} 2025-08-04 04:27:23,876 - INFO - Created VN vn2 2025-08-04 04:27:23,896 - DEBUG - VN vn2 UUID is 9f953e65-f5fe-4a37-a117-b132c8f46b03 2025-08-04 04:27:23,986 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-32132481: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-32132481: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-04 04:27:24,360 - INFO - Attaching policy sec_grp_policy_ctest-random-59876310 to vn vn1 2025-08-04 04:27:24,737 - INFO - Attaching policy sec_grp_policy_ctest-random-59876310 to vn vn2 2025-08-04 04:27:26,386 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3750-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3750-2) 2025-08-04 04:27:27,888 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3750-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3750-1) 2025-08-04 04:27:27,958 - INFO - ================================================================================ 2025-08-04 04:27:27,959 - INFO - STARTING TEST : test_create_md5_on_control 2025-08-04 04:27:27,959 - INFO - TEST DESCRIPTION : Description: Verify md5 with allow specific protocol on all ports and policy with allow all between VN's 2025-08-04 04:27:29,261 - DEBUG - Skipping xmpp flap check 2025-08-04 04:27:29,261 - INFO - Initial checks done. Running the testcase now 2025-08-04 04:27:29,261 - INFO - 2025-08-04 04:27:29,261 - INFO - config md5 on each host with auth_data None 2025-08-04 04:27:29,261 - INFO - config per peer with auth_data None 2025-08-04 04:27:29,960 - INFO - configure MD5 on host 3300bead-4dad-45e7-b23f-291db5d82960 2025-08-04 04:27:30,084 - INFO - config per peer with auth_data None 2025-08-04 04:27:30,868 - INFO - configure MD5 on host baf5f5d6-1fbe-487a-9fee-cf752d438b84 2025-08-04 04:27:30,980 - INFO - config per peer with auth_data None 2025-08-04 04:27:31,784 - INFO - configure MD5 on host c9c05325-8486-48ca-9f05-bd481b4427bf 2025-08-04 04:27:31,896 - INFO - check if BGP between peers are up before setting md5 2025-08-04 04:27:31,896 - INFO - Check BGP staus between peers 2025-08-04 04:27:31,912 - INFO - setting MD5 config on host 3300bead-4dad-45e7-b23f-291db5d82960 2025-08-04 04:27:31,912 - INFO - configure MD5 on host 3300bead-4dad-45e7-b23f-291db5d82960 2025-08-04 04:27:32,008 - INFO - setting MD5 config on host baf5f5d6-1fbe-487a-9fee-cf752d438b84 2025-08-04 04:27:32,008 - INFO - configure MD5 on host baf5f5d6-1fbe-487a-9fee-cf752d438b84 2025-08-04 04:27:32,124 - INFO - setting MD5 config on host c9c05325-8486-48ca-9f05-bd481b4427bf 2025-08-04 04:27:32,124 - INFO - configure MD5 on host c9c05325-8486-48ca-9f05-bd481b4427bf 2025-08-04 04:27:32,232 - INFO - check if BGP peering is up after md5 config 2025-08-04 04:27:32,232 - INFO - Check BGP staus between peers 2025-08-04 04:27:32,247 - DEBUG - Check the BGP connection on 10.0.0.27 2025-08-04 04:27:32,247 - DEBUG - Check the BGP connection on 10.0.0.27 2025-08-04 04:27:42,248 - INFO - Check BGP staus between peers 2025-08-04 04:27:42,262 - INFO - remove md5 configured on host 3300bead-4dad-45e7-b23f-291db5d82960 2025-08-04 04:27:42,262 - INFO - config per peer with auth_data None 2025-08-04 04:27:42,908 - INFO - configure MD5 on host 3300bead-4dad-45e7-b23f-291db5d82960 2025-08-04 04:27:43,028 - INFO - remove md5 configured on host baf5f5d6-1fbe-487a-9fee-cf752d438b84 2025-08-04 04:27:43,028 - INFO - config per peer with auth_data None 2025-08-04 04:27:43,736 - INFO - configure MD5 on host baf5f5d6-1fbe-487a-9fee-cf752d438b84 2025-08-04 04:27:43,848 - INFO - remove md5 configured on host c9c05325-8486-48ca-9f05-bd481b4427bf 2025-08-04 04:27:43,848 - INFO - config per peer with auth_data None 2025-08-04 04:27:44,528 - INFO - configure MD5 on host c9c05325-8486-48ca-9f05-bd481b4427bf 2025-08-04 04:27:44,624 - INFO - Skip interface_detach for VM vn2_vm_0 in state BUILD 2025-08-04 04:27:44,624 - INFO - Deleting VM vn2_vm_0 2025-08-04 04:27:44,624 - ERROR - VM vn2_vm_0 has failed to come up 2025-08-04 04:27:44,624 - ERROR - Nova failed to get host of the VM 2025-08-04 04:27:44,624 - ERROR - VM vn2_vm_0 has failed to come up 2025-08-04 04:27:44,624 - ERROR - Nova failed to get host of the VM 2025-08-04 04:27:44,694 - INFO - Skip interface_detach for VM vn1_vm_0 in state BUILD 2025-08-04 04:27:44,694 - INFO - Deleting VM vn1_vm_0 2025-08-04 04:27:44,694 - ERROR - VM vn1_vm_0 has failed to come up 2025-08-04 04:27:44,695 - ERROR - Nova failed to get host of the VM 2025-08-04 04:27:44,695 - ERROR - VM vn1_vm_0 has failed to come up 2025-08-04 04:27:44,695 - ERROR - Nova failed to get host of the VM 2025-08-04 04:27:44,766 - INFO - Dettaching policy sec_grp_policy_ctest-random-59876310 from vn vn2 2025-08-04 04:27:45,021 - INFO - Dettaching policy sec_grp_policy_ctest-random-59876310 from vn vn1 2025-08-04 04:27:45,315 - INFO - Deleted policy sec_grp_policy_ctest-random-59876310 2025-08-04 04:27:45,316 - INFO - Deleting VN vn2 2025-08-04 04:27:45,392 - DEBUG - VN 9f953e65-f5fe-4a37-a117-b132c8f46b03 still in use: Unable to complete operation on network 9f953e65-f5fe-4a37-a117-b132c8f46b03. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-a0bbd72a-769f-4bd1-be02-aa2366b8c26d'] 2025-08-04 04:27:45,392 - WARNING - Deleting VN vn2 failed..Will retry 2025-08-04 04:27:47,561 - DEBUG - Response for deleting network () 2025-08-04 04:27:47,562 - INFO - Deleting VN vn1 2025-08-04 04:27:47,740 - DEBUG - Response for deleting network () 2025-08-04 04:27:47,740 - INFO - Dettaching policy allow_all from vn test_vnv6dn 2025-08-04 04:27:47,965 - INFO - Dettaching policy allow_all from vn test_vnv6sr 2025-08-04 04:27:48,255 - INFO - Deleted policy allow_all 2025-08-04 04:27:48,255 - INFO - Deleting VM dest_vm 2025-08-04 04:27:48,332 - INFO - Deleting VM source_vm 2025-08-04 04:27:48,410 - INFO - Deleting VN test_vnv6dn 2025-08-04 04:27:48,472 - DEBUG - VN 318b4548-6ca4-44d1-bf2e-073f96b1db73 still in use: Unable to complete operation on network 318b4548-6ca4-44d1-bf2e-073f96b1db73. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-94a6442c-41aa-4d5e-97bb-93009f798a75'] 2025-08-04 04:27:48,472 - WARNING - Deleting VN test_vnv6dn failed..Will retry 2025-08-04 04:27:50,649 - DEBUG - Response for deleting network () 2025-08-04 04:27:50,649 - INFO - Deleting VN test_vnv6sr 2025-08-04 04:27:50,869 - DEBUG - Response for deleting network () 2025-08-04 04:27:52,164 - DEBUG - Skipping xmpp flap check
2025-08-04 04:27:52,164 - INFO - END TEST : test_create_md5_on_control : PASSED[0:00:25]
2025-08-04 04:27:52,164 - INFO - -------------------------------------------------------------------------------- 2025-08-04 04:27:52,969 - INFO - Deleted project: ctest-TestMd5testsOnControl-32132481, ID : 2c9ecb08-8f85-421d-9726-bc7b4f714051