2026-03-05 19:41:28,773 - INFO - Domain Default found not creating 2026-03-05 19:41:28,958 - INFO - Project ctest-FloatingipBasicTestSanity-77261896 not found, creating it 2026-03-05 19:41:29,446 - INFO - Created Project:ctest-FloatingipBasicTestSanity-77261896, ID : 20c91cca-f58a-4c7b-92e3-fcc01a2db2b0 2026-03-05 19:41:30,996 - INFO - Using existing project ['default-domain', 'admin'](f923d90d-36d8-44bd-9d56-c42792b58a7f) 2026-03-05 19:41:31,843 - INFO - Adding rules to the default security group in Project admin 2026-03-05 19:41:32,189 - INFO - ================================================================================ 2026-03-05 19:41:32,189 - INFO - STARTING TEST : test_floating_ip 2026-03-05 19:41:32,189 - 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-03-05 19:41:32,333 - DEBUG - Nothing to compare xmpp stats {'10.0.0.27': {'10.20.0.254': '0'}} with 2026-03-05 19:41:32,333 - INFO - Initial checks done. Running the testcase now 2026-03-05 19:41:32,333 - INFO - 2026-03-05 19:41:32,361 - DEBUG - Services list from nova: [, , ] 2026-03-05 19:41:32,361 - DEBUG - Hosts: {'nova': ['cn-jenkins-deploy-platform-ansible-os-5446-1']} 2026-03-05 19:41:32,987 - DEBUG - Response for create_network : {'network': {'id': '302861da-0018-4b65-92dc-c0715a80a7a8', 'name': 'ctest-fvn-16207526', 'tenant_id': '20c91ccaf58a4c7b92e3fcc01a2db2b0', 'project_id': '20c91ccaf58a4c7b92e3fcc01a2db2b0', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-FloatingipBasicTestSanity-77261896', 'ctest-fvn-16207526'], 'port_security_enabled': True, 'description': ''}} 2026-03-05 19:41:33,183 - DEBUG - Response for create_subnet : {'subnet': {'id': 'b2f8be56-2c46-4c61-bfad-b6cb507dff1a', 'name': '', 'tenant_id': '20c91ccaf58a4c7b92e3fcc01a2db2b0', 'network_id': '302861da-0018-4b65-92dc-c0715a80a7a8', 'ip_version': 4, 'cidr': '13.9.53.64/26', 'allocation_pools': [{'start': '13.9.53.66', 'end': '13.9.53.126'}], 'gateway_ip': '13.9.53.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '13.9.53.66', 'tags': [], 'project_id': '20c91ccaf58a4c7b92e3fcc01a2db2b0'}} 2026-03-05 19:41:33,207 - INFO - Created VN ctest-fvn-16207526 2026-03-05 19:41:33,259 - DEBUG - VN ctest-fvn-16207526 UUID is 302861da-0018-4b65-92dc-c0715a80a7a8 2026-03-05 19:41:33,367 - DEBUG - Requesting: http://10.0.0.27:8082/domains 2026-03-05 19:41:33,675 - DEBUG - Requesting: http://10.0.0.27:8082/domains 2026-03-05 19:41:33,709 - DEBUG - Requesting: http://10.0.0.27:8082/domain/c0a9362f-d5c3-44ab-b0d0-a4a177b3e40d 2026-03-05 19:41:33,722 - DEBUG - Requesting: http://10.0.0.27:8082/project/20c91cca-f58a-4c7b-92e3-fcc01a2db2b0 2026-03-05 19:41:33,800 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/302861da-0018-4b65-92dc-c0715a80a7a8 2026-03-05 19:41:33,813 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/302861da-0018-4b65-92dc-c0715a80a7a8 2026-03-05 19:41:33,823 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/d9647d27-a2e6-455b-8a37-2a491204198e 2026-03-05 19:41:33,830 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/d9647d27-a2e6-455b-8a37-2a491204198e 2026-03-05 19:41:33,839 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/05495136-a947-488c-abeb-2366be3ba7c0 2026-03-05 19:41:33,846 - DEBUG - Route Targets: ['target:64512:8000004'] 2026-03-05 19:41:33,846 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/302861da-0018-4b65-92dc-c0715a80a7a8 2026-03-05 19:41:33,856 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/d9647d27-a2e6-455b-8a37-2a491204198e 2026-03-05 19:41:33,872 - INFO - Verified VN network id 6 for VN 302861da-0018-4b65-92dc-c0715a80a7a8 2026-03-05 19:41:33,872 - INFO - Verifications in API Server for VN ctest-fvn-16207526 passed 2026-03-05 19:41:33,872 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/302861da-0018-4b65-92dc-c0715a80a7a8 2026-03-05 19:41:33,883 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/d9647d27-a2e6-455b-8a37-2a491204198e 2026-03-05 19:41:33,893 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/d9647d27-a2e6-455b-8a37-2a491204198e 2026-03-05 19:41:33,903 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/05495136-a947-488c-abeb-2366be3ba7c0 2026-03-05 19:41:33,918 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-FloatingipBasicTestSanity-77261896:ctest-fvn-16207526', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-FloatingipBasicTestSanity-77261896', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3470131101821324133', 'uuid-lslong': '10582544817496041384'}, 'enable': 'true', 'created': '2026-03-05T19:41:32', 'last-modified': '2026-03-05T19:41:33', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.770147'} 2026-03-05 19:41:33,925 - DEBUG - Route Targets: ['target:64512:8000004'] 2026-03-05 19:41:33,925 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-fvn-16207526 passed 2026-03-05 19:41:33,925 - DEBUG - ====Verifying policy data for ctest-fvn-16207526 in API_Server ====== 2026-03-05 19:41:33,925 - DEBUG - Requesting: http://10.0.0.27:8082/domains 2026-03-05 19:41:33,932 - DEBUG - Requesting: http://10.0.0.27:8082/domain/c0a9362f-d5c3-44ab-b0d0-a4a177b3e40d 2026-03-05 19:41:33,945 - DEBUG - Requesting: http://10.0.0.27:8082/project/20c91cca-f58a-4c7b-92e3-fcc01a2db2b0 2026-03-05 19:41:34,018 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/302861da-0018-4b65-92dc-c0715a80a7a8 2026-03-05 19:41:34,027 - DEBUG - =>VN ctest-fvn-16207526 has no policy to be verified 2026-03-05 19:41:34,028 - DEBUG - Verifying the vn in opserver 2026-03-05 19:41:34,028 - DEBUG - Verifying the default-domain:ctest-FloatingipBasicTestSanity-77261896:ctest-fvn-16207526 virtual network link through opserver 10.0.0.27 2026-03-05 19:41:34,028 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks 2026-03-05 19:41:34,327 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks 2026-03-05 19:41:34,402 - DEBUG - vn link and name as {'name': 'default-domain:ctest-FloatingipBasicTestSanity-77261896:ctest-fvn-16207526', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-FloatingipBasicTestSanity-77261896:ctest-fvn-16207526?flat'} 2026-03-05 19:41:34,402 - INFO - Validated that VN default-domain:ctest-FloatingipBasicTestSanity-77261896:ctest-fvn-16207526 is found in opserver 2026-03-05 19:41:34,413 - DEBUG - Do not have enough data to verify VN in agent 2026-03-05 19:41:34,418 - DEBUG - VRF ids for VN ctest-fvn-16207526: {} 2026-03-05 19:41:34,634 - DEBUG - Response for create_network : {'network': {'id': '3c82cf61-4704-43e8-b235-ca8b56e737c0', 'name': 'ctest-vn1-37910502', 'tenant_id': '20c91ccaf58a4c7b92e3fcc01a2db2b0', 'project_id': '20c91ccaf58a4c7b92e3fcc01a2db2b0', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-FloatingipBasicTestSanity-77261896', 'ctest-vn1-37910502'], 'port_security_enabled': True, 'description': ''}} 2026-03-05 19:41:34,884 - DEBUG - Response for create_subnet : {'subnet': {'id': '2ca05237-6a62-4f18-a7da-d46b5a7de452', 'name': '', 'tenant_id': '20c91ccaf58a4c7b92e3fcc01a2db2b0', 'network_id': '3c82cf61-4704-43e8-b235-ca8b56e737c0', 'ip_version': 4, 'cidr': '218.24.171.192/26', 'allocation_pools': [{'start': '218.24.171.194', 'end': '218.24.171.254'}], 'gateway_ip': '218.24.171.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '218.24.171.194', 'tags': [], 'project_id': '20c91ccaf58a4c7b92e3fcc01a2db2b0'}} 2026-03-05 19:41:34,903 - INFO - Created VN ctest-vn1-37910502 2026-03-05 19:41:34,955 - DEBUG - VN ctest-vn1-37910502 UUID is 3c82cf61-4704-43e8-b235-ca8b56e737c0 2026-03-05 19:41:35,067 - DEBUG - Requesting: http://10.0.0.27:8082/domains 2026-03-05 19:41:35,074 - DEBUG - Requesting: http://10.0.0.27:8082/domain/c0a9362f-d5c3-44ab-b0d0-a4a177b3e40d 2026-03-05 19:41:35,087 - DEBUG - Requesting: http://10.0.0.27:8082/project/20c91cca-f58a-4c7b-92e3-fcc01a2db2b0 2026-03-05 19:41:35,167 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/3c82cf61-4704-43e8-b235-ca8b56e737c0 2026-03-05 19:41:35,179 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/3c82cf61-4704-43e8-b235-ca8b56e737c0 2026-03-05 19:41:35,190 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/2a2658e9-49b0-4f6e-8cdd-a56a36ed850d 2026-03-05 19:41:35,198 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/2a2658e9-49b0-4f6e-8cdd-a56a36ed850d 2026-03-05 19:41:35,205 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/7ed25a67-ff56-473b-a7e2-628ded7bedcf 2026-03-05 19:41:35,213 - DEBUG - Route Targets: ['target:64512:8000005'] 2026-03-05 19:41:35,213 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/3c82cf61-4704-43e8-b235-ca8b56e737c0 2026-03-05 19:41:35,224 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/2a2658e9-49b0-4f6e-8cdd-a56a36ed850d 2026-03-05 19:41:35,238 - INFO - Verified VN network id 7 for VN 3c82cf61-4704-43e8-b235-ca8b56e737c0 2026-03-05 19:41:35,239 - INFO - Verifications in API Server for VN ctest-vn1-37910502 passed 2026-03-05 19:41:35,239 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/3c82cf61-4704-43e8-b235-ca8b56e737c0 2026-03-05 19:41:35,249 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/2a2658e9-49b0-4f6e-8cdd-a56a36ed850d 2026-03-05 19:41:35,256 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/2a2658e9-49b0-4f6e-8cdd-a56a36ed850d 2026-03-05 19:41:35,264 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/7ed25a67-ff56-473b-a7e2-628ded7bedcf 2026-03-05 19:41:35,278 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-FloatingipBasicTestSanity-77261896:ctest-vn1-37910502', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-FloatingipBasicTestSanity-77261896', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4360275405958300648', 'uuid-lslong': '12841392612324095936'}, 'enable': 'true', 'created': '2026-03-05T19:41:34', 'last-modified': '2026-03-05T19:41:34', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.429968'} 2026-03-05 19:41:35,286 - DEBUG - Route Targets: ['target:64512:8000005'] 2026-03-05 19:41:35,287 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn1-37910502 passed 2026-03-05 19:41:35,287 - DEBUG - ====Verifying policy data for ctest-vn1-37910502 in API_Server ====== 2026-03-05 19:41:35,287 - DEBUG - Requesting: http://10.0.0.27:8082/domains 2026-03-05 19:41:35,294 - DEBUG - Requesting: http://10.0.0.27:8082/domain/c0a9362f-d5c3-44ab-b0d0-a4a177b3e40d 2026-03-05 19:41:35,307 - DEBUG - Requesting: http://10.0.0.27:8082/project/20c91cca-f58a-4c7b-92e3-fcc01a2db2b0 2026-03-05 19:41:35,380 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/3c82cf61-4704-43e8-b235-ca8b56e737c0 2026-03-05 19:41:35,389 - DEBUG - =>VN ctest-vn1-37910502 has no policy to be verified 2026-03-05 19:41:35,389 - DEBUG - Verifying the vn in opserver 2026-03-05 19:41:35,389 - DEBUG - Verifying the default-domain:ctest-FloatingipBasicTestSanity-77261896:ctest-vn1-37910502 virtual network link through opserver 10.0.0.27 2026-03-05 19:41:35,389 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks 2026-03-05 19:41:35,399 - DEBUG - vn link and name as {'name': 'default-domain:ctest-FloatingipBasicTestSanity-77261896:ctest-vn1-37910502', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-FloatingipBasicTestSanity-77261896:ctest-vn1-37910502?flat'} 2026-03-05 19:41:35,399 - INFO - Validated that VN default-domain:ctest-FloatingipBasicTestSanity-77261896:ctest-vn1-37910502 is found in opserver 2026-03-05 19:41:35,410 - DEBUG - Do not have enough data to verify VN in agent 2026-03-05 19:41:35,415 - DEBUG - VRF ids for VN ctest-vn1-37910502: {} 2026-03-05 19:41:36,510 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-5446-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-5446-1) 2026-03-05 19:41:37,887 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-5446-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-5446-1) 2026-03-05 19:41:38,095 - INFO - Creating Floating IP pool ctest-some-pool-28554788 in API Server 2026-03-05 19:41:38,339 - DEBUG - Requesting: http://10.0.0.27:8082/domains 2026-03-05 19:41:38,346 - DEBUG - Requesting: http://10.0.0.27:8082/domain/c0a9362f-d5c3-44ab-b0d0-a4a177b3e40d 2026-03-05 19:41:38,383 - DEBUG - Requesting: http://10.0.0.27:8082/project/20c91cca-f58a-4c7b-92e3-fcc01a2db2b0 2026-03-05 19:41:38,508 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/302861da-0018-4b65-92dc-c0715a80a7a8 2026-03-05 19:41:38,528 - DEBUG - Requesting: http://10.0.0.27:8082/floating-ip-pool/306e0d01-226b-4c09-adda-9c0930083806 2026-03-05 19:41:38,552 - DEBUG - Requesting: http://10.0.0.27:8082/domains 2026-03-05 19:41:38,563 - DEBUG - Requesting: http://10.0.0.27:8082/domain/c0a9362f-d5c3-44ab-b0d0-a4a177b3e40d 2026-03-05 19:41:38,578 - DEBUG - Requesting: http://10.0.0.27:8082/project/20c91cca-f58a-4c7b-92e3-fcc01a2db2b0 2026-03-05 19:41:38,679 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/302861da-0018-4b65-92dc-c0715a80a7a8 2026-03-05 19:41:38,696 - DEBUG - FIP Pool ctest-some-pool-28554788 found in API Server 2026-03-05 19:41:38,696 - INFO - Verification for FIP pool ctest-some-pool-28554788 in API Server passed 2026-03-05 19:41:38,715 - DEBUG - Control-node Ifmap-view has FIP pool ctest-some-pool-28554788 information 2026-03-05 19:41:38,715 - INFO - Verification for FIP pool ctest-some-pool-28554788 in Control-node passed 2026-03-05 19:41:38,715 - INFO - Waiting for VM ctest-vn1_vm1_name-90527732 to be up.. 2026-03-05 19:41:38,844 - DEBUG - VM is in ACTIVE state now 2026-03-05 19:41:38,844 - INFO - VM name : ctest-vn1_vm1_name-90527732 2026-03-05 19:41:38,956 - DEBUG - VM ctest-vn1_vm1_name-90527732 ID is 0e015eb7-126f-4cd8-98fc-9c94c76d0f52 2026-03-05 19:41:38,981 - DEBUG - VM ctest-vn1_vm1_name-90527732 launched on Node cn-jenkins-deploy-platform-ansible-os-5446-1 2026-03-05 19:41:39,062 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/0e015eb7-126f-4cd8-98fc-9c94c76d0f52 2026-03-05 19:41:39,071 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/319d1512-ca51-4c3c-9451-00a574a91f0d 2026-03-05 19:41:42,274 - 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') 2026-03-05 19:41:42,274 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1_vm1_name-90527732 failed! 2026-03-05 19:41:42,327 - DEBUG - Gateway for vn default-domain:ctest-FloatingipBasicTestSanity-77261896:ctest-vn1-37910502 is 218.24.171.193 and allocation pool is NOT set 2026-03-05 19:41:46,368 - 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 1027ms') 2026-03-05 19:41:46,368 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1_vm1_name-90527732 failed! 2026-03-05 19:41:46,423 - DEBUG - Gateway for vn default-domain:ctest-FloatingipBasicTestSanity-77261896:ctest-vn1-37910502 is 218.24.171.193 and allocation pool is NOT set 2026-03-05 19:41:48,447 - 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.2 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=2.22 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.218/6.207/10.197/3.989 ms') 2026-03-05 19:41:48,447 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1_vm1_name-90527732 passed 2026-03-05 19:41:48,599 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-05 19:41:48,599 - DEBUG - Waiting to SSH to VM ctest-vn1_vm1_name-90527732, IP 218.24.171.195, Port 22 2026-03-05 19:41:48,671 - DEBUG - VM ctest-vn1_vm1_name-90527732 is ready for SSH connections 2026-03-05 19:41:48,765 - DEBUG - Created Floating IP : ('13.9.53.68', 'f0b340e5-d273-4b62-acb5-67299b9d2f53') 2026-03-05 19:41:48,765 - DEBUG - Associating FIP 13.9.53.68 to 0e015eb7-126f-4cd8-98fc-9c94c76d0f52 2026-03-05 19:41:48,792 - DEBUG - Associating FIP ID f0b340e5-d273-4b62-acb5-67299b9d2f53 with Port ID 319d1512-ca51-4c3c-9451-00a574a91f0d 2026-03-05 19:41:48,859 - INFO - Waiting for VM ctest-fvn_vm1_name-39259089 to be up.. 2026-03-05 19:41:48,946 - DEBUG - VM is in ACTIVE state now 2026-03-05 19:41:48,946 - INFO - VM name : ctest-fvn_vm1_name-39259089 2026-03-05 19:41:49,029 - DEBUG - VM ctest-fvn_vm1_name-39259089 ID is f5e165d3-29ec-4ad0-8e2d-cf92d2f537de 2026-03-05 19:41:49,029 - DEBUG - VM ctest-fvn_vm1_name-39259089 launched on Node cn-jenkins-deploy-platform-ansible-os-5446-1 2026-03-05 19:41:49,105 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/f5e165d3-29ec-4ad0-8e2d-cf92d2f537de 2026-03-05 19:41:49,114 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/6e0129aa-5aab-4126-b879-cdfdd999c768 2026-03-05 19:41:50,310 - 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=2.84 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.557 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 0.557/1.698/2.839/1.141 ms') 2026-03-05 19:41:50,310 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-fvn_vm1_name-39259089 passed 2026-03-05 19:41:50,463 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-05 19:41:50,463 - DEBUG - Waiting to SSH to VM ctest-fvn_vm1_name-39259089, IP 13.9.53.67, Port 22 2026-03-05 19:41:50,532 - DEBUG - VM ctest-fvn_vm1_name-39259089 is ready for SSH connections 2026-03-05 19:41:50,671 - INFO - FIP 13.9.53.68 verification for passed on all Control-nodes 2026-03-05 19:41:50,671 - INFO - FIP ID f0b340e5-d273-4b62-acb5-67299b9d2f53(13.9.53.68) validation in Control node passed 2026-03-05 19:41:50,689 - DEBUG - agent_label query returned:{'nh': {'type': 'interface', 'ref_count': '14', 'valid': 'true', 'policy': 'enabled', 'itf': 'tap319d1512-ca', 'mac': ['2:31:9d:15:12:ca'], 'mcast': 'disabled', 'nh_index': '23', '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': '25', 'vxlan_id': '0', 'peer': '10.20.0.254', 'dest_vn_list': ['default-domain:ctest-FloatingipBasicTestSanity-77261896:ctest-fvn-16207526'], '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': '25', '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-03-05 19:41:50,689 - DEBUG - Route for FIP IP 13.9.53.68 is present in agent 10.0.0.27 2026-03-05 19:41:50,689 - DEBUG - FIP 13.9.53.68 verification for VM ctest-vn1_vm1_name-90527732 in Agent 10.0.0.27 passed 2026-03-05 19:41:50,689 - INFO - FIP ID f0b340e5-d273-4b62-acb5-67299b9d2f53(13.9.53.68) validation in agents passed 2026-03-05 19:41:50,689 - DEBUG - Requesting: http://10.0.0.27:8082/floating-ip/f0b340e5-d273-4b62-acb5-67299b9d2f53 2026-03-05 19:41:50,697 - DEBUG - FIP f0b340e5-d273-4b62-acb5-67299b9d2f53 is present in API server 2026-03-05 19:41:50,697 - INFO - FIP ID f0b340e5-d273-4b62-acb5-67299b9d2f53(13.9.53.68) validation in API Server passed 2026-03-05 19:41:50,697 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-05 19:41:50,697 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 13.9.53.67, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.27, gateway password: c0ntrail123 2026-03-05 19:41:50,698 - DEBUG - ping -s 56 -c 3 -W 1 13.9.53.67 2026-03-05 19:41:53,680 - DEBUG - PING 13.9.53.67 (13.9.53.67): 56 data bytes 64 bytes from 13.9.53.67: seq=0 ttl=63 time=5.753 ms 64 bytes from 13.9.53.67: seq=1 ttl=63 time=0.936 ms 64 bytes from 13.9.53.67: seq=2 ttl=63 time=0.812 ms --- 13.9.53.67 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 0.812/2.500/5.753 ms 2026-03-05 19:41:53,680 - INFO - Ping to IP 13.9.53.67 from VM ctest-vn1_vm1_name-90527732 passed 2026-03-05 19:41:53,680 - DEBUG - Disassociating port from FIP ID : f0b340e5-d273-4b62-acb5-67299b9d2f53 2026-03-05 19:41:53,723 - DEBUG - Deleting FIP ID f0b340e5-d273-4b62-acb5-67299b9d2f53 2026-03-05 19:41:53,774 - INFO - Deleting the FIP pool ctest-some-pool-28554788 2026-03-05 19:41:53,860 - DEBUG - Control-node Ifmap-view does not have FIP pool ctest-some-pool-28554788 information 2026-03-05 19:41:53,860 - INFO - Deleting VM ctest-fvn_vm1_name-39259089 2026-03-05 19:41:53,935 - INFO - Deleting VM ctest-vn1_vm1_name-90527732 2026-03-05 19:41:54,004 - INFO - Deleting VN ctest-vn1-37910502 2026-03-05 19:41:54,039 - DEBUG - VN 3c82cf61-4704-43e8-b235-ca8b56e737c0 still in use: Unable to complete operation on network 3c82cf61-4704-43e8-b235-ca8b56e737c0. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-b83d0f0f-bb0e-4def-af0b-122cc038b018'] 2026-03-05 19:41:54,040 - WARNING - Deleting VN ctest-vn1-37910502 failed..Will retry 2026-03-05 19:41:56,148 - DEBUG - Response for deleting network () 2026-03-05 19:41:56,148 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/2a2658e9-49b0-4f6e-8cdd-a56a36ed850d 2026-03-05 19:41:56,154 - DEBUG - Response Code: 404 2026-03-05 19:41:56,154 - DEBUG - Requesting: http://10.0.0.27:8082/domains 2026-03-05 19:41:56,160 - DEBUG - Requesting: http://10.0.0.27:8082/domain/c0a9362f-d5c3-44ab-b0d0-a4a177b3e40d 2026-03-05 19:41:56,223 - DEBUG - Requesting: http://10.0.0.27:8082/project/20c91cca-f58a-4c7b-92e3-fcc01a2db2b0 2026-03-05 19:41:56,291 - INFO - Validated that VN ctest-vn1-37910502 is not found in API Server 2026-03-05 19:41:56,301 - DEBUG - VN ctest-vn1-37910502 is not present in Agent 10.0.0.27 2026-03-05 19:41:56,301 - INFO - Validated that VN ctest-vn1-37910502 is not in any agent 2026-03-05 19:41:56,315 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn1-37910502 info 2026-03-05 19:41:56,315 - INFO - Deleting VN ctest-fvn-16207526 2026-03-05 19:41:56,413 - DEBUG - Response for deleting network () 2026-03-05 19:41:56,413 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/d9647d27-a2e6-455b-8a37-2a491204198e 2026-03-05 19:41:56,419 - DEBUG - Response Code: 404 2026-03-05 19:41:56,419 - DEBUG - Requesting: http://10.0.0.27:8082/domains 2026-03-05 19:41:56,424 - DEBUG - Requesting: http://10.0.0.27:8082/domain/c0a9362f-d5c3-44ab-b0d0-a4a177b3e40d 2026-03-05 19:41:56,480 - DEBUG - Requesting: http://10.0.0.27:8082/project/20c91cca-f58a-4c7b-92e3-fcc01a2db2b0 2026-03-05 19:41:56,560 - INFO - Validated that VN ctest-fvn-16207526 is not found in API Server 2026-03-05 19:41:56,571 - DEBUG - VN ctest-fvn-16207526 is not present in Agent 10.0.0.27 2026-03-05 19:41:56,571 - INFO - Validated that VN ctest-fvn-16207526 is not in any agent 2026-03-05 19:41:56,585 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-fvn-16207526 info 2026-03-05 19:41:56,840 - DEBUG - No XMPP flaps were noticed during the test
2026-03-05 19:41:56,840 - INFO - END TEST : test_floating_ip : PASSED[0:00:24]
2026-03-05 19:41:56,840 - INFO - -------------------------------------------------------------------------------- 2026-03-05 19:41:57,545 - INFO - Deleted project: ctest-FloatingipBasicTestSanity-77261896, ID : 20c91cca-f58a-4c7b-92e3-fcc01a2db2b0