2026-09-02 14:17:18,425 - INFO - Domain Default found not creating 2026-09-02 14:17:18,631 - INFO - Project ctest-FloatingipBasicTestSanity-19899094 not found, creating it 2026-09-02 14:17:19,191 - INFO - Created Project:ctest-FloatingipBasicTestSanity-19899094, ID : f6649df5-4424-4b92-bb2e-1f729c3b662e 2026-09-02 14:17:21,101 - INFO - Using existing project ['default-domain', 'admin'](d5ed56f2-3f2e-4bdf-940d-eea28713bb18) 2026-09-02 14:17:22,021 - INFO - Adding rules to the default security group in Project admin 2026-09-02 14:17:22,379 - INFO - ================================================================================ 2026-09-02 14:17:22,380 - INFO - STARTING TEST : test_floating_ip 2026-09-02 14:17:22,380 - INFO - TEST DESCRIPTION : Test to validate floating-ip Assignment to a VM. It creates a VM, assigns a FIP to it and pings to a IP in the FIP VN. 2026-09-02 14:17:22,536 - DEBUG - Nothing to compare xmpp stats {'10.0.0.161': {'10.20.0.25': '0'}} with 2026-09-02 14:17:22,536 - INFO - Initial checks done. Running the testcase now 2026-09-02 14:17:22,537 - INFO - 2026-09-02 14:17:22,569 - DEBUG - Services list from nova: [, , ] 2026-09-02 14:17:22,569 - DEBUG - Hosts: {'nova': ['cn-jenkins-deploy-platform-ansible-os-6248-1']} 2026-09-02 14:17:23,306 - DEBUG - Response for create_network : {'network': {'id': '99897fee-ca23-4de4-a41e-25180e3c15d0', 'name': 'ctest-fvn-64533992', 'tenant_id': 'f6649df544244b92bb2e1f729c3b662e', 'project_id': 'f6649df544244b92bb2e1f729c3b662e', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'tags': [], 'subnets': [], 'fq_name': ['default-domain', 'ctest-FloatingipBasicTestSanity-19899094', 'ctest-fvn-64533992'], 'port_security_enabled': True, 'description': ''}} 2026-09-02 14:17:23,498 - DEBUG - Response for create_subnet : {'subnet': {'id': 'b034f28b-693d-4c0d-bafe-ac82dd3a89db', 'name': '', 'tenant_id': 'f6649df544244b92bb2e1f729c3b662e', 'network_id': '99897fee-ca23-4de4-a41e-25180e3c15d0', 'ip_version': 4, 'cidr': '167.111.224.64/26', 'allocation_pools': [{'start': '167.111.224.66', 'end': '167.111.224.126'}], 'gateway_ip': '167.111.224.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '167.111.224.66', 'tags': [], 'project_id': 'f6649df544244b92bb2e1f729c3b662e'}} 2026-09-02 14:17:23,517 - INFO - Created VN ctest-fvn-64533992 2026-09-02 14:17:23,573 - DEBUG - VN ctest-fvn-64533992 UUID is 99897fee-ca23-4de4-a41e-25180e3c15d0 2026-09-02 14:17:23,692 - DEBUG - Requesting: http://10.0.0.161:8082/domains 2026-09-02 14:17:24,091 - DEBUG - Requesting: http://10.0.0.161:8082/domains 2026-09-02 14:17:24,128 - DEBUG - Requesting: http://10.0.0.161:8082/domain/759a9c83-c697-48bb-b428-5713b193674b 2026-09-02 14:17:24,144 - DEBUG - Requesting: http://10.0.0.161:8082/project/f6649df5-4424-4b92-bb2e-1f729c3b662e 2026-09-02 14:17:24,234 - DEBUG - Requesting: http://10.0.0.161:8082/virtual-network/99897fee-ca23-4de4-a41e-25180e3c15d0 2026-09-02 14:17:24,249 - DEBUG - Requesting: http://10.0.0.161:8082/virtual-network/99897fee-ca23-4de4-a41e-25180e3c15d0 2026-09-02 14:17:24,263 - DEBUG - Requesting: http://10.0.0.161:8082/routing-instance/e527a935-f73c-4cfd-9160-1da42b3543ed 2026-09-02 14:17:24,273 - DEBUG - Requesting: http://10.0.0.161:8082/routing-instance/e527a935-f73c-4cfd-9160-1da42b3543ed 2026-09-02 14:17:24,283 - DEBUG - Requesting: http://10.0.0.161:8082/route-target/3c4447d1-1276-4e3e-a503-0c5a94a26372 2026-09-02 14:17:24,294 - DEBUG - Route Targets: ['target:64512:8000004'] 2026-09-02 14:17:24,294 - DEBUG - Requesting: http://10.0.0.161:8082/virtual-network/99897fee-ca23-4de4-a41e-25180e3c15d0 2026-09-02 14:17:24,308 - DEBUG - Requesting: http://10.0.0.161:8082/routing-instance/e527a935-f73c-4cfd-9160-1da42b3543ed 2026-09-02 14:17:24,328 - INFO - Verified VN network id 6 for VN 99897fee-ca23-4de4-a41e-25180e3c15d0 2026-09-02 14:17:24,329 - INFO - Verifications in API Server for VN ctest-fvn-64533992 passed 2026-09-02 14:17:24,329 - DEBUG - Requesting: http://10.0.0.161:8082/virtual-network/99897fee-ca23-4de4-a41e-25180e3c15d0 2026-09-02 14:17:24,341 - DEBUG - Requesting: http://10.0.0.161:8082/routing-instance/e527a935-f73c-4cfd-9160-1da42b3543ed 2026-09-02 14:17:24,351 - DEBUG - Requesting: http://10.0.0.161:8082/routing-instance/e527a935-f73c-4cfd-9160-1da42b3543ed 2026-09-02 14:17:24,361 - DEBUG - Requesting: http://10.0.0.161:8082/route-target/3c4447d1-1276-4e3e-a503-0c5a94a26372 2026-09-02 14:17:24,381 - DEBUG - Control-node 10.0.0.161 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-FloatingipBasicTestSanity-19899094:ctest-fvn-64533992', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-FloatingipBasicTestSanity-19899094', 'owner-access': '7', 'group': 'manager', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11063514623182589412', 'uuid-lslong': '11825930456769762768'}, 'enable': 'true', 'created': '2026-09-02T14:17:23', 'last-modified': '2026-09-02T14:17:23', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.926186'} 2026-09-02 14:17:24,392 - DEBUG - Route Targets: ['target:64512:8000004'] 2026-09-02 14:17:24,392 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-fvn-64533992 passed 2026-09-02 14:17:24,392 - DEBUG - ====Verifying policy data for ctest-fvn-64533992 in API_Server ====== 2026-09-02 14:17:24,393 - DEBUG - Requesting: http://10.0.0.161:8082/domains 2026-09-02 14:17:24,404 - DEBUG - Requesting: http://10.0.0.161:8082/domain/759a9c83-c697-48bb-b428-5713b193674b 2026-09-02 14:17:24,420 - DEBUG - Requesting: http://10.0.0.161:8082/project/f6649df5-4424-4b92-bb2e-1f729c3b662e 2026-09-02 14:17:24,533 - DEBUG - Requesting: http://10.0.0.161:8082/virtual-network/99897fee-ca23-4de4-a41e-25180e3c15d0 2026-09-02 14:17:24,546 - DEBUG - =>VN ctest-fvn-64533992 has no policy to be verified 2026-09-02 14:17:24,546 - DEBUG - Verifying the vn in opserver 2026-09-02 14:17:24,546 - DEBUG - Verifying the default-domain:ctest-FloatingipBasicTestSanity-19899094:ctest-fvn-64533992 virtual network link through opserver 10.0.0.161 2026-09-02 14:17:24,546 - DEBUG - Requesting: http://10.0.0.161:8081/analytics/uves/virtual-networks 2026-09-02 14:17:24,938 - DEBUG - Requesting: http://10.0.0.161:8081/analytics/uves/virtual-networks 2026-09-02 14:17:25,006 - DEBUG - vn link and name as {'name': 'default-domain:ctest-FloatingipBasicTestSanity-19899094:ctest-fvn-64533992', 'href': 'http://10.0.0.161:8081/analytics/uves/virtual-network/default-domain:ctest-FloatingipBasicTestSanity-19899094:ctest-fvn-64533992?flat'} 2026-09-02 14:17:25,006 - INFO - Validated that VN default-domain:ctest-FloatingipBasicTestSanity-19899094:ctest-fvn-64533992 is found in opserver 2026-09-02 14:17:25,022 - DEBUG - Do not have enough data to verify VN in agent 2026-09-02 14:17:25,028 - DEBUG - VRF ids for VN ctest-fvn-64533992: {} 2026-09-02 14:17:25,248 - DEBUG - Response for create_network : {'network': {'id': '5e27dd33-58d3-4dd1-8eff-abc10d1f53c2', 'name': 'ctest-vn1-82547426', 'tenant_id': 'f6649df544244b92bb2e1f729c3b662e', 'project_id': 'f6649df544244b92bb2e1f729c3b662e', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'tags': [], 'subnets': [], 'fq_name': ['default-domain', 'ctest-FloatingipBasicTestSanity-19899094', 'ctest-vn1-82547426'], 'port_security_enabled': True, 'description': ''}} 2026-09-02 14:17:25,469 - DEBUG - Response for create_subnet : {'subnet': {'id': 'bea00c19-85c0-4386-9864-8c7b9f3c480c', 'name': '', 'tenant_id': 'f6649df544244b92bb2e1f729c3b662e', 'network_id': '5e27dd33-58d3-4dd1-8eff-abc10d1f53c2', 'ip_version': 4, 'cidr': '98.70.163.0/26', 'allocation_pools': [{'start': '98.70.163.2', 'end': '98.70.163.62'}], 'gateway_ip': '98.70.163.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '98.70.163.2', 'tags': [], 'project_id': 'f6649df544244b92bb2e1f729c3b662e'}} 2026-09-02 14:17:25,491 - INFO - Created VN ctest-vn1-82547426 2026-09-02 14:17:25,545 - DEBUG - VN ctest-vn1-82547426 UUID is 5e27dd33-58d3-4dd1-8eff-abc10d1f53c2 2026-09-02 14:17:25,663 - DEBUG - Requesting: http://10.0.0.161:8082/domains 2026-09-02 14:17:25,671 - DEBUG - Requesting: http://10.0.0.161:8082/domain/759a9c83-c697-48bb-b428-5713b193674b 2026-09-02 14:17:25,684 - DEBUG - Requesting: http://10.0.0.161:8082/project/f6649df5-4424-4b92-bb2e-1f729c3b662e 2026-09-02 14:17:25,767 - DEBUG - Requesting: http://10.0.0.161:8082/virtual-network/5e27dd33-58d3-4dd1-8eff-abc10d1f53c2 2026-09-02 14:17:25,781 - DEBUG - Requesting: http://10.0.0.161:8082/virtual-network/5e27dd33-58d3-4dd1-8eff-abc10d1f53c2 2026-09-02 14:17:25,793 - DEBUG - Requesting: http://10.0.0.161:8082/routing-instance/14727825-2b1e-45f6-8593-39a72c56e651 2026-09-02 14:17:25,804 - DEBUG - Requesting: http://10.0.0.161:8082/routing-instance/14727825-2b1e-45f6-8593-39a72c56e651 2026-09-02 14:17:25,812 - DEBUG - Requesting: http://10.0.0.161:8082/route-target/ed82dc7a-9e06-42f5-9d28-8a494357bfd1 2026-09-02 14:17:25,820 - DEBUG - Route Targets: ['target:64512:8000005'] 2026-09-02 14:17:25,820 - DEBUG - Requesting: http://10.0.0.161:8082/virtual-network/5e27dd33-58d3-4dd1-8eff-abc10d1f53c2 2026-09-02 14:17:25,831 - DEBUG - Requesting: http://10.0.0.161:8082/routing-instance/14727825-2b1e-45f6-8593-39a72c56e651 2026-09-02 14:17:25,848 - INFO - Verified VN network id 7 for VN 5e27dd33-58d3-4dd1-8eff-abc10d1f53c2 2026-09-02 14:17:25,849 - INFO - Verifications in API Server for VN ctest-vn1-82547426 passed 2026-09-02 14:17:25,849 - DEBUG - Requesting: http://10.0.0.161:8082/virtual-network/5e27dd33-58d3-4dd1-8eff-abc10d1f53c2 2026-09-02 14:17:25,861 - DEBUG - Requesting: http://10.0.0.161:8082/routing-instance/14727825-2b1e-45f6-8593-39a72c56e651 2026-09-02 14:17:25,869 - DEBUG - Requesting: http://10.0.0.161:8082/routing-instance/14727825-2b1e-45f6-8593-39a72c56e651 2026-09-02 14:17:25,879 - DEBUG - Requesting: http://10.0.0.161:8082/route-target/ed82dc7a-9e06-42f5-9d28-8a494357bfd1 2026-09-02 14:17:25,894 - DEBUG - Control-node 10.0.0.161 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-FloatingipBasicTestSanity-19899094:ctest-vn1-82547426', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-FloatingipBasicTestSanity-19899094', 'owner-access': '7', 'group': 'manager', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6784634576260255185', 'uuid-lslong': '10304143318084178882'}, 'enable': 'true', 'created': '2026-09-02T14:17:25', 'last-modified': '2026-09-02T14:17:25', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.470834'} 2026-09-02 14:17:25,902 - DEBUG - Route Targets: ['target:64512:8000005'] 2026-09-02 14:17:25,902 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn1-82547426 passed 2026-09-02 14:17:25,903 - DEBUG - ====Verifying policy data for ctest-vn1-82547426 in API_Server ====== 2026-09-02 14:17:25,903 - DEBUG - Requesting: http://10.0.0.161:8082/domains 2026-09-02 14:17:25,912 - DEBUG - Requesting: http://10.0.0.161:8082/domain/759a9c83-c697-48bb-b428-5713b193674b 2026-09-02 14:17:25,926 - DEBUG - Requesting: http://10.0.0.161:8082/project/f6649df5-4424-4b92-bb2e-1f729c3b662e 2026-09-02 14:17:26,008 - DEBUG - Requesting: http://10.0.0.161:8082/virtual-network/5e27dd33-58d3-4dd1-8eff-abc10d1f53c2 2026-09-02 14:17:26,019 - DEBUG - =>VN ctest-vn1-82547426 has no policy to be verified 2026-09-02 14:17:26,020 - DEBUG - Verifying the vn in opserver 2026-09-02 14:17:26,020 - DEBUG - Verifying the default-domain:ctest-FloatingipBasicTestSanity-19899094:ctest-vn1-82547426 virtual network link through opserver 10.0.0.161 2026-09-02 14:17:26,020 - DEBUG - Requesting: http://10.0.0.161:8081/analytics/uves/virtual-networks 2026-09-02 14:17:26,032 - DEBUG - vn link and name as {'name': 'default-domain:ctest-FloatingipBasicTestSanity-19899094:ctest-vn1-82547426', 'href': 'http://10.0.0.161:8081/analytics/uves/virtual-network/default-domain:ctest-FloatingipBasicTestSanity-19899094:ctest-vn1-82547426?flat'} 2026-09-02 14:17:26,032 - INFO - Validated that VN default-domain:ctest-FloatingipBasicTestSanity-19899094:ctest-vn1-82547426 is found in opserver 2026-09-02 14:17:26,043 - DEBUG - Do not have enough data to verify VN in agent 2026-09-02 14:17:26,051 - DEBUG - VRF ids for VN ctest-vn1-82547426: {} 2026-09-02 14:17:26,483 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-6248-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-6248-1) 2026-09-02 14:17:26,943 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-6248-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-6248-1) 2026-09-02 14:17:27,133 - INFO - Creating Floating IP pool ctest-some-pool-63084477 in API Server 2026-09-02 14:17:27,361 - DEBUG - Requesting: http://10.0.0.161:8082/domains 2026-09-02 14:17:27,369 - DEBUG - Requesting: http://10.0.0.161:8082/domain/759a9c83-c697-48bb-b428-5713b193674b 2026-09-02 14:17:27,383 - DEBUG - Requesting: http://10.0.0.161:8082/project/f6649df5-4424-4b92-bb2e-1f729c3b662e 2026-09-02 14:17:27,470 - DEBUG - Requesting: http://10.0.0.161:8082/virtual-network/99897fee-ca23-4de4-a41e-25180e3c15d0 2026-09-02 14:17:27,484 - DEBUG - Requesting: http://10.0.0.161:8082/floating-ip-pool/af1ec26f-657d-436d-ac8f-9be47831602e 2026-09-02 14:17:27,492 - DEBUG - Requesting: http://10.0.0.161:8082/domains 2026-09-02 14:17:27,498 - DEBUG - Requesting: http://10.0.0.161:8082/domain/759a9c83-c697-48bb-b428-5713b193674b 2026-09-02 14:17:27,510 - DEBUG - Requesting: http://10.0.0.161:8082/project/f6649df5-4424-4b92-bb2e-1f729c3b662e 2026-09-02 14:17:27,587 - DEBUG - Requesting: http://10.0.0.161:8082/virtual-network/99897fee-ca23-4de4-a41e-25180e3c15d0 2026-09-02 14:17:27,599 - DEBUG - FIP Pool ctest-some-pool-63084477 found in API Server 2026-09-02 14:17:27,599 - INFO - Verification for FIP pool ctest-some-pool-63084477 in API Server passed 2026-09-02 14:17:27,617 - DEBUG - Control-node Ifmap-view has FIP pool ctest-some-pool-63084477 information 2026-09-02 14:17:27,618 - INFO - Verification for FIP pool ctest-some-pool-63084477 in Control-node passed 2026-09-02 14:17:27,618 - INFO - Waiting for VM ctest-vn1_vm1_name-33564897 to be up.. 2026-09-02 14:17:27,675 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-02 14:17:32,779 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-02 14:17:37,865 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-02 14:17:42,976 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-02 14:17:48,067 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-02 14:17:53,169 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-02 14:17:58,259 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-02 14:18:03,356 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-02 14:18:08,458 - DEBUG - VM is in ACTIVE state now 2026-09-02 14:18:08,458 - INFO - VM name : ctest-vn1_vm1_name-33564897 2026-09-02 14:18:08,552 - DEBUG - VM ctest-vn1_vm1_name-33564897 ID is 1bdeaecb-79f2-45ea-9332-e16473aa26cb 2026-09-02 14:18:08,575 - DEBUG - VM ctest-vn1_vm1_name-33564897 launched on Node cn-jenkins-deploy-platform-ansible-os-6248-1 2026-09-02 14:18:08,666 - DEBUG - Requesting: http://10.0.0.161:8082/virtual-machine/1bdeaecb-79f2-45ea-9332-e16473aa26cb 2026-09-02 14:18:08,676 - DEBUG - Requesting: http://10.0.0.161:8082/virtual-machine-interface/0129d4ac-6f2c-4de5-90ac-87552bd7ee2b 2026-09-02 14:18:11,946 - 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 1015ms') 2026-09-02 14:18:11,947 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1_vm1_name-33564897 failed! 2026-09-02 14:18:12,009 - DEBUG - Gateway for vn default-domain:ctest-FloatingipBasicTestSanity-19899094:ctest-vn1-82547426 is 98.70.163.1 and allocation pool is NOT set 2026-09-02 14:18:16,035 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=5.91 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 1 received, 50% packet loss, time 1007ms\r\nrtt min/avg/max/mdev = 5.914/5.914/5.914/0.000 ms') 2026-09-02 14:18:16,035 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1_vm1_name-33564897 passed 2026-09-02 14:18:16,197 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-09-02 14:18:16,197 - DEBUG - Waiting to SSH to VM ctest-vn1_vm1_name-33564897, IP 98.70.163.3, Port 22 2026-09-02 14:18:16,222 - 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': ''} 2026-09-02 14:18:16,342 - DEBUG - VM ctest-vn1_vm1_name-33564897 is NOT ready for SSH connections, VM status: ACTIVE 2026-09-02 14:18:21,343 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-09-02 14:18:21,344 - DEBUG - Waiting to SSH to VM ctest-vn1_vm1_name-33564897, IP 98.70.163.3, Port 22 2026-09-02 14:18:21,434 - DEBUG - VM ctest-vn1_vm1_name-33564897 is ready for SSH connections 2026-09-02 14:18:21,589 - DEBUG - Created Floating IP : ('167.111.224.68', 'a05bb25d-a8d4-4c00-9db4-5d40bccf71f7') 2026-09-02 14:18:21,589 - DEBUG - Associating FIP 167.111.224.68 to 1bdeaecb-79f2-45ea-9332-e16473aa26cb 2026-09-02 14:18:21,623 - DEBUG - Associating FIP ID a05bb25d-a8d4-4c00-9db4-5d40bccf71f7 with Port ID 0129d4ac-6f2c-4de5-90ac-87552bd7ee2b 2026-09-02 14:18:21,704 - INFO - Waiting for VM ctest-fvn_vm1_name-47686541 to be up.. 2026-09-02 14:18:21,795 - DEBUG - VM is in ACTIVE state now 2026-09-02 14:18:21,795 - INFO - VM name : ctest-fvn_vm1_name-47686541 2026-09-02 14:18:21,899 - DEBUG - VM ctest-fvn_vm1_name-47686541 ID is 23f2a593-5ec6-48b7-ab48-ab36edc0f780 2026-09-02 14:18:21,899 - DEBUG - VM ctest-fvn_vm1_name-47686541 launched on Node cn-jenkins-deploy-platform-ansible-os-6248-1 2026-09-02 14:18:21,988 - DEBUG - Requesting: http://10.0.0.161:8082/virtual-machine/23f2a593-5ec6-48b7-ab48-ab36edc0f780 2026-09-02 14:18:21,999 - DEBUG - Requesting: http://10.0.0.161:8082/virtual-machine-interface/26ffc974-4ac9-41d6-aa8e-d0d3b994d55e 2026-09-02 14:18:23,206 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n64 bytes from 169.254.0.4: icmp_seq=1 ttl=63 time=6.33 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=1.30 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 1.300/3.815/6.330/2.515 ms') 2026-09-02 14:18:23,206 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-fvn_vm1_name-47686541 passed 2026-09-02 14:18:23,361 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-09-02 14:18:23,361 - DEBUG - Waiting to SSH to VM ctest-fvn_vm1_name-47686541, IP 167.111.224.67, Port 22 2026-09-02 14:18:23,449 - DEBUG - VM ctest-fvn_vm1_name-47686541 is ready for SSH connections 2026-09-02 14:18:23,610 - INFO - FIP 167.111.224.68 verification for passed on all Control-nodes 2026-09-02 14:18:23,611 - INFO - FIP ID a05bb25d-a8d4-4c00-9db4-5d40bccf71f7(167.111.224.68) validation in Control node passed 2026-09-02 14:18:23,637 - DEBUG - agent_label query returned:{'nh': {'type': 'interface', 'ref_count': '12', 'valid': 'true', 'policy': 'enabled', 'itf': 'tap0129d4ac-6f', 'mac': ['2:1:29:d4:ac:6f'], 'mcast': 'disabled', 'nh_index': '27', 'vxlan_flag': 'false', 'intf_flags': '1', 'isid': '0', 'learning_enabled': 'false', 'etree_leaf': 'false', 'layer2_control_word': 'false', 'crypt_all_traffic': 'false', 'crypt_path_available': 'false', 'crypt_interface': None}, 'label': '27', 'vxlan_id': '0', 'peer': '10.20.0.25', 'dest_vn_list': ['default-domain:ctest-FloatingipBasicTestSanity-19899094:ctest-fvn-64533992'], 'unresolved': 'false', 'sg_list': ['8000004'], 'supported_tunnel_type': 'MPLSoGRE MPLSoUDP ', 'active_tunnel_type': 'MPLSoUDP', 'stale': 'false', 'path_preference_data': {'sequence': '0', 'preference': '200', 'ecmp': 'true'}, 'active_label': '27', 'ecmp_hashing_fields': 'l3-source-address,l3-destination-address,l4-protocol,l4-source-port,l4-destination-port,', 'communities': None, 'peer_sequence_number': '1', 'etree_leaf': 'false', 'layer2_control_word': 'false', 'tag_list': None, 'inactive': 'false', 'origin_vn': None} 2026-09-02 14:18:23,637 - DEBUG - Route for FIP IP 167.111.224.68 is present in agent 10.0.0.161 2026-09-02 14:18:23,637 - DEBUG - FIP 167.111.224.68 verification for VM ctest-vn1_vm1_name-33564897 in Agent 10.0.0.161 passed 2026-09-02 14:18:23,637 - INFO - FIP ID a05bb25d-a8d4-4c00-9db4-5d40bccf71f7(167.111.224.68) validation in agents passed 2026-09-02 14:18:23,637 - DEBUG - Requesting: http://10.0.0.161:8082/floating-ip/a05bb25d-a8d4-4c00-9db4-5d40bccf71f7 2026-09-02 14:18:23,650 - DEBUG - FIP a05bb25d-a8d4-4c00-9db4-5d40bccf71f7 is present in API server 2026-09-02 14:18:23,650 - INFO - FIP ID a05bb25d-a8d4-4c00-9db4-5d40bccf71f7(167.111.224.68) validation in API Server passed 2026-09-02 14:18:23,650 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-09-02 14:18:23,650 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 167.111.224.67, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.161, gateway password: c0ntrail123 2026-09-02 14:18:23,650 - DEBUG - ping -s 56 -c 3 -W 1 167.111.224.67 2026-09-02 14:18:26,836 - DEBUG - PING 167.111.224.67 (167.111.224.67): 56 data bytes 64 bytes from 167.111.224.67: seq=0 ttl=63 time=5.370 ms 64 bytes from 167.111.224.67: seq=1 ttl=63 time=1.152 ms 64 bytes from 167.111.224.67: seq=2 ttl=63 time=1.019 ms --- 167.111.224.67 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 1.019/2.513/5.370 ms 2026-09-02 14:18:26,836 - INFO - Ping to IP 167.111.224.67 from VM ctest-vn1_vm1_name-33564897 passed 2026-09-02 14:18:26,836 - DEBUG - Disassociating port from FIP ID : a05bb25d-a8d4-4c00-9db4-5d40bccf71f7 2026-09-02 14:18:26,906 - DEBUG - Deleting FIP ID a05bb25d-a8d4-4c00-9db4-5d40bccf71f7 2026-09-02 14:18:26,977 - INFO - Deleting the FIP pool ctest-some-pool-63084477 2026-09-02 14:18:27,085 - DEBUG - Control-node Ifmap-view does not have FIP pool ctest-some-pool-63084477 information 2026-09-02 14:18:27,085 - INFO - Deleting VM ctest-fvn_vm1_name-47686541 2026-09-02 14:18:27,190 - INFO - Deleting VM ctest-vn1_vm1_name-33564897 2026-09-02 14:18:27,292 - INFO - Deleting VN ctest-vn1-82547426 2026-09-02 14:18:27,337 - DEBUG - VN 5e27dd33-58d3-4dd1-8eff-abc10d1f53c2 still in use: Unable to complete operation on network 5e27dd33-58d3-4dd1-8eff-abc10d1f53c2. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-ce05b983-1c20-46ba-9933-cbd2ab9b2535'] 2026-09-02 14:18:27,337 - WARNING - Deleting VN ctest-vn1-82547426 failed..Will retry 2026-09-02 14:18:29,425 - DEBUG - VN 5e27dd33-58d3-4dd1-8eff-abc10d1f53c2 still in use: Unable to complete operation on network 5e27dd33-58d3-4dd1-8eff-abc10d1f53c2. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-11d2a437-36f6-42d9-946a-bd32e7ec44d9'] 2026-09-02 14:18:29,426 - WARNING - Deleting VN ctest-vn1-82547426 failed..Will retry 2026-09-02 14:18:31,596 - DEBUG - Response for deleting network () 2026-09-02 14:18:31,597 - DEBUG - Requesting: http://10.0.0.161:8082/routing-instance/14727825-2b1e-45f6-8593-39a72c56e651 2026-09-02 14:18:31,605 - DEBUG - Response Code: 404 2026-09-02 14:18:31,606 - DEBUG - Requesting: http://10.0.0.161:8082/domains 2026-09-02 14:18:31,616 - DEBUG - Requesting: http://10.0.0.161:8082/domain/759a9c83-c697-48bb-b428-5713b193674b 2026-09-02 14:18:31,674 - DEBUG - Requesting: http://10.0.0.161:8082/project/f6649df5-4424-4b92-bb2e-1f729c3b662e 2026-09-02 14:18:31,755 - INFO - Validated that VN ctest-vn1-82547426 is not found in API Server 2026-09-02 14:18:31,768 - DEBUG - VN ctest-vn1-82547426 is not present in Agent 10.0.0.161 2026-09-02 14:18:31,768 - INFO - Validated that VN ctest-vn1-82547426 is not in any agent 2026-09-02 14:18:31,786 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn1-82547426 info 2026-09-02 14:18:31,786 - INFO - Deleting VN ctest-fvn-64533992 2026-09-02 14:18:31,941 - DEBUG - Response for deleting network () 2026-09-02 14:18:31,941 - DEBUG - Requesting: http://10.0.0.161:8082/routing-instance/e527a935-f73c-4cfd-9160-1da42b3543ed 2026-09-02 14:18:31,947 - DEBUG - Response Code: 404 2026-09-02 14:18:31,947 - DEBUG - Requesting: http://10.0.0.161:8082/domains 2026-09-02 14:18:31,958 - DEBUG - Requesting: http://10.0.0.161:8082/domain/759a9c83-c697-48bb-b428-5713b193674b 2026-09-02 14:18:32,016 - DEBUG - Requesting: http://10.0.0.161:8082/project/f6649df5-4424-4b92-bb2e-1f729c3b662e 2026-09-02 14:18:32,105 - INFO - Validated that VN ctest-fvn-64533992 is not found in API Server 2026-09-02 14:18:32,120 - DEBUG - VN ctest-fvn-64533992 is not present in Agent 10.0.0.161 2026-09-02 14:18:32,120 - INFO - Validated that VN ctest-fvn-64533992 is not in any agent 2026-09-02 14:18:32,141 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-fvn-64533992 info 2026-09-02 14:18:32,419 - DEBUG - No XMPP flaps were noticed during the test
2026-09-02 14:18:32,419 - INFO - END TEST : test_floating_ip : PASSED[0:01:10]
2026-09-02 14:18:32,420 - INFO - -------------------------------------------------------------------------------- 2026-09-02 14:18:33,261 - INFO - Deleted project: ctest-FloatingipBasicTestSanity-19899094, ID : f6649df5-4424-4b92-bb2e-1f729c3b662e