2026-08-17 15:51:07,634 - INFO - Domain Default found not creating 2026-08-17 15:51:07,784 - INFO - Project ctest-FloatingipBasicTestSanity-23573692 not found, creating it 2026-08-17 15:51:08,313 - INFO - Created Project:ctest-FloatingipBasicTestSanity-23573692, ID : 9f00f40b-25d5-48c9-8db2-e0df467a425b 2026-08-17 15:51:09,943 - INFO - Using existing project ['default-domain', 'admin'](77336c8d-8ae9-48d5-aa85-23d60325472b) 2026-08-17 15:51:10,748 - INFO - Adding rules to the default security group in Project admin 2026-08-17 15:51:11,084 - INFO - ================================================================================ 2026-08-17 15:51:11,085 - INFO - STARTING TEST : test_floating_ip 2026-08-17 15:51:11,085 - 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-08-17 15:51:11,217 - DEBUG - Nothing to compare xmpp stats {'10.0.0.38': {'10.20.0.25': '0'}} with 2026-08-17 15:51:11,217 - INFO - Initial checks done. Running the testcase now 2026-08-17 15:51:11,217 - INFO - 2026-08-17 15:51:11,241 - DEBUG - Services list from nova: [, , ] 2026-08-17 15:51:11,241 - DEBUG - Hosts: {'nova': ['cn-jenkins-deploy-platform-ansible-os-6177-1']} 2026-08-17 15:51:11,867 - DEBUG - Response for create_network : {'network': {'id': '1218507a-d789-4ad7-a2ae-cfff8117f64f', 'name': 'ctest-fvn-70696382', 'tenant_id': '9f00f40b25d548c98db2e0df467a425b', 'project_id': '9f00f40b25d548c98db2e0df467a425b', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-FloatingipBasicTestSanity-23573692', 'ctest-fvn-70696382'], 'port_security_enabled': True, 'description': ''}} 2026-08-17 15:51:12,069 - DEBUG - Response for create_subnet : {'subnet': {'id': '3ac82f29-c601-4ddf-b02f-951f95bf36aa', 'name': '', 'tenant_id': '9f00f40b25d548c98db2e0df467a425b', 'network_id': '1218507a-d789-4ad7-a2ae-cfff8117f64f', 'ip_version': 4, 'cidr': '74.52.9.64/26', 'allocation_pools': [{'start': '74.52.9.66', 'end': '74.52.9.126'}], 'gateway_ip': '74.52.9.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '74.52.9.66', 'tags': [], 'project_id': '9f00f40b25d548c98db2e0df467a425b'}} 2026-08-17 15:51:12,087 - INFO - Created VN ctest-fvn-70696382 2026-08-17 15:51:12,140 - DEBUG - VN ctest-fvn-70696382 UUID is 1218507a-d789-4ad7-a2ae-cfff8117f64f 2026-08-17 15:51:12,250 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2026-08-17 15:51:12,654 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2026-08-17 15:51:12,696 - DEBUG - Requesting: http://10.0.0.38:8082/domain/96701fdd-cdc5-4d69-897f-51d30045c946 2026-08-17 15:51:12,712 - DEBUG - Requesting: http://10.0.0.38:8082/project/9f00f40b-25d5-48c9-8db2-e0df467a425b 2026-08-17 15:51:12,799 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/1218507a-d789-4ad7-a2ae-cfff8117f64f 2026-08-17 15:51:12,811 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/1218507a-d789-4ad7-a2ae-cfff8117f64f 2026-08-17 15:51:12,823 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/04ef5761-f098-473f-97a4-2ae06afe2e98 2026-08-17 15:51:12,832 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/04ef5761-f098-473f-97a4-2ae06afe2e98 2026-08-17 15:51:12,842 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/544f936b-8352-49d6-a61b-5c30432ea12c 2026-08-17 15:51:12,851 - DEBUG - Route Targets: ['target:64512:8000004'] 2026-08-17 15:51:12,851 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/1218507a-d789-4ad7-a2ae-cfff8117f64f 2026-08-17 15:51:12,860 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/04ef5761-f098-473f-97a4-2ae06afe2e98 2026-08-17 15:51:12,876 - INFO - Verified VN network id 5 for VN 1218507a-d789-4ad7-a2ae-cfff8117f64f 2026-08-17 15:51:12,876 - INFO - Verifications in API Server for VN ctest-fvn-70696382 passed 2026-08-17 15:51:12,876 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/1218507a-d789-4ad7-a2ae-cfff8117f64f 2026-08-17 15:51:12,887 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/04ef5761-f098-473f-97a4-2ae06afe2e98 2026-08-17 15:51:12,895 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/04ef5761-f098-473f-97a4-2ae06afe2e98 2026-08-17 15:51:12,903 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/544f936b-8352-49d6-a61b-5c30432ea12c 2026-08-17 15:51:12,916 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-FloatingipBasicTestSanity-23573692:ctest-fvn-70696382', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-FloatingipBasicTestSanity-23573692', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1303880580656089815', 'uuid-lslong': '11722535576381421135'}, 'enable': 'true', 'created': '2026-08-17T15:51:11', 'last-modified': '2026-08-17T15:51:12', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.884383'} 2026-08-17 15:51:12,922 - DEBUG - Route Targets: ['target:64512:8000004'] 2026-08-17 15:51:12,922 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-fvn-70696382 passed 2026-08-17 15:51:12,922 - DEBUG - ====Verifying policy data for ctest-fvn-70696382 in API_Server ====== 2026-08-17 15:51:12,922 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2026-08-17 15:51:12,929 - DEBUG - Requesting: http://10.0.0.38:8082/domain/96701fdd-cdc5-4d69-897f-51d30045c946 2026-08-17 15:51:12,941 - DEBUG - Requesting: http://10.0.0.38:8082/project/9f00f40b-25d5-48c9-8db2-e0df467a425b 2026-08-17 15:51:13,020 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/1218507a-d789-4ad7-a2ae-cfff8117f64f 2026-08-17 15:51:13,038 - DEBUG - =>VN ctest-fvn-70696382 has no policy to be verified 2026-08-17 15:51:13,038 - DEBUG - Verifying the vn in opserver 2026-08-17 15:51:13,038 - DEBUG - Verifying the default-domain:ctest-FloatingipBasicTestSanity-23573692:ctest-fvn-70696382 virtual network link through opserver 10.0.0.38 2026-08-17 15:51:13,038 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks 2026-08-17 15:51:13,365 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks 2026-08-17 15:51:13,421 - DEBUG - vn link and name as {'name': 'default-domain:ctest-FloatingipBasicTestSanity-23573692:ctest-fvn-70696382', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-FloatingipBasicTestSanity-23573692:ctest-fvn-70696382?flat'} 2026-08-17 15:51:13,421 - INFO - Validated that VN default-domain:ctest-FloatingipBasicTestSanity-23573692:ctest-fvn-70696382 is found in opserver 2026-08-17 15:51:13,434 - DEBUG - Do not have enough data to verify VN in agent 2026-08-17 15:51:13,438 - DEBUG - VRF ids for VN ctest-fvn-70696382: {} 2026-08-17 15:51:13,642 - DEBUG - Response for create_network : {'network': {'id': '7ec79b8f-9888-42c8-81fc-4c7c8a3613dc', 'name': 'ctest-vn1-69406626', 'tenant_id': '9f00f40b25d548c98db2e0df467a425b', 'project_id': '9f00f40b25d548c98db2e0df467a425b', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-FloatingipBasicTestSanity-23573692', 'ctest-vn1-69406626'], 'port_security_enabled': True, 'description': ''}} 2026-08-17 15:51:13,866 - DEBUG - Response for create_subnet : {'subnet': {'id': 'e7c07635-1995-4e11-9208-514d8d386ca9', 'name': '', 'tenant_id': '9f00f40b25d548c98db2e0df467a425b', 'network_id': '7ec79b8f-9888-42c8-81fc-4c7c8a3613dc', 'ip_version': 4, 'cidr': '129.15.235.64/26', 'allocation_pools': [{'start': '129.15.235.66', 'end': '129.15.235.126'}], 'gateway_ip': '129.15.235.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '129.15.235.66', 'tags': [], 'project_id': '9f00f40b25d548c98db2e0df467a425b'}} 2026-08-17 15:51:13,887 - INFO - Created VN ctest-vn1-69406626 2026-08-17 15:51:13,940 - DEBUG - VN ctest-vn1-69406626 UUID is 7ec79b8f-9888-42c8-81fc-4c7c8a3613dc 2026-08-17 15:51:14,052 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2026-08-17 15:51:14,058 - DEBUG - Requesting: http://10.0.0.38:8082/domain/96701fdd-cdc5-4d69-897f-51d30045c946 2026-08-17 15:51:14,069 - DEBUG - Requesting: http://10.0.0.38:8082/project/9f00f40b-25d5-48c9-8db2-e0df467a425b 2026-08-17 15:51:14,145 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/7ec79b8f-9888-42c8-81fc-4c7c8a3613dc 2026-08-17 15:51:14,155 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/7ec79b8f-9888-42c8-81fc-4c7c8a3613dc 2026-08-17 15:51:14,166 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/75ab2bc6-5984-4c39-90ad-4ed59e010e5e 2026-08-17 15:51:14,177 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/75ab2bc6-5984-4c39-90ad-4ed59e010e5e 2026-08-17 15:51:14,184 - DEBUG - Route Targets: [] 2026-08-17 15:51:14,184 - DEBUG - RT names not yet present for VN ctest-vn1-69406626 2026-08-17 15:51:19,185 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2026-08-17 15:51:19,193 - DEBUG - Requesting: http://10.0.0.38:8082/domain/96701fdd-cdc5-4d69-897f-51d30045c946 2026-08-17 15:51:19,203 - DEBUG - Requesting: http://10.0.0.38:8082/project/9f00f40b-25d5-48c9-8db2-e0df467a425b 2026-08-17 15:51:19,283 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/7ec79b8f-9888-42c8-81fc-4c7c8a3613dc 2026-08-17 15:51:19,292 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/7ec79b8f-9888-42c8-81fc-4c7c8a3613dc 2026-08-17 15:51:19,301 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/75ab2bc6-5984-4c39-90ad-4ed59e010e5e 2026-08-17 15:51:19,311 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/75ab2bc6-5984-4c39-90ad-4ed59e010e5e 2026-08-17 15:51:19,317 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/90d6fb9b-8c0e-477b-b245-9933820d6a71 2026-08-17 15:51:19,324 - DEBUG - Route Targets: ['target:64512:8000005'] 2026-08-17 15:51:19,324 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/7ec79b8f-9888-42c8-81fc-4c7c8a3613dc 2026-08-17 15:51:19,334 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/75ab2bc6-5984-4c39-90ad-4ed59e010e5e 2026-08-17 15:51:19,349 - INFO - Verified VN network id 6 for VN 7ec79b8f-9888-42c8-81fc-4c7c8a3613dc 2026-08-17 15:51:19,349 - INFO - Verifications in API Server for VN ctest-vn1-69406626 passed 2026-08-17 15:51:19,349 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/7ec79b8f-9888-42c8-81fc-4c7c8a3613dc 2026-08-17 15:51:19,359 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/75ab2bc6-5984-4c39-90ad-4ed59e010e5e 2026-08-17 15:51:19,366 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/75ab2bc6-5984-4c39-90ad-4ed59e010e5e 2026-08-17 15:51:19,372 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/90d6fb9b-8c0e-477b-b245-9933820d6a71 2026-08-17 15:51:19,385 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-FloatingipBasicTestSanity-23573692:ctest-vn1-69406626', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-FloatingipBasicTestSanity-23573692', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9135441410186035912', 'uuid-lslong': '9366445422802244572'}, 'enable': 'true', 'created': '2026-08-17T15:51:13', 'last-modified': '2026-08-17T15:51:13', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:05.562704'} 2026-08-17 15:51:19,391 - DEBUG - Route Targets: ['target:64512:8000005'] 2026-08-17 15:51:19,391 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn1-69406626 passed 2026-08-17 15:51:19,391 - DEBUG - ====Verifying policy data for ctest-vn1-69406626 in API_Server ====== 2026-08-17 15:51:19,391 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2026-08-17 15:51:19,398 - DEBUG - Requesting: http://10.0.0.38:8082/domain/96701fdd-cdc5-4d69-897f-51d30045c946 2026-08-17 15:51:19,409 - DEBUG - Requesting: http://10.0.0.38:8082/project/9f00f40b-25d5-48c9-8db2-e0df467a425b 2026-08-17 15:51:19,485 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/7ec79b8f-9888-42c8-81fc-4c7c8a3613dc 2026-08-17 15:51:19,493 - DEBUG - =>VN ctest-vn1-69406626 has no policy to be verified 2026-08-17 15:51:19,493 - DEBUG - Verifying the vn in opserver 2026-08-17 15:51:19,494 - DEBUG - Verifying the default-domain:ctest-FloatingipBasicTestSanity-23573692:ctest-vn1-69406626 virtual network link through opserver 10.0.0.38 2026-08-17 15:51:19,494 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks 2026-08-17 15:51:19,503 - DEBUG - vn link and name as {'name': 'default-domain:ctest-FloatingipBasicTestSanity-23573692:ctest-vn1-69406626', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-FloatingipBasicTestSanity-23573692:ctest-vn1-69406626?flat'} 2026-08-17 15:51:19,503 - INFO - Validated that VN default-domain:ctest-FloatingipBasicTestSanity-23573692:ctest-vn1-69406626 is found in opserver 2026-08-17 15:51:19,513 - DEBUG - Do not have enough data to verify VN in agent 2026-08-17 15:51:19,518 - DEBUG - VRF ids for VN ctest-vn1-69406626: {} 2026-08-17 15:51:19,871 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-6177-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-6177-1) 2026-08-17 15:51:20,244 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-6177-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-6177-1) 2026-08-17 15:51:20,432 - INFO - Creating Floating IP pool ctest-some-pool-75743436 in API Server 2026-08-17 15:51:20,676 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2026-08-17 15:51:20,683 - DEBUG - Requesting: http://10.0.0.38:8082/domain/96701fdd-cdc5-4d69-897f-51d30045c946 2026-08-17 15:51:20,694 - DEBUG - Requesting: http://10.0.0.38:8082/project/9f00f40b-25d5-48c9-8db2-e0df467a425b 2026-08-17 15:51:20,772 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/1218507a-d789-4ad7-a2ae-cfff8117f64f 2026-08-17 15:51:20,787 - DEBUG - Requesting: http://10.0.0.38:8082/floating-ip-pool/33e9ce12-6c8c-48e1-9ab6-ef38a6314de8 2026-08-17 15:51:20,795 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2026-08-17 15:51:20,801 - DEBUG - Requesting: http://10.0.0.38:8082/domain/96701fdd-cdc5-4d69-897f-51d30045c946 2026-08-17 15:51:20,811 - DEBUG - Requesting: http://10.0.0.38:8082/project/9f00f40b-25d5-48c9-8db2-e0df467a425b 2026-08-17 15:51:20,892 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/1218507a-d789-4ad7-a2ae-cfff8117f64f 2026-08-17 15:51:20,902 - DEBUG - FIP Pool ctest-some-pool-75743436 found in API Server 2026-08-17 15:51:20,902 - INFO - Verification for FIP pool ctest-some-pool-75743436 in API Server passed 2026-08-17 15:51:20,915 - DEBUG - Control-node Ifmap-view has FIP pool ctest-some-pool-75743436 information 2026-08-17 15:51:20,915 - INFO - Verification for FIP pool ctest-some-pool-75743436 in Control-node passed 2026-08-17 15:51:20,915 - INFO - Waiting for VM ctest-vn1_vm1_name-89795244 to be up.. 2026-08-17 15:51:20,964 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-08-17 15:51:26,043 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-08-17 15:51:31,124 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-08-17 15:51:36,205 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-08-17 15:51:41,281 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-08-17 15:51:46,371 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-08-17 15:51:51,441 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-08-17 15:51:56,535 - DEBUG - VM is in ACTIVE state now 2026-08-17 15:51:56,535 - INFO - VM name : ctest-vn1_vm1_name-89795244 2026-08-17 15:51:56,630 - DEBUG - VM ctest-vn1_vm1_name-89795244 ID is f4cdc086-0c0b-453a-97e5-7e2f9323c95a 2026-08-17 15:51:56,650 - DEBUG - VM ctest-vn1_vm1_name-89795244 launched on Node cn-jenkins-deploy-platform-ansible-os-6177-1 2026-08-17 15:51:56,733 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/f4cdc086-0c0b-453a-97e5-7e2f9323c95a 2026-08-17 15:51:56,743 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/ca1d380d-78cd-4d72-a648-ada515ea2677 2026-08-17 15:51:59,958 - 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 1006ms') 2026-08-17 15:51:59,958 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1_vm1_name-89795244 failed! 2026-08-17 15:52:00,016 - DEBUG - Gateway for vn default-domain:ctest-FloatingipBasicTestSanity-23573692:ctest-vn1-69406626 is 129.15.235.65 and allocation pool is NOT set 2026-08-17 15:52:04,053 - 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=9.38 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 1 received, 50% packet loss, time 1014ms\r\nrtt min/avg/max/mdev = 9.382/9.382/9.382/0.000 ms') 2026-08-17 15:52:04,053 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1_vm1_name-89795244 passed 2026-08-17 15:52:04,212 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-08-17 15:52:04,212 - DEBUG - Waiting to SSH to VM ctest-vn1_vm1_name-89795244, IP 129.15.235.67, Port 22 2026-08-17 15:52:06,077 - DEBUG - VM ctest-vn1_vm1_name-89795244 is ready for SSH connections 2026-08-17 15:52:06,186 - DEBUG - Created Floating IP : ('74.52.9.68', 'b42e475d-dee2-4bd3-8b43-10b285d95e28') 2026-08-17 15:52:06,186 - DEBUG - Associating FIP 74.52.9.68 to f4cdc086-0c0b-453a-97e5-7e2f9323c95a 2026-08-17 15:52:06,213 - DEBUG - Associating FIP ID b42e475d-dee2-4bd3-8b43-10b285d95e28 with Port ID ca1d380d-78cd-4d72-a648-ada515ea2677 2026-08-17 15:52:06,294 - INFO - Waiting for VM ctest-fvn_vm1_name-84271061 to be up.. 2026-08-17 15:52:06,369 - DEBUG - VM is in ACTIVE state now 2026-08-17 15:52:06,369 - INFO - VM name : ctest-fvn_vm1_name-84271061 2026-08-17 15:52:06,459 - DEBUG - VM ctest-fvn_vm1_name-84271061 ID is 13b1a0d5-2f71-432d-b764-880834b2b278 2026-08-17 15:52:06,460 - DEBUG - VM ctest-fvn_vm1_name-84271061 launched on Node cn-jenkins-deploy-platform-ansible-os-6177-1 2026-08-17 15:52:06,555 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/13b1a0d5-2f71-432d-b764-880834b2b278 2026-08-17 15:52:06,564 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/d67bec1c-81ae-4618-8610-5ee4a9999ab9 2026-08-17 15:52:07,766 - 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=3.16 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.594 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.594/1.877/3.160/1.283 ms') 2026-08-17 15:52:07,766 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-fvn_vm1_name-84271061 passed 2026-08-17 15:52:07,920 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-08-17 15:52:07,920 - DEBUG - Waiting to SSH to VM ctest-fvn_vm1_name-84271061, IP 74.52.9.67, Port 22 2026-08-17 15:52:08,004 - DEBUG - VM ctest-fvn_vm1_name-84271061 is ready for SSH connections 2026-08-17 15:52:08,144 - INFO - FIP 74.52.9.68 verification for passed on all Control-nodes 2026-08-17 15:52:08,144 - INFO - FIP ID b42e475d-dee2-4bd3-8b43-10b285d95e28(74.52.9.68) validation in Control node passed 2026-08-17 15:52:08,165 - DEBUG - agent_label query returned:{'nh': {'type': 'interface', 'ref_count': '12', 'valid': 'true', 'policy': 'enabled', 'itf': 'tapca1d380d-78', 'mac': ['2:ca:1d:38:d:78'], '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-23573692:ctest-fvn-70696382'], 'unresolved': 'false', 'sg_list': ['8000003'], '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-08-17 15:52:08,165 - DEBUG - Route for FIP IP 74.52.9.68 is present in agent 10.0.0.38 2026-08-17 15:52:08,165 - DEBUG - FIP 74.52.9.68 verification for VM ctest-vn1_vm1_name-89795244 in Agent 10.0.0.38 passed 2026-08-17 15:52:08,165 - INFO - FIP ID b42e475d-dee2-4bd3-8b43-10b285d95e28(74.52.9.68) validation in agents passed 2026-08-17 15:52:08,166 - DEBUG - Requesting: http://10.0.0.38:8082/floating-ip/b42e475d-dee2-4bd3-8b43-10b285d95e28 2026-08-17 15:52:08,175 - DEBUG - FIP b42e475d-dee2-4bd3-8b43-10b285d95e28 is present in API server 2026-08-17 15:52:08,175 - INFO - FIP ID b42e475d-dee2-4bd3-8b43-10b285d95e28(74.52.9.68) validation in API Server passed 2026-08-17 15:52:08,175 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-08-17 15:52:08,176 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 74.52.9.67, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.38, gateway password: c0ntrail123 2026-08-17 15:52:08,176 - DEBUG - ping -s 56 -c 3 -W 1 74.52.9.67 2026-08-17 15:52:11,101 - DEBUG - PING 74.52.9.67 (74.52.9.67): 56 data bytes 64 bytes from 74.52.9.67: seq=0 ttl=63 time=5.541 ms 64 bytes from 74.52.9.67: seq=1 ttl=63 time=1.040 ms 64 bytes from 74.52.9.67: seq=2 ttl=63 time=0.839 ms --- 74.52.9.67 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 0.839/2.473/5.541 ms 2026-08-17 15:52:11,101 - INFO - Ping to IP 74.52.9.67 from VM ctest-vn1_vm1_name-89795244 passed 2026-08-17 15:52:11,101 - DEBUG - Disassociating port from FIP ID : b42e475d-dee2-4bd3-8b43-10b285d95e28 2026-08-17 15:52:11,155 - DEBUG - Deleting FIP ID b42e475d-dee2-4bd3-8b43-10b285d95e28 2026-08-17 15:52:11,216 - INFO - Deleting the FIP pool ctest-some-pool-75743436 2026-08-17 15:52:11,308 - DEBUG - Control-node Ifmap-view does not have FIP pool ctest-some-pool-75743436 information 2026-08-17 15:52:11,308 - INFO - Deleting VM ctest-fvn_vm1_name-84271061 2026-08-17 15:52:11,374 - INFO - Deleting VM ctest-vn1_vm1_name-89795244 2026-08-17 15:52:11,443 - INFO - Deleting VN ctest-vn1-69406626 2026-08-17 15:52:11,477 - DEBUG - VN 7ec79b8f-9888-42c8-81fc-4c7c8a3613dc still in use: Unable to complete operation on network 7ec79b8f-9888-42c8-81fc-4c7c8a3613dc. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-6b196e9a-c248-46d3-946f-4ccfd02337b0'] 2026-08-17 15:52:11,477 - WARNING - Deleting VN ctest-vn1-69406626 failed..Will retry 2026-08-17 15:52:13,538 - DEBUG - VN 7ec79b8f-9888-42c8-81fc-4c7c8a3613dc still in use: Unable to complete operation on network 7ec79b8f-9888-42c8-81fc-4c7c8a3613dc. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-acd1c421-d116-4580-b1ed-c71f63157961'] 2026-08-17 15:52:13,538 - WARNING - Deleting VN ctest-vn1-69406626 failed..Will retry 2026-08-17 15:52:15,656 - DEBUG - Response for deleting network () 2026-08-17 15:52:15,656 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/75ab2bc6-5984-4c39-90ad-4ed59e010e5e 2026-08-17 15:52:15,661 - DEBUG - Response Code: 404 2026-08-17 15:52:15,661 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2026-08-17 15:52:15,670 - DEBUG - Requesting: http://10.0.0.38:8082/domain/96701fdd-cdc5-4d69-897f-51d30045c946 2026-08-17 15:52:15,726 - DEBUG - Requesting: http://10.0.0.38:8082/project/9f00f40b-25d5-48c9-8db2-e0df467a425b 2026-08-17 15:52:15,805 - INFO - Validated that VN ctest-vn1-69406626 is not found in API Server 2026-08-17 15:52:15,816 - DEBUG - VN ctest-vn1-69406626 is not present in Agent 10.0.0.38 2026-08-17 15:52:15,816 - INFO - Validated that VN ctest-vn1-69406626 is not in any agent 2026-08-17 15:52:15,839 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn1-69406626 info 2026-08-17 15:52:15,839 - INFO - Deleting VN ctest-fvn-70696382 2026-08-17 15:52:15,951 - DEBUG - Response for deleting network () 2026-08-17 15:52:15,951 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/04ef5761-f098-473f-97a4-2ae06afe2e98 2026-08-17 15:52:15,960 - DEBUG - Response Code: 404 2026-08-17 15:52:15,961 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2026-08-17 15:52:15,967 - DEBUG - Requesting: http://10.0.0.38:8082/domain/96701fdd-cdc5-4d69-897f-51d30045c946 2026-08-17 15:52:16,021 - DEBUG - Requesting: http://10.0.0.38:8082/project/9f00f40b-25d5-48c9-8db2-e0df467a425b 2026-08-17 15:52:16,100 - INFO - Validated that VN ctest-fvn-70696382 is not found in API Server 2026-08-17 15:52:16,111 - DEBUG - VN ctest-fvn-70696382 is not present in Agent 10.0.0.38 2026-08-17 15:52:16,111 - INFO - Validated that VN ctest-fvn-70696382 is not in any agent 2026-08-17 15:52:16,124 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-fvn-70696382 info 2026-08-17 15:52:16,378 - DEBUG - No XMPP flaps were noticed during the test
2026-08-17 15:52:16,378 - INFO - END TEST : test_floating_ip : PASSED[0:01:05]
2026-08-17 15:52:16,378 - INFO - -------------------------------------------------------------------------------- 2026-08-17 15:52:17,170 - INFO - Deleted project: ctest-FloatingipBasicTestSanity-23573692, ID : 9f00f40b-25d5-48c9-8db2-e0df467a425b