2025-10-16 04:12:21,047 - INFO - Domain Default found not creating 2025-10-16 04:12:21,206 - INFO - Project ctest-TestMd5testsOnControl-31596472 not found, creating it 2025-10-16 04:12:21,697 - INFO - Created Project:ctest-TestMd5testsOnControl-31596472, ID : 227057cf-4131-492a-b4e5-0c8d36330bc1 2025-10-16 04:12:23,911 - DEBUG - Response for create_network : {'network': {'id': '3869fa3e-45e4-4027-9efa-49bb55866fa0', 'name': 'test_vnv6sr', 'tenant_id': '227057cf4131492ab4e50c8d36330bc1', 'project_id': '227057cf4131492ab4e50c8d36330bc1', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-31596472', 'test_vnv6sr'], 'port_security_enabled': True, 'description': ''}} 2025-10-16 04:12:24,160 - DEBUG - Response for create_subnet : {'subnet': {'id': '777ebfba-eda2-4c69-81d0-5531c0273242', 'name': '', 'tenant_id': '227057cf4131492ab4e50c8d36330bc1', 'network_id': '3869fa3e-45e4-4027-9efa-49bb55866fa0', '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': '227057cf4131492ab4e50c8d36330bc1'}} 2025-10-16 04:12:24,428 - DEBUG - Response for create_subnet : {'subnet': {'id': '3dfceb10-0c29-4dd1-98bb-6712c98482ae', 'name': '', 'tenant_id': '227057cf4131492ab4e50c8d36330bc1', 'network_id': '3869fa3e-45e4-4027-9efa-49bb55866fa0', 'ip_version': 4, 'cidr': '20.184.100.128/26', 'allocation_pools': [{'start': '20.184.100.130', 'end': '20.184.100.190'}], 'gateway_ip': '20.184.100.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '20.184.100.130', 'tags': [], 'project_id': '227057cf4131492ab4e50c8d36330bc1'}} 2025-10-16 04:12:24,454 - INFO - Created VN test_vnv6sr 2025-10-16 04:12:24,467 - DEBUG - VN test_vnv6sr UUID is 3869fa3e-45e4-4027-9efa-49bb55866fa0 2025-10-16 04:12:24,847 - DEBUG - Response for create_network : {'network': {'id': '5ff25090-6f72-4b92-aead-3d8b2174681a', 'name': 'test_vnv6dn', 'tenant_id': '227057cf4131492ab4e50c8d36330bc1', 'project_id': '227057cf4131492ab4e50c8d36330bc1', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-31596472', 'test_vnv6dn'], 'port_security_enabled': True, 'description': ''}} 2025-10-16 04:12:25,191 - DEBUG - Response for create_subnet : {'subnet': {'id': '78bbde03-10fa-4a46-8eb3-319ebeed938e', 'name': '', 'tenant_id': '227057cf4131492ab4e50c8d36330bc1', 'network_id': '5ff25090-6f72-4b92-aead-3d8b2174681a', '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': '227057cf4131492ab4e50c8d36330bc1'}} 2025-10-16 04:12:25,475 - DEBUG - Response for create_subnet : {'subnet': {'id': '3c51bb9c-e12e-4e5d-96fa-575329a4a834', 'name': '', 'tenant_id': '227057cf4131492ab4e50c8d36330bc1', 'network_id': '5ff25090-6f72-4b92-aead-3d8b2174681a', 'ip_version': 4, 'cidr': '180.144.67.128/26', 'allocation_pools': [{'start': '180.144.67.130', 'end': '180.144.67.190'}], 'gateway_ip': '180.144.67.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '180.144.67.130', 'tags': [], 'project_id': '227057cf4131492ab4e50c8d36330bc1'}} 2025-10-16 04:12:25,512 - INFO - Created VN test_vnv6dn 2025-10-16 04:12:25,528 - DEBUG - VN test_vnv6dn UUID is 5ff25090-6f72-4b92-aead-3d8b2174681a 2025-10-16 04:12:25,701 - DEBUG - Services list from nova: [, , , ] 2025-10-16 04:12:26,814 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4339-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4339-1) 2025-10-16 04:12:28,130 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4339-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4339-2) 2025-10-16 04:12:28,130 - INFO - Waiting for VM source_vm to be up.. 2025-10-16 04:12:28,237 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-10-16 04:12:33,318 - DEBUG - VM is in ACTIVE state now 2025-10-16 04:12:33,319 - INFO - VM name : source_vm 2025-10-16 04:12:33,425 - DEBUG - VM source_vm ID is 75b39b74-c59c-4d33-8b7f-7fb198bfc1f3 2025-10-16 04:12:33,450 - DEBUG - VM source_vm launched on Node an-jenkins-deploy-platform-ansible-os-4339-1 2025-10-16 04:12:33,543 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/75b39b74-c59c-4d33-8b7f-7fb198bfc1f3 2025-10-16 04:12:33,842 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/75b39b74-c59c-4d33-8b7f-7fb198bfc1f3 2025-10-16 04:12:33,871 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/989b67cc-2440-4dc0-bd58-e2850d3ac346 2025-10-16 04:12:37,060 - 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 1031ms') 2025-10-16 04:12:37,060 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM source_vm failed! 2025-10-16 04:12:37,075 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-31596472:test_vnv6sr is 2001::101:1 and allocation pool is NOT set 2025-10-16 04:12:37,075 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-31596472:test_vnv6sr is 20.184.100.129 and allocation pool is NOT set 2025-10-16 04:12:39,143 - 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.48 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.780 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.780/3.129/5.478/2.349 ms') 2025-10-16 04:12:39,143 - INFO - Ping to Metadata IP 169.254.0.3 of VM source_vm passed 2025-10-16 04:12:39,210 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-16 04:12:39,210 - DEBUG - Waiting to SSH to VM source_vm, IP 20.184.100.131, Port 22 2025-10-16 04:12:39,375 - DEBUG - VM source_vm is ready for SSH connections 2025-10-16 04:12:39,375 - INFO - Waiting for VM dest_vm to be up.. 2025-10-16 04:12:39,474 - DEBUG - VM is in ACTIVE state now 2025-10-16 04:12:39,474 - INFO - VM name : dest_vm 2025-10-16 04:12:39,590 - DEBUG - VM dest_vm ID is 05bf369f-a77b-463f-b205-df063037edd1 2025-10-16 04:12:39,591 - DEBUG - VM dest_vm launched on Node an-jenkins-deploy-platform-ansible-os-4339-2 2025-10-16 04:12:39,670 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/05bf369f-a77b-463f-b205-df063037edd1 2025-10-16 04:12:39,679 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/d1baaf3e-1e60-4129-ad4a-2ac2d0e12183 2025-10-16 04:12:40,845 - 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.70 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.624 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.624/2.662/4.701/2.038 ms') 2025-10-16 04:12:40,845 - INFO - Ping to Metadata IP 169.254.0.3 of VM dest_vm passed 2025-10-16 04:12:40,911 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-16 04:12:40,911 - DEBUG - Waiting to SSH to VM dest_vm, IP 180.144.67.131, Port 22 2025-10-16 04:12:41,091 - DEBUG - VM dest_vm is ready for SSH connections 2025-10-16 04:12:41,098 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-31596472: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-31596472: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-10-16 04:12:41,436 - INFO - Attaching policy allow_all to vn test_vnv6sr 2025-10-16 04:12:41,830 - INFO - Attaching policy allow_all to vn test_vnv6dn 2025-10-16 04:12:42,103 - INFO - Configure the policy with allow any 2025-10-16 04:12:42,456 - DEBUG - Response for create_network : {'network': {'id': '0bb35ee3-36a4-4577-b51a-3f6d12300f28', 'name': 'vn1', 'tenant_id': '227057cf4131492ab4e50c8d36330bc1', 'project_id': '227057cf4131492ab4e50c8d36330bc1', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-31596472', 'vn1'], 'port_security_enabled': True, 'description': ''}} 2025-10-16 04:12:42,688 - DEBUG - Response for create_subnet : {'subnet': {'id': '1df08093-ad74-40a3-bc4d-0f0c05d49dbc', 'name': '', 'tenant_id': '227057cf4131492ab4e50c8d36330bc1', 'network_id': '0bb35ee3-36a4-4577-b51a-3f6d12300f28', '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': '227057cf4131492ab4e50c8d36330bc1'}} 2025-10-16 04:12:42,709 - INFO - Created VN vn1 2025-10-16 04:12:42,721 - DEBUG - VN vn1 UUID is 0bb35ee3-36a4-4577-b51a-3f6d12300f28 2025-10-16 04:12:43,069 - DEBUG - Response for create_network : {'network': {'id': 'ae2ea495-82cd-4a01-bfff-64ae93118f09', 'name': 'vn2', 'tenant_id': '227057cf4131492ab4e50c8d36330bc1', 'project_id': '227057cf4131492ab4e50c8d36330bc1', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-31596472', 'vn2'], 'port_security_enabled': True, 'description': ''}} 2025-10-16 04:12:43,359 - DEBUG - Response for create_subnet : {'subnet': {'id': '981c0cdf-daad-419e-b836-a253adefb1cf', 'name': '', 'tenant_id': '227057cf4131492ab4e50c8d36330bc1', 'network_id': 'ae2ea495-82cd-4a01-bfff-64ae93118f09', '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': '227057cf4131492ab4e50c8d36330bc1'}} 2025-10-16 04:12:43,380 - INFO - Created VN vn2 2025-10-16 04:12:43,393 - DEBUG - VN vn2 UUID is ae2ea495-82cd-4a01-bfff-64ae93118f09 2025-10-16 04:12:43,506 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-31596472: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-31596472: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-10-16 04:12:43,829 - INFO - Attaching policy sec_grp_policy_ctest-random-37247877 to vn vn1 2025-10-16 04:12:44,177 - INFO - Attaching policy sec_grp_policy_ctest-random-37247877 to vn vn2 2025-10-16 04:12:45,664 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4339-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4339-1) 2025-10-16 04:12:47,028 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4339-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4339-2) 2025-10-16 04:12:47,090 - INFO - ================================================================================ 2025-10-16 04:12:47,090 - INFO - STARTING TEST : test_check_per_peer_on_control 2025-10-16 04:12:47,090 - INFO - TEST DESCRIPTION : Description: Verify per peer md5 and specific protocol on all ports and policy with allow all between VN's 2025-10-16 04:12:48,396 - DEBUG - Skipping xmpp flap check 2025-10-16 04:12:48,396 - INFO - Initial checks done. Running the testcase now 2025-10-16 04:12:48,397 - INFO - 2025-10-16 04:12:48,397 - INFO - config per peer with auth_data None 2025-10-16 04:12:48,910 - INFO - configure MD5 on host d07b5859-da08-4d1a-8c08-8da8cdd7f03f 2025-10-16 04:12:48,998 - INFO - config per peer with auth_data None 2025-10-16 04:12:49,574 - INFO - configure MD5 on host fe1dde23-ad0d-4c29-b2fe-bd00cd0a28a5 2025-10-16 04:12:49,650 - INFO - config per peer with auth_data None 2025-10-16 04:12:50,206 - INFO - configure MD5 on host 7530c941-0f57-401b-bc56-87c837db5191 2025-10-16 04:12:50,290 - INFO - check BGP peering is up before setting md5 config 2025-10-16 04:12:50,291 - INFO - Check BGP staus between peers 2025-10-16 04:12:50,299 - INFO - config per peer with auth_data {'key_items': [{'key': 'juniper', 'key_id': 0}], 'key_type': 'md5'} 2025-10-16 04:12:50,867 - INFO - Check BGP staus between peers 2025-10-16 04:12:50,877 - DEBUG - Check the BGP connection on 10.0.0.19 2025-10-16 04:12:50,877 - DEBUG - Check the BGP connection on 10.0.0.19 2025-10-16 04:13:00,878 - INFO - Check BGP staus between peers 2025-10-16 04:13:00,886 - INFO - remove md5 configured on host d07b5859-da08-4d1a-8c08-8da8cdd7f03f 2025-10-16 04:13:00,886 - INFO - config per peer with auth_data None 2025-10-16 04:13:01,442 - INFO - configure MD5 on host d07b5859-da08-4d1a-8c08-8da8cdd7f03f 2025-10-16 04:13:01,526 - INFO - remove md5 configured on host fe1dde23-ad0d-4c29-b2fe-bd00cd0a28a5 2025-10-16 04:13:01,528 - INFO - config per peer with auth_data None 2025-10-16 04:13:02,114 - INFO - configure MD5 on host fe1dde23-ad0d-4c29-b2fe-bd00cd0a28a5 2025-10-16 04:13:02,194 - INFO - remove md5 configured on host 7530c941-0f57-401b-bc56-87c837db5191 2025-10-16 04:13:02,194 - INFO - config per peer with auth_data None 2025-10-16 04:13:02,730 - INFO - configure MD5 on host 7530c941-0f57-401b-bc56-87c837db5191 2025-10-16 04:13:02,810 - INFO - Skip interface_detach for VM vn2_vm_0 in state BUILD 2025-10-16 04:13:02,810 - INFO - Deleting VM vn2_vm_0 2025-10-16 04:13:02,811 - ERROR - VM vn2_vm_0 has failed to come up 2025-10-16 04:13:02,811 - ERROR - Nova failed to get host of the VM 2025-10-16 04:13:02,811 - ERROR - VM vn2_vm_0 has failed to come up 2025-10-16 04:13:02,811 - ERROR - Nova failed to get host of the VM 2025-10-16 04:13:02,892 - INFO - Skip interface_detach for VM vn1_vm_0 in state BUILD 2025-10-16 04:13:02,892 - INFO - Deleting VM vn1_vm_0 2025-10-16 04:13:02,892 - ERROR - VM vn1_vm_0 has failed to come up 2025-10-16 04:13:02,892 - ERROR - Nova failed to get host of the VM 2025-10-16 04:13:02,892 - ERROR - VM vn1_vm_0 has failed to come up 2025-10-16 04:13:02,892 - ERROR - Nova failed to get host of the VM 2025-10-16 04:13:02,955 - INFO - Dettaching policy sec_grp_policy_ctest-random-37247877 from vn vn2 2025-10-16 04:13:03,120 - INFO - Dettaching policy sec_grp_policy_ctest-random-37247877 from vn vn1 2025-10-16 04:13:03,360 - INFO - Deleted policy sec_grp_policy_ctest-random-37247877 2025-10-16 04:13:03,360 - INFO - Deleting VN vn2 2025-10-16 04:13:03,400 - DEBUG - VN ae2ea495-82cd-4a01-bfff-64ae93118f09 still in use: Unable to complete operation on network ae2ea495-82cd-4a01-bfff-64ae93118f09. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-b84bbaf8-5ced-40e1-b63d-1281ee0f2a2f'] 2025-10-16 04:13:03,400 - WARNING - Deleting VN vn2 failed..Will retry 2025-10-16 04:13:05,553 - DEBUG - Response for deleting network () 2025-10-16 04:13:05,554 - INFO - Deleting VN vn1 2025-10-16 04:13:05,742 - DEBUG - Response for deleting network () 2025-10-16 04:13:05,742 - INFO - Dettaching policy allow_all from vn test_vnv6dn 2025-10-16 04:13:05,904 - INFO - Dettaching policy allow_all from vn test_vnv6sr 2025-10-16 04:13:06,157 - INFO - Deleted policy allow_all 2025-10-16 04:13:06,157 - INFO - Deleting VM dest_vm 2025-10-16 04:13:06,246 - INFO - Deleting VM source_vm 2025-10-16 04:13:06,311 - INFO - Deleting VN test_vnv6dn 2025-10-16 04:13:06,363 - DEBUG - VN 5ff25090-6f72-4b92-aead-3d8b2174681a still in use: Unable to complete operation on network 5ff25090-6f72-4b92-aead-3d8b2174681a. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-d7a6fe12-4c73-4ed6-b7e3-1eebf62b9bb8'] 2025-10-16 04:13:06,363 - WARNING - Deleting VN test_vnv6dn failed..Will retry 2025-10-16 04:13:08,546 - DEBUG - Response for deleting network () 2025-10-16 04:13:08,547 - INFO - Deleting VN test_vnv6sr 2025-10-16 04:13:08,737 - DEBUG - Response for deleting network () 2025-10-16 04:13:09,985 - DEBUG - Skipping xmpp flap check
2025-10-16 04:13:09,986 - INFO - END TEST : test_check_per_peer_on_control : PASSED[0:00:22]
2025-10-16 04:13:09,986 - INFO - -------------------------------------------------------------------------------- 2025-10-16 04:13:10,227 - DEBUG - Response for create_network : {'network': {'id': 'e38f14df-8d5e-4b2c-a3fe-f113e66a51ac', 'name': 'test_vnv6sr', 'tenant_id': '227057cf4131492ab4e50c8d36330bc1', 'project_id': '227057cf4131492ab4e50c8d36330bc1', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-31596472', 'test_vnv6sr'], 'port_security_enabled': True, 'description': ''}} 2025-10-16 04:13:10,510 - DEBUG - Response for create_subnet : {'subnet': {'id': '5b2acae5-1e62-4a26-a942-262a5a705c15', 'name': '', 'tenant_id': '227057cf4131492ab4e50c8d36330bc1', 'network_id': 'e38f14df-8d5e-4b2c-a3fe-f113e66a51ac', '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': '227057cf4131492ab4e50c8d36330bc1'}} 2025-10-16 04:13:10,776 - DEBUG - Response for create_subnet : {'subnet': {'id': 'e631b5bd-8a61-4579-ac64-8a6661e79ae4', 'name': '', 'tenant_id': '227057cf4131492ab4e50c8d36330bc1', 'network_id': 'e38f14df-8d5e-4b2c-a3fe-f113e66a51ac', 'ip_version': 4, 'cidr': '163.163.188.128/26', 'allocation_pools': [{'start': '163.163.188.130', 'end': '163.163.188.190'}], 'gateway_ip': '163.163.188.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '163.163.188.130', 'tags': [], 'project_id': '227057cf4131492ab4e50c8d36330bc1'}} 2025-10-16 04:13:10,803 - INFO - Created VN test_vnv6sr 2025-10-16 04:13:10,814 - DEBUG - VN test_vnv6sr UUID is e38f14df-8d5e-4b2c-a3fe-f113e66a51ac 2025-10-16 04:13:11,189 - DEBUG - Response for create_network : {'network': {'id': '2f283d15-1453-4b0c-a0e0-91109e7cd666', 'name': 'test_vnv6dn', 'tenant_id': '227057cf4131492ab4e50c8d36330bc1', 'project_id': '227057cf4131492ab4e50c8d36330bc1', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-31596472', 'test_vnv6dn'], 'port_security_enabled': True, 'description': ''}} 2025-10-16 04:13:11,445 - DEBUG - Response for create_subnet : {'subnet': {'id': 'df07c6d8-5e4c-49a2-8e46-bdffa49d13e9', 'name': '', 'tenant_id': '227057cf4131492ab4e50c8d36330bc1', 'network_id': '2f283d15-1453-4b0c-a0e0-91109e7cd666', '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': '227057cf4131492ab4e50c8d36330bc1'}} 2025-10-16 04:13:11,698 - DEBUG - Response for create_subnet : {'subnet': {'id': 'a4488ed9-467a-47e9-9453-eadc6a9213db', 'name': '', 'tenant_id': '227057cf4131492ab4e50c8d36330bc1', 'network_id': '2f283d15-1453-4b0c-a0e0-91109e7cd666', 'ip_version': 4, 'cidr': '25.199.36.64/26', 'allocation_pools': [{'start': '25.199.36.66', 'end': '25.199.36.126'}], 'gateway_ip': '25.199.36.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '25.199.36.66', 'tags': [], 'project_id': '227057cf4131492ab4e50c8d36330bc1'}} 2025-10-16 04:13:11,724 - INFO - Created VN test_vnv6dn 2025-10-16 04:13:11,735 - DEBUG - VN test_vnv6dn UUID is 2f283d15-1453-4b0c-a0e0-91109e7cd666 2025-10-16 04:13:12,871 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4339-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4339-1) 2025-10-16 04:13:14,338 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4339-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4339-2) 2025-10-16 04:13:14,338 - INFO - Waiting for VM source_vm to be up.. 2025-10-16 04:13:14,440 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-10-16 04:13:19,518 - DEBUG - VM is in ACTIVE state now 2025-10-16 04:13:19,519 - INFO - VM name : source_vm 2025-10-16 04:13:19,589 - DEBUG - VM source_vm ID is d41c9243-d4ad-4d9d-a2d7-f9fd1daf41dd 2025-10-16 04:13:19,589 - DEBUG - VM source_vm launched on Node an-jenkins-deploy-platform-ansible-os-4339-1 2025-10-16 04:13:19,689 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/d41c9243-d4ad-4d9d-a2d7-f9fd1daf41dd 2025-10-16 04:13:19,699 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/a6efc708-5553-45f3-a70f-2ebb5e0f1a9f 2025-10-16 04:13:22,922 - 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 1031ms') 2025-10-16 04:13:22,922 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM source_vm failed! 2025-10-16 04:13:22,936 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-31596472:test_vnv6sr is 2001::101:1 and allocation pool is NOT set 2025-10-16 04:13:22,936 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-31596472:test_vnv6sr is 163.163.188.129 and allocation pool is NOT set 2025-10-16 04:13:24,996 - 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=10.7 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=2.17 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.172/6.427/10.683/4.255 ms') 2025-10-16 04:13:24,996 - INFO - Ping to Metadata IP 169.254.0.3 of VM source_vm passed 2025-10-16 04:13:25,062 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-16 04:13:25,062 - DEBUG - Waiting to SSH to VM source_vm, IP 163.163.188.131, Port 22 2025-10-16 04:13:25,132 - 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-10-16 04:13:25,220 - DEBUG - VM source_vm is NOT ready for SSH connections, VM status: ACTIVE 2025-10-16 04:13:30,222 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-16 04:13:30,222 - DEBUG - Waiting to SSH to VM source_vm, IP 163.163.188.131, Port 22 2025-10-16 04:13:30,382 - DEBUG - VM source_vm is ready for SSH connections 2025-10-16 04:13:30,382 - INFO - Waiting for VM dest_vm to be up.. 2025-10-16 04:13:30,463 - DEBUG - VM is in ACTIVE state now 2025-10-16 04:13:30,463 - INFO - VM name : dest_vm 2025-10-16 04:13:30,548 - DEBUG - VM dest_vm ID is 6f60638c-4f0f-4db4-89dd-cbd0e6f70781 2025-10-16 04:13:30,548 - DEBUG - VM dest_vm launched on Node an-jenkins-deploy-platform-ansible-os-4339-2 2025-10-16 04:13:30,624 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/6f60638c-4f0f-4db4-89dd-cbd0e6f70781 2025-10-16 04:13:30,635 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/525fc9d3-4822-44a5-9a0d-19b8854522d0 2025-10-16 04:13:31,796 - 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.57 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.368 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.368/2.467/4.566/2.099 ms') 2025-10-16 04:13:31,797 - INFO - Ping to Metadata IP 169.254.0.3 of VM dest_vm passed 2025-10-16 04:13:31,862 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-16 04:13:31,863 - DEBUG - Waiting to SSH to VM dest_vm, IP 25.199.36.67, Port 22 2025-10-16 04:13:32,026 - DEBUG - VM dest_vm is ready for SSH connections 2025-10-16 04:13:32,033 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-31596472: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-31596472: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-10-16 04:13:32,372 - INFO - Attaching policy allow_all to vn test_vnv6sr 2025-10-16 04:13:32,725 - INFO - Attaching policy allow_all to vn test_vnv6dn 2025-10-16 04:13:32,976 - INFO - Configure the policy with allow any 2025-10-16 04:13:33,313 - DEBUG - Response for create_network : {'network': {'id': '8bedede4-432c-4ef5-aa66-6a148b9814c9', 'name': 'vn1', 'tenant_id': '227057cf4131492ab4e50c8d36330bc1', 'project_id': '227057cf4131492ab4e50c8d36330bc1', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-31596472', 'vn1'], 'port_security_enabled': True, 'description': ''}} 2025-10-16 04:13:33,529 - DEBUG - Response for create_subnet : {'subnet': {'id': '35af7158-e4d0-43a1-87c2-e20e9c1c7d82', 'name': '', 'tenant_id': '227057cf4131492ab4e50c8d36330bc1', 'network_id': '8bedede4-432c-4ef5-aa66-6a148b9814c9', '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': '227057cf4131492ab4e50c8d36330bc1'}} 2025-10-16 04:13:33,556 - INFO - Created VN vn1 2025-10-16 04:13:33,567 - DEBUG - VN vn1 UUID is 8bedede4-432c-4ef5-aa66-6a148b9814c9 2025-10-16 04:13:33,904 - DEBUG - Response for create_network : {'network': {'id': '821f5c2a-7056-48f2-88af-da375dafe4d8', 'name': 'vn2', 'tenant_id': '227057cf4131492ab4e50c8d36330bc1', 'project_id': '227057cf4131492ab4e50c8d36330bc1', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-31596472', 'vn2'], 'port_security_enabled': True, 'description': ''}} 2025-10-16 04:13:34,110 - DEBUG - Response for create_subnet : {'subnet': {'id': 'e6508ab5-c893-45f2-9774-22407cbdadd6', 'name': '', 'tenant_id': '227057cf4131492ab4e50c8d36330bc1', 'network_id': '821f5c2a-7056-48f2-88af-da375dafe4d8', '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': '227057cf4131492ab4e50c8d36330bc1'}} 2025-10-16 04:13:34,138 - INFO - Created VN vn2 2025-10-16 04:13:34,150 - DEBUG - VN vn2 UUID is 821f5c2a-7056-48f2-88af-da375dafe4d8 2025-10-16 04:13:34,235 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-31596472: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-31596472: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-10-16 04:13:34,553 - INFO - Attaching policy sec_grp_policy_ctest-random-64297623 to vn vn1 2025-10-16 04:13:34,957 - INFO - Attaching policy sec_grp_policy_ctest-random-64297623 to vn vn2 2025-10-16 04:13:36,544 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4339-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4339-1) 2025-10-16 04:13:37,874 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4339-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4339-2) 2025-10-16 04:13:37,937 - INFO - ================================================================================ 2025-10-16 04:13:37,938 - INFO - STARTING TEST : test_create_md5_on_control 2025-10-16 04:13:37,939 - INFO - TEST DESCRIPTION : Description: Verify md5 with allow specific protocol on all ports and policy with allow all between VN's 2025-10-16 04:13:39,233 - DEBUG - Skipping xmpp flap check 2025-10-16 04:13:39,233 - INFO - Initial checks done. Running the testcase now 2025-10-16 04:13:39,233 - INFO - 2025-10-16 04:13:39,233 - INFO - config md5 on each host with auth_data None 2025-10-16 04:13:39,233 - INFO - config per peer with auth_data None 2025-10-16 04:13:39,866 - INFO - configure MD5 on host d07b5859-da08-4d1a-8c08-8da8cdd7f03f 2025-10-16 04:13:39,954 - INFO - config per peer with auth_data None 2025-10-16 04:13:40,574 - INFO - configure MD5 on host fe1dde23-ad0d-4c29-b2fe-bd00cd0a28a5 2025-10-16 04:13:40,674 - INFO - config per peer with auth_data None 2025-10-16 04:13:41,279 - INFO - configure MD5 on host 7530c941-0f57-401b-bc56-87c837db5191 2025-10-16 04:13:41,363 - INFO - check if BGP between peers are up before setting md5 2025-10-16 04:13:41,363 - INFO - Check BGP staus between peers 2025-10-16 04:13:41,375 - INFO - setting MD5 config on host d07b5859-da08-4d1a-8c08-8da8cdd7f03f 2025-10-16 04:13:41,375 - INFO - configure MD5 on host d07b5859-da08-4d1a-8c08-8da8cdd7f03f 2025-10-16 04:13:41,454 - INFO - setting MD5 config on host fe1dde23-ad0d-4c29-b2fe-bd00cd0a28a5 2025-10-16 04:13:41,455 - INFO - configure MD5 on host fe1dde23-ad0d-4c29-b2fe-bd00cd0a28a5 2025-10-16 04:13:41,538 - INFO - setting MD5 config on host 7530c941-0f57-401b-bc56-87c837db5191 2025-10-16 04:13:41,538 - INFO - configure MD5 on host 7530c941-0f57-401b-bc56-87c837db5191 2025-10-16 04:13:41,618 - INFO - check if BGP peering is up after md5 config 2025-10-16 04:13:41,618 - INFO - Check BGP staus between peers 2025-10-16 04:13:41,625 - DEBUG - Check the BGP connection on 10.0.0.19 2025-10-16 04:13:41,625 - DEBUG - Check the BGP connection on 10.0.0.19 2025-10-16 04:13:51,626 - INFO - Check BGP staus between peers 2025-10-16 04:13:51,635 - INFO - remove md5 configured on host d07b5859-da08-4d1a-8c08-8da8cdd7f03f 2025-10-16 04:13:51,635 - INFO - config per peer with auth_data None 2025-10-16 04:13:52,170 - INFO - configure MD5 on host d07b5859-da08-4d1a-8c08-8da8cdd7f03f 2025-10-16 04:13:52,258 - INFO - remove md5 configured on host fe1dde23-ad0d-4c29-b2fe-bd00cd0a28a5 2025-10-16 04:13:52,258 - INFO - config per peer with auth_data None 2025-10-16 04:13:52,830 - INFO - configure MD5 on host fe1dde23-ad0d-4c29-b2fe-bd00cd0a28a5 2025-10-16 04:13:52,914 - INFO - remove md5 configured on host 7530c941-0f57-401b-bc56-87c837db5191 2025-10-16 04:13:52,914 - INFO - config per peer with auth_data None 2025-10-16 04:13:53,523 - INFO - configure MD5 on host 7530c941-0f57-401b-bc56-87c837db5191 2025-10-16 04:13:53,602 - INFO - Skip interface_detach for VM vn2_vm_0 in state BUILD 2025-10-16 04:13:53,603 - INFO - Deleting VM vn2_vm_0 2025-10-16 04:13:53,603 - ERROR - VM vn2_vm_0 has failed to come up 2025-10-16 04:13:53,603 - ERROR - Nova failed to get host of the VM 2025-10-16 04:13:53,603 - ERROR - VM vn2_vm_0 has failed to come up 2025-10-16 04:13:53,603 - ERROR - Nova failed to get host of the VM 2025-10-16 04:13:53,676 - INFO - Skip interface_detach for VM vn1_vm_0 in state BUILD 2025-10-16 04:13:53,676 - INFO - Deleting VM vn1_vm_0 2025-10-16 04:13:53,676 - ERROR - VM vn1_vm_0 has failed to come up 2025-10-16 04:13:53,676 - ERROR - Nova failed to get host of the VM 2025-10-16 04:13:53,676 - ERROR - VM vn1_vm_0 has failed to come up 2025-10-16 04:13:53,676 - ERROR - Nova failed to get host of the VM 2025-10-16 04:13:53,745 - INFO - Dettaching policy sec_grp_policy_ctest-random-64297623 from vn vn2 2025-10-16 04:13:53,926 - INFO - Dettaching policy sec_grp_policy_ctest-random-64297623 from vn vn1 2025-10-16 04:13:54,199 - INFO - Deleted policy sec_grp_policy_ctest-random-64297623 2025-10-16 04:13:54,199 - INFO - Deleting VN vn2 2025-10-16 04:13:54,262 - DEBUG - VN 821f5c2a-7056-48f2-88af-da375dafe4d8 still in use: Unable to complete operation on network 821f5c2a-7056-48f2-88af-da375dafe4d8. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-75fc27cb-146c-4d41-8274-204d0145d001'] 2025-10-16 04:13:54,262 - WARNING - Deleting VN vn2 failed..Will retry 2025-10-16 04:13:56,418 - DEBUG - Response for deleting network () 2025-10-16 04:13:56,418 - INFO - Deleting VN vn1 2025-10-16 04:13:56,582 - DEBUG - Response for deleting network () 2025-10-16 04:13:56,582 - INFO - Dettaching policy allow_all from vn test_vnv6dn 2025-10-16 04:13:56,749 - INFO - Dettaching policy allow_all from vn test_vnv6sr 2025-10-16 04:13:56,971 - INFO - Deleted policy allow_all 2025-10-16 04:13:56,971 - INFO - Deleting VM dest_vm 2025-10-16 04:13:57,057 - INFO - Deleting VM source_vm 2025-10-16 04:13:57,135 - INFO - Deleting VN test_vnv6dn 2025-10-16 04:13:57,189 - DEBUG - VN 2f283d15-1453-4b0c-a0e0-91109e7cd666 still in use: Unable to complete operation on network 2f283d15-1453-4b0c-a0e0-91109e7cd666. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-9ec39cb5-7941-4644-bbe7-421872fdfa0a'] 2025-10-16 04:13:57,189 - WARNING - Deleting VN test_vnv6dn failed..Will retry 2025-10-16 04:13:59,366 - DEBUG - Response for deleting network () 2025-10-16 04:13:59,366 - INFO - Deleting VN test_vnv6sr 2025-10-16 04:13:59,591 - DEBUG - Response for deleting network () 2025-10-16 04:14:00,872 - DEBUG - Skipping xmpp flap check
2025-10-16 04:14:00,873 - INFO - END TEST : test_create_md5_on_control : PASSED[0:00:23]
2025-10-16 04:14:00,873 - INFO - -------------------------------------------------------------------------------- 2025-10-16 04:14:01,603 - INFO - Deleted project: ctest-TestMd5testsOnControl-31596472, ID : 227057cf-4131-492a-b4e5-0c8d36330bc1