2026-09-28 02:48:49,819 - INFO - Domain Default found not creating 2026-09-28 02:48:50,028 - INFO - Project ctest-FloatingipBasicTestSanity-54630985 not found, creating it 2026-09-28 02:48:50,707 - INFO - Created Project:ctest-FloatingipBasicTestSanity-54630985, ID : 55728823-c529-402a-8a70-78720eeac77d 2026-09-28 02:48:52,573 - INFO - ================================================================================ 2026-09-28 02:48:52,574 - INFO - STARTING TEST : test_floating_ip 2026-09-28 02:48:52,574 - 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-28 02:48:53,282 - DEBUG - Nothing to compare xmpp stats {'10.0.0.38': {'10.20.0.254': '0', '10.20.0.25': '0'}, '10.0.0.162': {'10.20.0.25': '0', '10.20.0.254': '0'}} with 2026-09-28 02:48:53,282 - INFO - Initial checks done. Running the testcase now 2026-09-28 02:48:53,282 - INFO - 2026-09-28 02:48:53,314 - DEBUG - Services list from nova: [, , , ] 2026-09-28 02:48:53,314 - DEBUG - Hosts: {'nova': ['an-jenkins-deploy-platform-ansible-os-6324-2', 'an-jenkins-deploy-platform-ansible-os-6324-1']} 2026-09-28 02:48:54,257 - DEBUG - Response for create_network : {'network': {'id': '3b05f6a8-702b-49b1-bf6a-b802575de51e', 'name': 'ctest-fvn-05560044', 'tenant_id': '55728823c529402a8a7078720eeac77d', 'project_id': '55728823c529402a8a7078720eeac77d', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'tags': [], 'subnets': [], 'fq_name': ['default-domain', 'ctest-FloatingipBasicTestSanity-54630985', 'ctest-fvn-05560044'], 'port_security_enabled': True, 'description': ''}} 2026-09-28 02:48:54,653 - DEBUG - Response for create_subnet : {'subnet': {'id': '63caef8c-f65a-49aa-8021-ac26361eb4c7', 'name': '', 'tenant_id': '55728823c529402a8a7078720eeac77d', 'network_id': '3b05f6a8-702b-49b1-bf6a-b802575de51e', 'ip_version': 4, 'cidr': '58.88.150.0/26', 'allocation_pools': [{'start': '58.88.150.2', 'end': '58.88.150.62'}], 'gateway_ip': '58.88.150.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '58.88.150.2', 'tags': [], 'project_id': '55728823c529402a8a7078720eeac77d'}} 2026-09-28 02:48:54,681 - INFO - Created VN ctest-fvn-05560044 2026-09-28 02:48:54,696 - DEBUG - VN ctest-fvn-05560044 UUID is 3b05f6a8-702b-49b1-bf6a-b802575de51e 2026-09-28 02:48:54,798 - DEBUG - Requesting: http://10.0.0.176:8082/domains 2026-09-28 02:48:55,185 - DEBUG - Requesting: http://10.0.0.176:8082/domains 2026-09-28 02:48:55,222 - DEBUG - Requesting: http://10.0.0.176:8082/domain/6a7973b0-69cf-4d5f-a060-1b4d1311d404 2026-09-28 02:48:55,241 - DEBUG - Requesting: http://10.0.0.176:8082/project/55728823-c529-402a-8a70-78720eeac77d 2026-09-28 02:48:55,349 - DEBUG - Requesting: http://10.0.0.176:8082/virtual-network/3b05f6a8-702b-49b1-bf6a-b802575de51e 2026-09-28 02:48:55,367 - DEBUG - Requesting: http://10.0.0.176:8082/virtual-network/3b05f6a8-702b-49b1-bf6a-b802575de51e 2026-09-28 02:48:55,380 - DEBUG - Requesting: http://10.0.0.176:8082/routing-instance/351217a5-9721-4bce-9fe2-2ccc2ea762c8 2026-09-28 02:48:55,397 - DEBUG - Requesting: http://10.0.0.176:8082/routing-instance/351217a5-9721-4bce-9fe2-2ccc2ea762c8 2026-09-28 02:48:55,411 - DEBUG - Requesting: http://10.0.0.176:8082/route-target/63330ded-1ebd-47ab-afaa-b7702d642d4c 2026-09-28 02:48:55,422 - DEBUG - Route Targets: ['target:64512:8000004'] 2026-09-28 02:48:55,422 - DEBUG - Requesting: http://10.0.0.176:8082/virtual-network/3b05f6a8-702b-49b1-bf6a-b802575de51e 2026-09-28 02:48:55,439 - DEBUG - Requesting: http://10.0.0.176:8082/routing-instance/351217a5-9721-4bce-9fe2-2ccc2ea762c8 2026-09-28 02:48:55,459 - INFO - Verified VN network id 9 for VN 3b05f6a8-702b-49b1-bf6a-b802575de51e 2026-09-28 02:48:55,459 - INFO - Verifications in API Server for VN ctest-fvn-05560044 passed 2026-09-28 02:48:55,459 - DEBUG - Requesting: http://10.0.0.176:8082/virtual-network/3b05f6a8-702b-49b1-bf6a-b802575de51e 2026-09-28 02:48:55,476 - DEBUG - Requesting: http://10.0.0.176:8082/routing-instance/351217a5-9721-4bce-9fe2-2ccc2ea762c8 2026-09-28 02:48:55,487 - DEBUG - Requesting: http://10.0.0.176:8082/routing-instance/351217a5-9721-4bce-9fe2-2ccc2ea762c8 2026-09-28 02:48:55,499 - DEBUG - Requesting: http://10.0.0.176:8082/route-target/63330ded-1ebd-47ab-afaa-b7702d642d4c 2026-09-28 02:48:55,515 - DEBUG - Control-node 10.0.0.176 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-FloatingipBasicTestSanity-54630985:ctest-fvn-05560044', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-FloatingipBasicTestSanity-54630985', 'owner-access': '7', 'group': 'manager', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4253076626418125233', 'uuid-lslong': '13793039128970781982'}, 'enable': 'true', 'created': '2026-09-28T02:48:54', 'last-modified': '2026-09-28T02:48:54', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.956303'} 2026-09-28 02:48:55,522 - DEBUG - Route Targets: ['target:64512:8000004'] 2026-09-28 02:48:55,528 - DEBUG - Control-node 10.0.0.177 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-FloatingipBasicTestSanity-54630985:ctest-fvn-05560044', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-FloatingipBasicTestSanity-54630985', 'owner-access': '7', 'group': 'manager', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4253076626418125233', 'uuid-lslong': '13793039128970781982'}, 'enable': 'true', 'created': '2026-09-28T02:48:54', 'last-modified': '2026-09-28T02:48:54', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.978776'} 2026-09-28 02:48:55,534 - DEBUG - Route Targets: ['target:64512:8000004'] 2026-09-28 02:48:55,541 - DEBUG - Control-node 10.0.0.161 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-FloatingipBasicTestSanity-54630985:ctest-fvn-05560044', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-FloatingipBasicTestSanity-54630985', 'owner-access': '7', 'group': 'manager', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4253076626418125233', 'uuid-lslong': '13793039128970781982'}, 'enable': 'true', 'created': '2026-09-28T02:48:54', 'last-modified': '2026-09-28T02:48:54', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.991030'} 2026-09-28 02:48:55,550 - DEBUG - Route Targets: ['target:64512:8000004'] 2026-09-28 02:48:55,550 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-fvn-05560044 passed 2026-09-28 02:48:55,551 - DEBUG - ====Verifying policy data for ctest-fvn-05560044 in API_Server ====== 2026-09-28 02:48:55,551 - DEBUG - Requesting: http://10.0.0.176:8082/domains 2026-09-28 02:48:55,561 - DEBUG - Requesting: http://10.0.0.176:8082/domain/6a7973b0-69cf-4d5f-a060-1b4d1311d404 2026-09-28 02:48:55,582 - DEBUG - Requesting: http://10.0.0.176:8082/project/55728823-c529-402a-8a70-78720eeac77d 2026-09-28 02:48:55,691 - DEBUG - Requesting: http://10.0.0.176:8082/virtual-network/3b05f6a8-702b-49b1-bf6a-b802575de51e 2026-09-28 02:48:55,710 - DEBUG - =>VN ctest-fvn-05560044 has no policy to be verified 2026-09-28 02:48:55,711 - DEBUG - Verifying the vn in opserver 2026-09-28 02:48:55,711 - DEBUG - Verifying the default-domain:ctest-FloatingipBasicTestSanity-54630985:ctest-fvn-05560044 virtual network link through opserver 10.0.0.176 2026-09-28 02:48:55,711 - DEBUG - Requesting: http://10.0.0.176:8081/analytics/uves/virtual-networks 2026-09-28 02:48:56,138 - DEBUG - Requesting: http://10.0.0.176:8081/analytics/uves/virtual-networks 2026-09-28 02:48:56,205 - DEBUG - vn link and name as {'name': 'default-domain:ctest-FloatingipBasicTestSanity-54630985:ctest-fvn-05560044', 'href': 'http://10.0.0.176:8081/analytics/uves/virtual-network/default-domain:ctest-FloatingipBasicTestSanity-54630985:ctest-fvn-05560044?flat'} 2026-09-28 02:48:56,205 - DEBUG - Verifying the default-domain:ctest-FloatingipBasicTestSanity-54630985:ctest-fvn-05560044 virtual network link through opserver 10.0.0.177 2026-09-28 02:48:56,205 - DEBUG - Requesting: http://10.0.0.177:8081/analytics/uves/virtual-networks 2026-09-28 02:48:56,629 - DEBUG - Requesting: http://10.0.0.177:8081/analytics/uves/virtual-networks 2026-09-28 02:48:56,736 - DEBUG - vn link and name as {'name': 'default-domain:ctest-FloatingipBasicTestSanity-54630985:ctest-fvn-05560044', 'href': 'http://10.0.0.177:8081/analytics/uves/virtual-network/default-domain:ctest-FloatingipBasicTestSanity-54630985:ctest-fvn-05560044?flat'} 2026-09-28 02:48:56,736 - DEBUG - Verifying the default-domain:ctest-FloatingipBasicTestSanity-54630985:ctest-fvn-05560044 virtual network link through opserver 10.0.0.161 2026-09-28 02:48:56,736 - DEBUG - Requesting: http://10.0.0.161:8081/analytics/uves/virtual-networks 2026-09-28 02:48:57,157 - DEBUG - Requesting: http://10.0.0.161:8081/analytics/uves/virtual-networks 2026-09-28 02:48:57,228 - DEBUG - vn link and name as {'name': 'default-domain:ctest-FloatingipBasicTestSanity-54630985:ctest-fvn-05560044', 'href': 'http://10.0.0.161:8081/analytics/uves/virtual-network/default-domain:ctest-FloatingipBasicTestSanity-54630985:ctest-fvn-05560044?flat'} 2026-09-28 02:48:57,228 - INFO - Validated that VN default-domain:ctest-FloatingipBasicTestSanity-54630985:ctest-fvn-05560044 is found in opserver 2026-09-28 02:48:57,256 - DEBUG - Do not have enough data to verify VN in agent 2026-09-28 02:48:57,268 - DEBUG - VRF ids for VN ctest-fvn-05560044: {} 2026-09-28 02:48:57,615 - DEBUG - Response for create_network : {'network': {'id': '9b127b68-d11e-46b2-8337-a241336ea539', 'name': 'ctest-vn1-31511793', 'tenant_id': '55728823c529402a8a7078720eeac77d', 'project_id': '55728823c529402a8a7078720eeac77d', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'tags': [], 'subnets': [], 'fq_name': ['default-domain', 'ctest-FloatingipBasicTestSanity-54630985', 'ctest-vn1-31511793'], 'port_security_enabled': True, 'description': ''}} 2026-09-28 02:48:57,958 - DEBUG - Response for create_subnet : {'subnet': {'id': '4f93f3b9-9b5a-41a9-a583-e34a22bc70f2', 'name': '', 'tenant_id': '55728823c529402a8a7078720eeac77d', 'network_id': '9b127b68-d11e-46b2-8337-a241336ea539', 'ip_version': 4, 'cidr': '100.3.16.0/26', 'allocation_pools': [{'start': '100.3.16.2', 'end': '100.3.16.62'}], 'gateway_ip': '100.3.16.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '100.3.16.2', 'tags': [], 'project_id': '55728823c529402a8a7078720eeac77d'}} 2026-09-28 02:48:57,988 - INFO - Created VN ctest-vn1-31511793 2026-09-28 02:48:58,011 - DEBUG - VN ctest-vn1-31511793 UUID is 9b127b68-d11e-46b2-8337-a241336ea539 2026-09-28 02:48:58,105 - DEBUG - Requesting: http://10.0.0.176:8082/domains 2026-09-28 02:48:58,114 - DEBUG - Requesting: http://10.0.0.176:8082/domain/6a7973b0-69cf-4d5f-a060-1b4d1311d404 2026-09-28 02:48:58,131 - DEBUG - Requesting: http://10.0.0.176:8082/project/55728823-c529-402a-8a70-78720eeac77d 2026-09-28 02:48:58,253 - DEBUG - Requesting: http://10.0.0.176:8082/virtual-network/9b127b68-d11e-46b2-8337-a241336ea539 2026-09-28 02:48:58,267 - DEBUG - Requesting: http://10.0.0.176:8082/virtual-network/9b127b68-d11e-46b2-8337-a241336ea539 2026-09-28 02:48:58,277 - DEBUG - Requesting: http://10.0.0.176:8082/routing-instance/eb0ce267-64d1-4c9a-b655-86418972bb3d 2026-09-28 02:48:58,288 - DEBUG - Requesting: http://10.0.0.176:8082/routing-instance/eb0ce267-64d1-4c9a-b655-86418972bb3d 2026-09-28 02:48:58,298 - DEBUG - Requesting: http://10.0.0.176:8082/route-target/e6b84613-8fdf-4f41-84fa-8d3ba47781c6 2026-09-28 02:48:58,307 - DEBUG - Route Targets: ['target:64512:8000005'] 2026-09-28 02:48:58,307 - DEBUG - Requesting: http://10.0.0.176:8082/virtual-network/9b127b68-d11e-46b2-8337-a241336ea539 2026-09-28 02:48:58,320 - DEBUG - Requesting: http://10.0.0.176:8082/routing-instance/eb0ce267-64d1-4c9a-b655-86418972bb3d 2026-09-28 02:48:58,340 - INFO - Verified VN network id 10 for VN 9b127b68-d11e-46b2-8337-a241336ea539 2026-09-28 02:48:58,340 - INFO - Verifications in API Server for VN ctest-vn1-31511793 passed 2026-09-28 02:48:58,340 - DEBUG - Requesting: http://10.0.0.176:8082/virtual-network/9b127b68-d11e-46b2-8337-a241336ea539 2026-09-28 02:48:58,359 - DEBUG - Requesting: http://10.0.0.176:8082/routing-instance/eb0ce267-64d1-4c9a-b655-86418972bb3d 2026-09-28 02:48:58,371 - DEBUG - Requesting: http://10.0.0.176:8082/routing-instance/eb0ce267-64d1-4c9a-b655-86418972bb3d 2026-09-28 02:48:58,383 - DEBUG - Requesting: http://10.0.0.176:8082/route-target/e6b84613-8fdf-4f41-84fa-8d3ba47781c6 2026-09-28 02:48:58,415 - DEBUG - Control-node 10.0.0.176 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-FloatingipBasicTestSanity-54630985:ctest-vn1-31511793', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-FloatingipBasicTestSanity-54630985', 'owner-access': '7', 'group': 'manager', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11174129315574859442', 'uuid-lslong': '9455204343607108921'}, 'enable': 'true', 'created': '2026-09-28T02:48:57', 'last-modified': '2026-09-28T02:48:57', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.542109'} 2026-09-28 02:48:58,431 - DEBUG - Route Targets: ['target:64512:8000005'] 2026-09-28 02:48:58,438 - DEBUG - Control-node 10.0.0.177 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-FloatingipBasicTestSanity-54630985:ctest-vn1-31511793', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-FloatingipBasicTestSanity-54630985', 'owner-access': '7', 'group': 'manager', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11174129315574859442', 'uuid-lslong': '9455204343607108921'}, 'enable': 'true', 'created': '2026-09-28T02:48:57', 'last-modified': '2026-09-28T02:48:57', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.578733'} 2026-09-28 02:48:58,445 - DEBUG - Route Targets: ['target:64512:8000005'] 2026-09-28 02:48:58,454 - DEBUG - Control-node 10.0.0.161 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-FloatingipBasicTestSanity-54630985:ctest-vn1-31511793', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-FloatingipBasicTestSanity-54630985', 'owner-access': '7', 'group': 'manager', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11174129315574859442', 'uuid-lslong': '9455204343607108921'}, 'enable': 'true', 'created': '2026-09-28T02:48:57', 'last-modified': '2026-09-28T02:48:57', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.588410'} 2026-09-28 02:48:58,465 - DEBUG - Route Targets: ['target:64512:8000005'] 2026-09-28 02:48:58,466 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn1-31511793 passed 2026-09-28 02:48:58,467 - DEBUG - ====Verifying policy data for ctest-vn1-31511793 in API_Server ====== 2026-09-28 02:48:58,467 - DEBUG - Requesting: http://10.0.0.176:8082/domains 2026-09-28 02:48:58,488 - DEBUG - Requesting: http://10.0.0.176:8082/domain/6a7973b0-69cf-4d5f-a060-1b4d1311d404 2026-09-28 02:48:58,506 - DEBUG - Requesting: http://10.0.0.176:8082/project/55728823-c529-402a-8a70-78720eeac77d 2026-09-28 02:48:58,614 - DEBUG - Requesting: http://10.0.0.176:8082/virtual-network/9b127b68-d11e-46b2-8337-a241336ea539 2026-09-28 02:48:58,630 - DEBUG - =>VN ctest-vn1-31511793 has no policy to be verified 2026-09-28 02:48:58,630 - DEBUG - Verifying the vn in opserver 2026-09-28 02:48:58,630 - DEBUG - Verifying the default-domain:ctest-FloatingipBasicTestSanity-54630985:ctest-vn1-31511793 virtual network link through opserver 10.0.0.176 2026-09-28 02:48:58,630 - DEBUG - Requesting: http://10.0.0.176:8081/analytics/uves/virtual-networks 2026-09-28 02:48:58,742 - DEBUG - vn link and name as {'name': 'default-domain:ctest-FloatingipBasicTestSanity-54630985:ctest-vn1-31511793', 'href': 'http://10.0.0.176:8081/analytics/uves/virtual-network/default-domain:ctest-FloatingipBasicTestSanity-54630985:ctest-vn1-31511793?flat'} 2026-09-28 02:48:58,742 - DEBUG - Verifying the default-domain:ctest-FloatingipBasicTestSanity-54630985:ctest-vn1-31511793 virtual network link through opserver 10.0.0.177 2026-09-28 02:48:58,742 - DEBUG - Requesting: http://10.0.0.177:8081/analytics/uves/virtual-networks 2026-09-28 02:48:58,810 - DEBUG - vn link and name as {'name': 'default-domain:ctest-FloatingipBasicTestSanity-54630985:ctest-vn1-31511793', 'href': 'http://10.0.0.177:8081/analytics/uves/virtual-network/default-domain:ctest-FloatingipBasicTestSanity-54630985:ctest-vn1-31511793?flat'} 2026-09-28 02:48:58,810 - DEBUG - Verifying the default-domain:ctest-FloatingipBasicTestSanity-54630985:ctest-vn1-31511793 virtual network link through opserver 10.0.0.161 2026-09-28 02:48:58,810 - DEBUG - Requesting: http://10.0.0.161:8081/analytics/uves/virtual-networks 2026-09-28 02:48:58,891 - DEBUG - vn link and name as {'name': 'default-domain:ctest-FloatingipBasicTestSanity-54630985:ctest-vn1-31511793', 'href': 'http://10.0.0.161:8081/analytics/uves/virtual-network/default-domain:ctest-FloatingipBasicTestSanity-54630985:ctest-vn1-31511793?flat'} 2026-09-28 02:48:58,891 - INFO - Validated that VN default-domain:ctest-FloatingipBasicTestSanity-54630985:ctest-vn1-31511793 is found in opserver 2026-09-28 02:48:58,924 - DEBUG - Do not have enough data to verify VN in agent 2026-09-28 02:48:58,940 - DEBUG - VRF ids for VN ctest-vn1-31511793: {} 2026-09-28 02:48:59,060 - DEBUG - Image by name ubuntu not found 2026-09-28 02:48:59,060 - DEBUG - Installing image ubuntu 2026-09-28 02:48:59,060 - DEBUG - Image info {'name': 'ubuntu-traffic.vmdk.gz', 'webserver': '', 'location': '/images/converts/', 'username': 'ubuntu', 'password': 'ubuntu', 'type': 'os', 'flavor': 'contrail_flavor_small', 'params': '--container-format bare --disk-format vmdk --property vmware_disktype="sparse" --property vmware_adaptertype="ide" --property hw_scsi_model=virtio-scsi --property hw_disk_bus_model=virtio-scsi --property hw_disk_bus=virtio', 'name_docker': 'ubuntu-traffic-docker', 'vmdk': 'ubuntu-traffic-disk1.vmdk', 'shrinked_vmdk': "'no'"} 2026-09-28 02:48:59,061 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/converts//ubuntu-traffic.vmdk.gz 2026-09-28 02:48:59,061 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/converts//ubuntu-traffic.vmdk.gz 2026-09-28 02:48:59,076 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: wget -nv http://nexus.opensdn.io/repository///images/converts//ubuntu-traffic.vmdk.gz -O /contrail-test/images/ubuntu-traffic.vmdk.gz 2026-09-28 02:49:04,275 - DEBUG - Image has been downloaded to /contrail-test/images/ubuntu-traffic.vmdk.gz 2026-09-28 02:49:04,276 - DEBUG - Unzip image 2026-09-28 02:49:04,276 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/ubuntu-traffic.vmdk.gz 2026-09-28 02:49:22,805 - DEBUG - Try to create image in user context 2026-09-28 02:49:36,148 - DEBUG - Image has been created in user context 2026-09-28 02:49:36,148 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: rm -f /contrail-test/images/ubuntu-traffic.vmdk 2026-09-28 02:49:36,395 - DEBUG - Downloaded image has been removed 2026-09-28 02:49:36,415 - DEBUG - Uploaded image status = active 2026-09-28 02:49:37,033 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-6324-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-6324-2) 2026-09-28 02:49:37,653 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-6324-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-6324-1) 2026-09-28 02:49:37,821 - INFO - Creating Floating IP pool ctest-some-pool-63789562 in API Server 2026-09-28 02:49:37,988 - DEBUG - Requesting: http://10.0.0.176:8082/domains 2026-09-28 02:49:38,001 - DEBUG - Requesting: http://10.0.0.176:8082/domain/6a7973b0-69cf-4d5f-a060-1b4d1311d404 2026-09-28 02:49:38,025 - DEBUG - Requesting: http://10.0.0.176:8082/project/55728823-c529-402a-8a70-78720eeac77d 2026-09-28 02:49:38,174 - DEBUG - Requesting: http://10.0.0.176:8082/virtual-network/3b05f6a8-702b-49b1-bf6a-b802575de51e 2026-09-28 02:49:38,192 - DEBUG - Requesting: http://10.0.0.176:8082/floating-ip-pool/ebb232b5-8784-46df-93a8-94b5bbfc358e 2026-09-28 02:49:38,208 - DEBUG - Requesting: http://10.0.0.176:8082/domains 2026-09-28 02:49:38,217 - DEBUG - Requesting: http://10.0.0.176:8082/domain/6a7973b0-69cf-4d5f-a060-1b4d1311d404 2026-09-28 02:49:38,236 - DEBUG - Requesting: http://10.0.0.176:8082/project/55728823-c529-402a-8a70-78720eeac77d 2026-09-28 02:49:38,361 - DEBUG - Requesting: http://10.0.0.176:8082/virtual-network/3b05f6a8-702b-49b1-bf6a-b802575de51e 2026-09-28 02:49:38,378 - DEBUG - FIP Pool ctest-some-pool-63789562 found in API Server 2026-09-28 02:49:38,378 - INFO - Verification for FIP pool ctest-some-pool-63789562 in API Server passed 2026-09-28 02:49:38,400 - DEBUG - Control-node Ifmap-view has FIP pool ctest-some-pool-63789562 information 2026-09-28 02:49:38,407 - DEBUG - Control-node Ifmap-view has FIP pool ctest-some-pool-63789562 information 2026-09-28 02:49:38,415 - DEBUG - Control-node Ifmap-view has FIP pool ctest-some-pool-63789562 information 2026-09-28 02:49:38,416 - INFO - Verification for FIP pool ctest-some-pool-63789562 in Control-node passed 2026-09-28 02:49:38,416 - INFO - Waiting for VM ctest-vn1_vm1_name-66233917 to be up.. 2026-09-28 02:49:38,491 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-28 02:49:43,604 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-28 02:49:48,748 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-28 02:49:53,882 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-28 02:49:58,986 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-28 02:50:04,086 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-28 02:50:09,194 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-28 02:50:14,303 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-28 02:50:19,404 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-28 02:50:24,505 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-28 02:50:29,635 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-28 02:50:34,735 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-28 02:50:39,870 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-28 02:50:44,973 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-28 02:50:50,066 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-28 02:50:55,180 - DEBUG - VM is in ACTIVE state now 2026-09-28 02:50:55,180 - INFO - VM name : ctest-vn1_vm1_name-66233917 2026-09-28 02:50:55,282 - DEBUG - VM ctest-vn1_vm1_name-66233917 ID is 77b50049-f5df-45c6-9b3f-cc2fa51e30be 2026-09-28 02:50:55,312 - DEBUG - VM ctest-vn1_vm1_name-66233917 launched on Node an-jenkins-deploy-platform-ansible-os-6324-2 2026-09-28 02:50:55,413 - DEBUG - Requesting: http://10.0.0.161:8082/virtual-machine/77b50049-f5df-45c6-9b3f-cc2fa51e30be 2026-09-28 02:50:55,800 - DEBUG - Requesting: http://10.0.0.161:8082/virtual-machine/77b50049-f5df-45c6-9b3f-cc2fa51e30be 2026-09-28 02:50:55,836 - DEBUG - Requesting: http://10.0.0.161:8082/virtual-machine-interface/a1133eac-dea7-4c9e-b716-fcf8f9bb7cc0 2026-09-28 02:50:58,995 - 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') 2026-09-28 02:50:58,995 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1_vm1_name-66233917 failed! 2026-09-28 02:50:59,014 - DEBUG - Gateway for vn default-domain:ctest-FloatingipBasicTestSanity-54630985:ctest-vn1-31511793 is 100.3.16.1 and allocation pool is NOT set 2026-09-28 02:51:03,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 1017ms') 2026-09-28 02:51:03,060 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1_vm1_name-66233917 failed! 2026-09-28 02:51:03,078 - DEBUG - Gateway for vn default-domain:ctest-FloatingipBasicTestSanity-54630985:ctest-vn1-31511793 is 100.3.16.1 and allocation pool is NOT set 2026-09-28 02:51:07,118 - 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 1020ms') 2026-09-28 02:51:07,118 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1_vm1_name-66233917 failed! 2026-09-28 02:51:07,134 - DEBUG - Gateway for vn default-domain:ctest-FloatingipBasicTestSanity-54630985:ctest-vn1-31511793 is 100.3.16.1 and allocation pool is NOT set 2026-09-28 02:51:11,187 - 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 1028ms') 2026-09-28 02:51:11,187 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1_vm1_name-66233917 failed! 2026-09-28 02:51:11,205 - DEBUG - Gateway for vn default-domain:ctest-FloatingipBasicTestSanity-54630985:ctest-vn1-31511793 is 100.3.16.1 and allocation pool is NOT set 2026-09-28 02:51:15,252 - 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 1022ms') 2026-09-28 02:51:15,252 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1_vm1_name-66233917 failed! 2026-09-28 02:51:15,269 - DEBUG - Gateway for vn default-domain:ctest-FloatingipBasicTestSanity-54630985:ctest-vn1-31511793 is 100.3.16.1 and allocation pool is NOT set 2026-09-28 02:51:19,317 - 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 1022ms') 2026-09-28 02:51:19,317 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1_vm1_name-66233917 failed! 2026-09-28 02:51:19,333 - DEBUG - Gateway for vn default-domain:ctest-FloatingipBasicTestSanity-54630985:ctest-vn1-31511793 is 100.3.16.1 and allocation pool is NOT set 2026-09-28 02:51:23,377 - 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 1016ms') 2026-09-28 02:51:23,377 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1_vm1_name-66233917 failed! 2026-09-28 02:51:23,395 - DEBUG - Gateway for vn default-domain:ctest-FloatingipBasicTestSanity-54630985:ctest-vn1-31511793 is 100.3.16.1 and allocation pool is NOT set 2026-09-28 02:51:25,432 - 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=8.54 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=9.85 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1005ms\r\nrtt min/avg/max/mdev = 8.539/9.192/9.845/0.653 ms') 2026-09-28 02:51:25,432 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1_vm1_name-66233917 passed 2026-09-28 02:51:25,508 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-09-28 02:51:25,508 - DEBUG - Waiting to SSH to VM ctest-vn1_vm1_name-66233917, IP 100.3.16.3, Port 22 2026-09-28 02:51:25,533 - DEBUG - Error on ssh to ubuntu@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-28 02:51:25,645 - DEBUG - VM ctest-vn1_vm1_name-66233917 is NOT ready for SSH connections, VM status: ACTIVE 2026-09-28 02:51:30,646 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-09-28 02:51:30,647 - DEBUG - Waiting to SSH to VM ctest-vn1_vm1_name-66233917, IP 100.3.16.3, Port 22 2026-09-28 02:51:30,671 - DEBUG - Error on ssh to ubuntu@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-28 02:51:30,780 - DEBUG - VM ctest-vn1_vm1_name-66233917 is NOT ready for SSH connections, VM status: ACTIVE 2026-09-28 02:51:35,781 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-09-28 02:51:35,781 - DEBUG - Waiting to SSH to VM ctest-vn1_vm1_name-66233917, IP 100.3.16.3, Port 22 2026-09-28 02:51:35,807 - DEBUG - Error on ssh to ubuntu@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-28 02:51:35,916 - DEBUG - VM ctest-vn1_vm1_name-66233917 is NOT ready for SSH connections, VM status: ACTIVE 2026-09-28 02:51:40,917 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-09-28 02:51:40,917 - DEBUG - Waiting to SSH to VM ctest-vn1_vm1_name-66233917, IP 100.3.16.3, Port 22 2026-09-28 02:51:41,007 - DEBUG - VM ctest-vn1_vm1_name-66233917 is ready for SSH connections 2026-09-28 02:51:41,177 - DEBUG - Created Floating IP : ('58.88.150.4', '0b69d9ca-2047-4b38-8b5f-cd8c1be6ca42') 2026-09-28 02:51:41,177 - DEBUG - Associating FIP 58.88.150.4 to 77b50049-f5df-45c6-9b3f-cc2fa51e30be 2026-09-28 02:51:41,219 - DEBUG - Associating FIP ID 0b69d9ca-2047-4b38-8b5f-cd8c1be6ca42 with Port ID a1133eac-dea7-4c9e-b716-fcf8f9bb7cc0 2026-09-28 02:51:41,337 - INFO - Waiting for VM ctest-fvn_vm1_name-04124670 to be up.. 2026-09-28 02:51:41,447 - DEBUG - VM is in ACTIVE state now 2026-09-28 02:51:41,447 - INFO - VM name : ctest-fvn_vm1_name-04124670 2026-09-28 02:51:41,550 - DEBUG - VM ctest-fvn_vm1_name-04124670 ID is 560db0e9-2899-4a14-8cb6-efaaa361ea34 2026-09-28 02:51:41,550 - DEBUG - VM ctest-fvn_vm1_name-04124670 launched on Node an-jenkins-deploy-platform-ansible-os-6324-1 2026-09-28 02:51:41,650 - DEBUG - Requesting: http://10.0.0.161:8082/virtual-machine/560db0e9-2899-4a14-8cb6-efaaa361ea34 2026-09-28 02:51:41,662 - DEBUG - Requesting: http://10.0.0.161:8082/virtual-machine-interface/e1c83aba-8d60-48e2-beba-bcca08c995f1 2026-09-28 02:51:42,802 - 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.12 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=2.79 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 2.789/3.954/5.119/1.165 ms') 2026-09-28 02:51:42,802 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-fvn_vm1_name-04124670 passed 2026-09-28 02:51:42,880 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-09-28 02:51:42,880 - DEBUG - Waiting to SSH to VM ctest-fvn_vm1_name-04124670, IP 58.88.150.3, Port 22 2026-09-28 02:51:42,975 - DEBUG - VM ctest-fvn_vm1_name-04124670 is ready for SSH connections 2026-09-28 02:51:43,176 - INFO - Route for FIP 58.88.150.4 is fine on Control-node 10.0.0.176 2026-09-28 02:51:43,186 - INFO - Route for FIP 58.88.150.4 is fine on Control-node 10.0.0.177 2026-09-28 02:51:43,186 - INFO - FIP 58.88.150.4 verification for passed on all Control-nodes 2026-09-28 02:51:43,186 - INFO - FIP ID 0b69d9ca-2047-4b38-8b5f-cd8c1be6ca42(58.88.150.4) validation in Control node passed 2026-09-28 02:51:43,257 - DEBUG - agent_label query returned:{'nh': {'type': 'tunnel', 'ref_count': '15', 'valid': 'true', 'policy': 'disabled', 'sip': '10.20.0.14', 'dip': '10.20.0.30', 'vrf': 'default-domain:default-project:ip-fabric:__default__', 'mac': ['fa:16:3e:f2:c7:ae'], 'tunnel_type': 'MPLSoUDP', 'nh_index': '22', 'vxlan_flag': 'false', 'intf_flags': '0', '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.25', 'dest_vn_list': ['default-domain:ctest-FloatingipBasicTestSanity-54630985:ctest-fvn-05560044'], '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-09-28 02:51:43,257 - DEBUG - Route for FIP IP 58.88.150.4 is present in agent 10.0.0.38 2026-09-28 02:51:43,257 - DEBUG - FIP 58.88.150.4 verification for VM ctest-vn1_vm1_name-66233917 in Agent 10.0.0.38 passed 2026-09-28 02:51:43,283 - DEBUG - agent_label query returned:{'nh': {'type': 'interface', 'ref_count': '38', 'valid': 'true', 'policy': 'enabled', 'itf': 'tapa1133eac-de', 'mac': ['2:a1:13:3e:ac:de'], 'mcast': 'disabled', 'nh_index': '30', '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.25', 'dest_vn_list': ['default-domain:ctest-FloatingipBasicTestSanity-54630985:ctest-fvn-05560044'], '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-09-28 02:51:43,283 - DEBUG - Route for FIP IP 58.88.150.4 is present in agent 10.0.0.162 2026-09-28 02:51:43,283 - DEBUG - FIP 58.88.150.4 verification for VM ctest-vn1_vm1_name-66233917 in Agent 10.0.0.162 passed 2026-09-28 02:51:43,283 - INFO - FIP ID 0b69d9ca-2047-4b38-8b5f-cd8c1be6ca42(58.88.150.4) validation in agents passed 2026-09-28 02:51:43,283 - DEBUG - Requesting: http://10.0.0.176:8082/floating-ip/0b69d9ca-2047-4b38-8b5f-cd8c1be6ca42 2026-09-28 02:51:43,296 - DEBUG - FIP 0b69d9ca-2047-4b38-8b5f-cd8c1be6ca42 is present in API server 2026-09-28 02:51:43,296 - INFO - FIP ID 0b69d9ca-2047-4b38-8b5f-cd8c1be6ca42(58.88.150.4) validation in API Server passed 2026-09-28 02:51:43,296 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-09-28 02:51:43,296 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 58.88.150.3, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.162, gateway password: c0ntrail123 2026-09-28 02:51:43,297 - DEBUG - ping -s 56 -c 3 -W 1 58.88.150.3 2026-09-28 02:51:53,088 - DEBUG - PING 58.88.150.3 (58.88.150.3) 56(84) bytes of data. 64 bytes from 58.88.150.3: icmp_req=1 ttl=64 time=17.5 ms 64 bytes from 58.88.150.3: icmp_req=2 ttl=64 time=1.80 ms 64 bytes from 58.88.150.3: icmp_req=3 ttl=64 time=2.97 ms --- 58.88.150.3 ping statistics --- 3 packets transmitted, 3 received, 0% packet loss, time 2003ms rtt min/avg/max/mdev = 1.803/7.455/17.594/7.185 ms 2026-09-28 02:51:53,088 - INFO - Ping to IP 58.88.150.3 from VM ctest-vn1_vm1_name-66233917 passed 2026-09-28 02:51:53,088 - DEBUG - Disassociating port from FIP ID : 0b69d9ca-2047-4b38-8b5f-cd8c1be6ca42 2026-09-28 02:51:53,215 - DEBUG - Deleting FIP ID 0b69d9ca-2047-4b38-8b5f-cd8c1be6ca42 2026-09-28 02:51:53,310 - INFO - Deleting the FIP pool ctest-some-pool-63789562 2026-09-28 02:51:53,460 - DEBUG - Control-node Ifmap-view does not have FIP pool ctest-some-pool-63789562 information 2026-09-28 02:51:53,467 - DEBUG - Control-node Ifmap-view does not have FIP pool ctest-some-pool-63789562 information 2026-09-28 02:51:53,474 - DEBUG - Control-node Ifmap-view does not have FIP pool ctest-some-pool-63789562 information 2026-09-28 02:51:53,474 - INFO - Deleting VM ctest-fvn_vm1_name-04124670 2026-09-28 02:51:53,561 - INFO - Deleting VM ctest-vn1_vm1_name-66233917 2026-09-28 02:51:53,646 - INFO - Deleting VN ctest-vn1-31511793 2026-09-28 02:51:53,697 - DEBUG - VN 9b127b68-d11e-46b2-8337-a241336ea539 still in use: Unable to complete operation on network 9b127b68-d11e-46b2-8337-a241336ea539. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-b56471de-0619-4dc2-a9c7-12803eb07b21'] 2026-09-28 02:51:53,697 - WARNING - Deleting VN ctest-vn1-31511793 failed..Will retry 2026-09-28 02:51:55,783 - DEBUG - VN 9b127b68-d11e-46b2-8337-a241336ea539 still in use: Unable to complete operation on network 9b127b68-d11e-46b2-8337-a241336ea539. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-7cba0cb5-ee8c-49f6-8f53-ae321dffca38'] 2026-09-28 02:51:55,783 - WARNING - Deleting VN ctest-vn1-31511793 failed..Will retry 2026-09-28 02:51:58,004 - DEBUG - Response for deleting network () 2026-09-28 02:51:58,004 - DEBUG - Requesting: http://10.0.0.176:8082/routing-instance/eb0ce267-64d1-4c9a-b655-86418972bb3d 2026-09-28 02:51:58,015 - DEBUG - Response Code: 404 2026-09-28 02:51:58,015 - DEBUG - Requesting: http://10.0.0.176:8082/domains 2026-09-28 02:51:58,025 - DEBUG - Requesting: http://10.0.0.176:8082/domain/6a7973b0-69cf-4d5f-a060-1b4d1311d404 2026-09-28 02:51:58,055 - DEBUG - Requesting: http://10.0.0.176:8082/project/55728823-c529-402a-8a70-78720eeac77d 2026-09-28 02:51:58,185 - INFO - Validated that VN ctest-vn1-31511793 is not found in API Server 2026-09-28 02:51:58,199 - DEBUG - VN ctest-vn1-31511793 is not present in Agent 10.0.0.38 2026-09-28 02:51:58,213 - DEBUG - VN ctest-vn1-31511793 is not present in Agent 10.0.0.162 2026-09-28 02:51:58,213 - INFO - Validated that VN ctest-vn1-31511793 is not in any agent 2026-09-28 02:51:58,264 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn1-31511793 info 2026-09-28 02:51:58,264 - INFO - Deleting VN ctest-fvn-05560044 2026-09-28 02:51:58,472 - DEBUG - Response for deleting network () 2026-09-28 02:51:58,473 - DEBUG - Requesting: http://10.0.0.176:8082/routing-instance/351217a5-9721-4bce-9fe2-2ccc2ea762c8 2026-09-28 02:51:58,479 - DEBUG - Response Code: 404 2026-09-28 02:51:58,480 - DEBUG - Requesting: http://10.0.0.176:8082/domains 2026-09-28 02:51:58,488 - DEBUG - Requesting: http://10.0.0.176:8082/domain/6a7973b0-69cf-4d5f-a060-1b4d1311d404 2026-09-28 02:51:58,520 - DEBUG - Requesting: http://10.0.0.176:8082/project/55728823-c529-402a-8a70-78720eeac77d 2026-09-28 02:51:58,642 - INFO - Validated that VN ctest-fvn-05560044 is not found in API Server 2026-09-28 02:51:58,656 - DEBUG - VN ctest-fvn-05560044 is not present in Agent 10.0.0.38 2026-09-28 02:51:58,676 - DEBUG - VN ctest-fvn-05560044 is not present in Agent 10.0.0.162 2026-09-28 02:51:58,676 - INFO - Validated that VN ctest-fvn-05560044 is not in any agent 2026-09-28 02:51:58,750 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-fvn-05560044 info 2026-09-28 02:52:00,092 - DEBUG - No XMPP flaps were noticed during the test
2026-09-28 02:52:00,093 - INFO - END TEST : test_floating_ip : PASSED[0:03:08]
2026-09-28 02:52:00,093 - INFO - -------------------------------------------------------------------------------- 2026-09-28 02:52:01,122 - INFO - Deleted project: ctest-FloatingipBasicTestSanity-54630985, ID : 55728823-c529-402a-8a70-78720eeac77d