2025-01-10 09:41:52,124 - INFO - Domain Default found not creating 2025-01-10 09:41:52,293 - INFO - Project ctest-FloatingipBasicTestSanity-44244481 not found, creating it 2025-01-10 09:41:53,015 - INFO - Created Project:ctest-FloatingipBasicTestSanity-44244481, ID : bcc7f3f4-6ff8-4621-8d5a-76676b856d28 2025-01-10 09:41:54,670 - INFO - Using existing project ['default-domain', 'admin'](72e7df02-993e-4a71-887b-37b7bfb5fefd) 2025-01-10 09:41:55,450 - INFO - Adding rules to the default security group in Project admin 2025-01-10 09:41:55,806 - INFO - ================================================================================ 2025-01-10 09:41:55,806 - INFO - STARTING TEST : test_floating_ip 2025-01-10 09:41:55,806 - 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. 2025-01-10 09:41:56,485 - DEBUG - Nothing to compare xmpp stats {'10.0.0.37': {'10.20.0.5': '0', '10.20.0.12': '0'}, '10.0.0.33': {'10.20.0.5': '0', '10.20.0.12': '0'}} with 2025-01-10 09:41:56,485 - INFO - Initial checks done. Running the testcase now 2025-01-10 09:41:56,485 - INFO - 2025-01-10 09:41:56,515 - DEBUG - Services list from nova: [, , , ] 2025-01-10 09:41:56,515 - DEBUG - Hosts: {'nova': ['an-jenkins-deploy-platform-ansible-os-2598-2', 'an-jenkins-deploy-platform-ansible-os-2598-1']} 2025-01-10 09:41:57,302 - DEBUG - Response for create_network : {'network': {'id': '03262bcf-073e-49a7-9516-46630b29334b', 'name': 'ctest-fvn-98630718', 'tenant_id': 'bcc7f3f46ff846218d5a76676b856d28', 'project_id': 'bcc7f3f46ff846218d5a76676b856d28', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-FloatingipBasicTestSanity-44244481', 'ctest-fvn-98630718'], 'port_security_enabled': True, 'description': ''}} 2025-01-10 09:41:57,585 - DEBUG - Response for create_subnet : {'subnet': {'id': 'a7e3c546-1327-4d5b-a95a-78f8f3de441a', 'name': '', 'tenant_id': 'bcc7f3f46ff846218d5a76676b856d28', 'network_id': '03262bcf-073e-49a7-9516-46630b29334b', 'ip_version': 4, 'cidr': '87.32.191.128/26', 'allocation_pools': [{'start': '87.32.191.130', 'end': '87.32.191.190'}], 'gateway_ip': '87.32.191.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '87.32.191.130', 'tags': [], 'project_id': 'bcc7f3f46ff846218d5a76676b856d28'}} 2025-01-10 09:41:57,613 - INFO - Created VN ctest-fvn-98630718 2025-01-10 09:41:57,629 - DEBUG - VN ctest-fvn-98630718 UUID is 03262bcf-073e-49a7-9516-46630b29334b 2025-01-10 09:41:57,729 - DEBUG - Requesting: http://10.0.0.30:8082/domains 2025-01-10 09:41:58,069 - DEBUG - Requesting: http://10.0.0.30:8082/domains 2025-01-10 09:41:58,104 - DEBUG - Requesting: http://10.0.0.30:8082/domain/9d97cda5-b041-47d1-8a65-cf8886a92f43 2025-01-10 09:41:58,127 - DEBUG - Requesting: http://10.0.0.30:8082/project/bcc7f3f4-6ff8-4621-8d5a-76676b856d28 2025-01-10 09:41:58,254 - DEBUG - Requesting: http://10.0.0.30:8082/virtual-network/03262bcf-073e-49a7-9516-46630b29334b 2025-01-10 09:41:58,269 - DEBUG - Requesting: http://10.0.0.30:8082/virtual-network/03262bcf-073e-49a7-9516-46630b29334b 2025-01-10 09:41:58,280 - DEBUG - Requesting: http://10.0.0.30:8082/routing-instance/7cee7a62-cd04-4b00-a839-04021ad6bdff 2025-01-10 09:41:58,299 - DEBUG - Requesting: http://10.0.0.30:8082/routing-instance/7cee7a62-cd04-4b00-a839-04021ad6bdff 2025-01-10 09:41:58,309 - DEBUG - Requesting: http://10.0.0.30:8082/route-target/e45e4e06-7c16-4eb4-a3f0-25d5fc1e112d 2025-01-10 09:41:58,319 - DEBUG - Route Targets: ['target:64512:8000005'] 2025-01-10 09:41:58,319 - DEBUG - Requesting: http://10.0.0.30:8082/virtual-network/03262bcf-073e-49a7-9516-46630b29334b 2025-01-10 09:41:58,332 - DEBUG - Requesting: http://10.0.0.30:8082/routing-instance/7cee7a62-cd04-4b00-a839-04021ad6bdff 2025-01-10 09:41:58,355 - INFO - Verified VN network id 6 for VN 03262bcf-073e-49a7-9516-46630b29334b 2025-01-10 09:41:58,356 - INFO - Verifications in API Server for VN ctest-fvn-98630718 passed 2025-01-10 09:41:58,356 - DEBUG - Requesting: http://10.0.0.30:8082/virtual-network/03262bcf-073e-49a7-9516-46630b29334b 2025-01-10 09:41:58,370 - DEBUG - Requesting: http://10.0.0.30:8082/routing-instance/7cee7a62-cd04-4b00-a839-04021ad6bdff 2025-01-10 09:41:58,380 - DEBUG - Requesting: http://10.0.0.30:8082/routing-instance/7cee7a62-cd04-4b00-a839-04021ad6bdff 2025-01-10 09:41:58,390 - DEBUG - Requesting: http://10.0.0.30:8082/route-target/e45e4e06-7c16-4eb4-a3f0-25d5fc1e112d 2025-01-10 09:41:58,409 - DEBUG - Control-node 10.0.0.30 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-FloatingipBasicTestSanity-44244481:ctest-fvn-98630718', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-FloatingipBasicTestSanity-44244481', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '226916999408535975', 'uuid-lslong': '10742851352341853003'}, 'enable': 'true', 'created': '2025-01-10T09:41:57', 'last-modified': '2025-01-10T09:41:57', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.904227'} 2025-01-10 09:41:58,417 - DEBUG - Route Targets: ['target:64512:8000005'] 2025-01-10 09:41:58,424 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-FloatingipBasicTestSanity-44244481:ctest-fvn-98630718', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-FloatingipBasicTestSanity-44244481', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '226916999408535975', 'uuid-lslong': '10742851352341853003'}, 'enable': 'true', 'created': '2025-01-10T09:41:57', 'last-modified': '2025-01-10T09:41:57', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.915989'} 2025-01-10 09:41:58,432 - DEBUG - Route Targets: ['target:64512:8000005'] 2025-01-10 09:41:58,442 - DEBUG - Control-node 10.0.0.31 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-FloatingipBasicTestSanity-44244481:ctest-fvn-98630718', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-FloatingipBasicTestSanity-44244481', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '226916999408535975', 'uuid-lslong': '10742851352341853003'}, 'enable': 'true', 'created': '2025-01-10T09:41:57', 'last-modified': '2025-01-10T09:41:57', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.938341'} 2025-01-10 09:41:58,451 - DEBUG - Route Targets: ['target:64512:8000005'] 2025-01-10 09:41:58,451 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-fvn-98630718 passed 2025-01-10 09:41:58,451 - DEBUG - ====Verifying policy data for ctest-fvn-98630718 in API_Server ====== 2025-01-10 09:41:58,451 - DEBUG - Requesting: http://10.0.0.30:8082/domains 2025-01-10 09:41:58,460 - DEBUG - Requesting: http://10.0.0.30:8082/domain/9d97cda5-b041-47d1-8a65-cf8886a92f43 2025-01-10 09:41:58,477 - DEBUG - Requesting: http://10.0.0.30:8082/project/bcc7f3f4-6ff8-4621-8d5a-76676b856d28 2025-01-10 09:41:58,600 - DEBUG - Requesting: http://10.0.0.30:8082/virtual-network/03262bcf-073e-49a7-9516-46630b29334b 2025-01-10 09:41:58,616 - DEBUG - =>VN ctest-fvn-98630718 has no policy to be verified 2025-01-10 09:41:58,616 - DEBUG - Verifying the vn in opserver 2025-01-10 09:41:58,616 - DEBUG - Verifying the default-domain:ctest-FloatingipBasicTestSanity-44244481:ctest-fvn-98630718 virtual network link through opserver 10.0.0.30 2025-01-10 09:41:58,617 - DEBUG - Requesting: http://10.0.0.30:8081/analytics/uves/virtual-networks 2025-01-10 09:41:58,924 - DEBUG - Requesting: http://10.0.0.30:8081/analytics/uves/virtual-networks 2025-01-10 09:41:58,990 - DEBUG - vn link and name as {'name': 'default-domain:ctest-FloatingipBasicTestSanity-44244481:ctest-fvn-98630718', 'href': 'http://10.0.0.30:8081/analytics/uves/virtual-network/default-domain:ctest-FloatingipBasicTestSanity-44244481:ctest-fvn-98630718?flat'} 2025-01-10 09:41:58,990 - DEBUG - Verifying the default-domain:ctest-FloatingipBasicTestSanity-44244481:ctest-fvn-98630718 virtual network link through opserver 10.0.0.27 2025-01-10 09:41:58,990 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks 2025-01-10 09:41:59,316 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks 2025-01-10 09:41:59,388 - DEBUG - vn link and name as {'name': 'default-domain:ctest-FloatingipBasicTestSanity-44244481:ctest-fvn-98630718', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-FloatingipBasicTestSanity-44244481:ctest-fvn-98630718?flat'} 2025-01-10 09:41:59,388 - DEBUG - Verifying the default-domain:ctest-FloatingipBasicTestSanity-44244481:ctest-fvn-98630718 virtual network link through opserver 10.0.0.31 2025-01-10 09:41:59,389 - DEBUG - Requesting: http://10.0.0.31:8081/analytics/uves/virtual-networks 2025-01-10 09:41:59,714 - DEBUG - Requesting: http://10.0.0.31:8081/analytics/uves/virtual-networks 2025-01-10 09:41:59,785 - DEBUG - vn link and name as {'name': 'default-domain:ctest-FloatingipBasicTestSanity-44244481:ctest-fvn-98630718', 'href': 'http://10.0.0.31:8081/analytics/uves/virtual-network/default-domain:ctest-FloatingipBasicTestSanity-44244481:ctest-fvn-98630718?flat'} 2025-01-10 09:41:59,785 - INFO - Validated that VN default-domain:ctest-FloatingipBasicTestSanity-44244481:ctest-fvn-98630718 is found in opserver 2025-01-10 09:41:59,813 - DEBUG - Do not have enough data to verify VN in agent 2025-01-10 09:41:59,827 - DEBUG - VRF ids for VN ctest-fvn-98630718: {} 2025-01-10 09:42:00,195 - DEBUG - Response for create_network : {'network': {'id': 'e3d5476f-effe-4739-84c9-87c95e2891bc', 'name': 'ctest-vn1-15680884', 'tenant_id': 'bcc7f3f46ff846218d5a76676b856d28', 'project_id': 'bcc7f3f46ff846218d5a76676b856d28', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-FloatingipBasicTestSanity-44244481', 'ctest-vn1-15680884'], 'port_security_enabled': True, 'description': ''}} 2025-01-10 09:42:00,527 - DEBUG - Response for create_subnet : {'subnet': {'id': '9eba99df-e4a9-4cbb-8fcb-3f7d7854318b', 'name': '', 'tenant_id': 'bcc7f3f46ff846218d5a76676b856d28', 'network_id': 'e3d5476f-effe-4739-84c9-87c95e2891bc', 'ip_version': 4, 'cidr': '197.204.160.64/26', 'allocation_pools': [{'start': '197.204.160.66', 'end': '197.204.160.126'}], 'gateway_ip': '197.204.160.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '197.204.160.66', 'tags': [], 'project_id': 'bcc7f3f46ff846218d5a76676b856d28'}} 2025-01-10 09:42:00,562 - INFO - Created VN ctest-vn1-15680884 2025-01-10 09:42:00,585 - DEBUG - VN ctest-vn1-15680884 UUID is e3d5476f-effe-4739-84c9-87c95e2891bc 2025-01-10 09:42:00,683 - DEBUG - Requesting: http://10.0.0.30:8082/domains 2025-01-10 09:42:00,691 - DEBUG - Requesting: http://10.0.0.30:8082/domain/9d97cda5-b041-47d1-8a65-cf8886a92f43 2025-01-10 09:42:00,709 - DEBUG - Requesting: http://10.0.0.30:8082/project/bcc7f3f4-6ff8-4621-8d5a-76676b856d28 2025-01-10 09:42:00,835 - DEBUG - Requesting: http://10.0.0.30:8082/virtual-network/e3d5476f-effe-4739-84c9-87c95e2891bc 2025-01-10 09:42:00,851 - DEBUG - Requesting: http://10.0.0.30:8082/virtual-network/e3d5476f-effe-4739-84c9-87c95e2891bc 2025-01-10 09:42:00,868 - DEBUG - Requesting: http://10.0.0.30:8082/routing-instance/574c3e66-1b42-4289-af88-0330b95779c5 2025-01-10 09:42:00,882 - DEBUG - Requesting: http://10.0.0.30:8082/routing-instance/574c3e66-1b42-4289-af88-0330b95779c5 2025-01-10 09:42:00,896 - DEBUG - Requesting: http://10.0.0.30:8082/route-target/33692c41-cd43-4bdd-8c04-6f42fd36a8a2 2025-01-10 09:42:00,916 - DEBUG - Route Targets: ['target:64512:8000006'] 2025-01-10 09:42:00,916 - DEBUG - Requesting: http://10.0.0.30:8082/virtual-network/e3d5476f-effe-4739-84c9-87c95e2891bc 2025-01-10 09:42:00,933 - DEBUG - Requesting: http://10.0.0.30:8082/routing-instance/574c3e66-1b42-4289-af88-0330b95779c5 2025-01-10 09:42:00,958 - INFO - Verified VN network id 7 for VN e3d5476f-effe-4739-84c9-87c95e2891bc 2025-01-10 09:42:00,959 - INFO - Verifications in API Server for VN ctest-vn1-15680884 passed 2025-01-10 09:42:00,959 - DEBUG - Requesting: http://10.0.0.30:8082/virtual-network/e3d5476f-effe-4739-84c9-87c95e2891bc 2025-01-10 09:42:00,979 - DEBUG - Requesting: http://10.0.0.30:8082/routing-instance/574c3e66-1b42-4289-af88-0330b95779c5 2025-01-10 09:42:00,990 - DEBUG - Requesting: http://10.0.0.30:8082/routing-instance/574c3e66-1b42-4289-af88-0330b95779c5 2025-01-10 09:42:01,002 - DEBUG - Requesting: http://10.0.0.30:8082/route-target/33692c41-cd43-4bdd-8c04-6f42fd36a8a2 2025-01-10 09:42:01,033 - DEBUG - Control-node 10.0.0.30 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-FloatingipBasicTestSanity-44244481:ctest-vn1-15680884', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-FloatingipBasicTestSanity-44244481', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '16417106562742372153', 'uuid-lslong': '9568328182263222716'}, 'enable': 'true', 'created': '2025-01-10T09:42:00', 'last-modified': '2025-01-10T09:42:00', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.582787'} 2025-01-10 09:42:01,048 - DEBUG - Route Targets: ['target:64512:8000006'] 2025-01-10 09:42:01,055 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-FloatingipBasicTestSanity-44244481:ctest-vn1-15680884', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-FloatingipBasicTestSanity-44244481', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '16417106562742372153', 'uuid-lslong': '9568328182263222716'}, 'enable': 'true', 'created': '2025-01-10T09:42:00', 'last-modified': '2025-01-10T09:42:00', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.616460'} 2025-01-10 09:42:01,063 - DEBUG - Route Targets: ['target:64512:8000006'] 2025-01-10 09:42:01,072 - DEBUG - Control-node 10.0.0.31 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-FloatingipBasicTestSanity-44244481:ctest-vn1-15680884', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-FloatingipBasicTestSanity-44244481', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '16417106562742372153', 'uuid-lslong': '9568328182263222716'}, 'enable': 'true', 'created': '2025-01-10T09:42:00', 'last-modified': '2025-01-10T09:42:00', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.627835'} 2025-01-10 09:42:01,081 - DEBUG - Route Targets: ['target:64512:8000006'] 2025-01-10 09:42:01,081 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn1-15680884 passed 2025-01-10 09:42:01,081 - DEBUG - ====Verifying policy data for ctest-vn1-15680884 in API_Server ====== 2025-01-10 09:42:01,081 - DEBUG - Requesting: http://10.0.0.30:8082/domains 2025-01-10 09:42:01,091 - DEBUG - Requesting: http://10.0.0.30:8082/domain/9d97cda5-b041-47d1-8a65-cf8886a92f43 2025-01-10 09:42:01,106 - DEBUG - Requesting: http://10.0.0.30:8082/project/bcc7f3f4-6ff8-4621-8d5a-76676b856d28 2025-01-10 09:42:01,245 - DEBUG - Requesting: http://10.0.0.30:8082/virtual-network/e3d5476f-effe-4739-84c9-87c95e2891bc 2025-01-10 09:42:01,260 - DEBUG - =>VN ctest-vn1-15680884 has no policy to be verified 2025-01-10 09:42:01,260 - DEBUG - Verifying the vn in opserver 2025-01-10 09:42:01,260 - DEBUG - Verifying the default-domain:ctest-FloatingipBasicTestSanity-44244481:ctest-vn1-15680884 virtual network link through opserver 10.0.0.30 2025-01-10 09:42:01,261 - DEBUG - Requesting: http://10.0.0.30:8081/analytics/uves/virtual-networks 2025-01-10 09:42:01,347 - DEBUG - vn link and name as {'name': 'default-domain:ctest-FloatingipBasicTestSanity-44244481:ctest-vn1-15680884', 'href': 'http://10.0.0.30:8081/analytics/uves/virtual-network/default-domain:ctest-FloatingipBasicTestSanity-44244481:ctest-vn1-15680884?flat'} 2025-01-10 09:42:01,347 - DEBUG - Verifying the default-domain:ctest-FloatingipBasicTestSanity-44244481:ctest-vn1-15680884 virtual network link through opserver 10.0.0.27 2025-01-10 09:42:01,347 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks 2025-01-10 09:42:01,420 - DEBUG - vn link and name as {'name': 'default-domain:ctest-FloatingipBasicTestSanity-44244481:ctest-vn1-15680884', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-FloatingipBasicTestSanity-44244481:ctest-vn1-15680884?flat'} 2025-01-10 09:42:01,420 - DEBUG - Verifying the default-domain:ctest-FloatingipBasicTestSanity-44244481:ctest-vn1-15680884 virtual network link through opserver 10.0.0.31 2025-01-10 09:42:01,420 - DEBUG - Requesting: http://10.0.0.31:8081/analytics/uves/virtual-networks 2025-01-10 09:42:01,491 - DEBUG - vn link and name as {'name': 'default-domain:ctest-FloatingipBasicTestSanity-44244481:ctest-vn1-15680884', 'href': 'http://10.0.0.31:8081/analytics/uves/virtual-network/default-domain:ctest-FloatingipBasicTestSanity-44244481:ctest-vn1-15680884?flat'} 2025-01-10 09:42:01,491 - INFO - Validated that VN default-domain:ctest-FloatingipBasicTestSanity-44244481:ctest-vn1-15680884 is found in opserver 2025-01-10 09:42:01,523 - DEBUG - Do not have enough data to verify VN in agent 2025-01-10 09:42:01,540 - DEBUG - VRF ids for VN ctest-vn1-15680884: {} 2025-01-10 09:42:02,862 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-2598-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-2598-2) 2025-01-10 09:42:04,348 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-2598-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-2598-1) 2025-01-10 09:42:04,563 - INFO - Creating Floating IP pool ctest-some-pool-09764020 in API Server 2025-01-10 09:42:04,795 - DEBUG - Requesting: http://10.0.0.30:8082/domains 2025-01-10 09:42:04,802 - DEBUG - Requesting: http://10.0.0.30:8082/domain/9d97cda5-b041-47d1-8a65-cf8886a92f43 2025-01-10 09:42:04,817 - DEBUG - Requesting: http://10.0.0.30:8082/project/bcc7f3f4-6ff8-4621-8d5a-76676b856d28 2025-01-10 09:42:04,959 - DEBUG - Requesting: http://10.0.0.30:8082/virtual-network/03262bcf-073e-49a7-9516-46630b29334b 2025-01-10 09:42:04,978 - DEBUG - Requesting: http://10.0.0.30:8082/floating-ip-pool/40aceda7-f036-44e1-8240-7f5e3e4fb82e 2025-01-10 09:42:04,987 - DEBUG - Requesting: http://10.0.0.30:8082/domains 2025-01-10 09:42:04,996 - DEBUG - Requesting: http://10.0.0.30:8082/domain/9d97cda5-b041-47d1-8a65-cf8886a92f43 2025-01-10 09:42:05,016 - DEBUG - Requesting: http://10.0.0.30:8082/project/bcc7f3f4-6ff8-4621-8d5a-76676b856d28 2025-01-10 09:42:05,170 - DEBUG - Requesting: http://10.0.0.30:8082/virtual-network/03262bcf-073e-49a7-9516-46630b29334b 2025-01-10 09:42:05,195 - DEBUG - FIP Pool ctest-some-pool-09764020 found in API Server 2025-01-10 09:42:05,195 - INFO - Verification for FIP pool ctest-some-pool-09764020 in API Server passed 2025-01-10 09:42:05,225 - DEBUG - Control-node Ifmap-view has FIP pool ctest-some-pool-09764020 information 2025-01-10 09:42:05,242 - DEBUG - Control-node Ifmap-view has FIP pool ctest-some-pool-09764020 information 2025-01-10 09:42:05,256 - DEBUG - Control-node Ifmap-view has FIP pool ctest-some-pool-09764020 information 2025-01-10 09:42:05,257 - INFO - Verification for FIP pool ctest-some-pool-09764020 in Control-node passed 2025-01-10 09:42:05,257 - INFO - Waiting for VM ctest-vn1_vm1_name-84242918 to be up.. 2025-01-10 09:42:05,460 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-01-10 09:42:10,572 - DEBUG - VM is in ACTIVE state now 2025-01-10 09:42:10,572 - INFO - VM name : ctest-vn1_vm1_name-84242918 2025-01-10 09:42:10,725 - DEBUG - VM ctest-vn1_vm1_name-84242918 ID is aafafe34-d421-4af4-b900-9d743ccd7f29 2025-01-10 09:42:10,765 - DEBUG - VM ctest-vn1_vm1_name-84242918 launched on Node an-jenkins-deploy-platform-ansible-os-2598-2 2025-01-10 09:42:10,948 - DEBUG - Requesting: http://10.0.0.31:8082/virtual-machine/aafafe34-d421-4af4-b900-9d743ccd7f29 2025-01-10 09:42:11,440 - DEBUG - Requesting: http://10.0.0.31:8082/virtual-machine/aafafe34-d421-4af4-b900-9d743ccd7f29 2025-01-10 09:42:11,482 - DEBUG - Requesting: http://10.0.0.31:8082/virtual-machine-interface/0de775d0-d33c-4a11-887e-0726f6b0afa7 2025-01-10 09:42:14,695 - 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=12.2 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 1 received, 50% packet loss, time 1026ms\r\nrtt min/avg/max/mdev = 12.248/12.248/12.248/0.000 ms') 2025-01-10 09:42:14,695 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1_vm1_name-84242918 passed 2025-01-10 09:42:14,821 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-01-10 09:42:14,821 - DEBUG - Waiting to SSH to VM ctest-vn1_vm1_name-84242918, IP 197.204.160.67, Port 22 2025-01-10 09:42:14,903 - DEBUG - VM ctest-vn1_vm1_name-84242918 is ready for SSH connections 2025-01-10 09:42:15,098 - DEBUG - Created Floating IP : ('87.32.191.132', '7b1cda34-2d70-4a38-b124-051fac53641a') 2025-01-10 09:42:15,099 - DEBUG - Associating FIP 87.32.191.132 to aafafe34-d421-4af4-b900-9d743ccd7f29 2025-01-10 09:42:15,146 - DEBUG - Associating FIP ID 7b1cda34-2d70-4a38-b124-051fac53641a with Port ID 0de775d0-d33c-4a11-887e-0726f6b0afa7 2025-01-10 09:42:15,291 - INFO - Waiting for VM ctest-fvn_vm1_name-17033943 to be up.. 2025-01-10 09:42:15,392 - DEBUG - VM is in ACTIVE state now 2025-01-10 09:42:15,392 - INFO - VM name : ctest-fvn_vm1_name-17033943 2025-01-10 09:42:15,508 - DEBUG - VM ctest-fvn_vm1_name-17033943 ID is 26a5d2dd-9e38-489c-a365-98f0ab868c6a 2025-01-10 09:42:15,508 - DEBUG - VM ctest-fvn_vm1_name-17033943 launched on Node an-jenkins-deploy-platform-ansible-os-2598-1 2025-01-10 09:42:15,609 - DEBUG - Requesting: http://10.0.0.31:8082/virtual-machine/26a5d2dd-9e38-489c-a365-98f0ab868c6a 2025-01-10 09:42:15,623 - DEBUG - Requesting: http://10.0.0.31:8082/virtual-machine-interface/ebf77077-2789-4ce3-b122-6ffbe6104554 2025-01-10 09:42:18,821 - 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') 2025-01-10 09:42:18,821 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-fvn_vm1_name-17033943 failed! 2025-01-10 09:42:18,839 - DEBUG - Gateway for vn default-domain:ctest-FloatingipBasicTestSanity-44244481:ctest-fvn-98630718 is 87.32.191.129 and allocation pool is NOT set 2025-01-10 09:42:20,863 - 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=2.16 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.529 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 0.529/1.345/2.162/0.816 ms') 2025-01-10 09:42:20,863 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-fvn_vm1_name-17033943 passed 2025-01-10 09:42:20,988 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-01-10 09:42:20,988 - DEBUG - Waiting to SSH to VM ctest-fvn_vm1_name-17033943, IP 87.32.191.131, Port 22 2025-01-10 09:42:21,070 - DEBUG - VM ctest-fvn_vm1_name-17033943 is ready for SSH connections 2025-01-10 09:42:21,259 - INFO - Route for FIP 87.32.191.132 is fine on Control-node 10.0.0.27 2025-01-10 09:42:21,273 - INFO - Route for FIP 87.32.191.132 is fine on Control-node 10.0.0.30 2025-01-10 09:42:21,273 - INFO - FIP 87.32.191.132 verification for passed on all Control-nodes 2025-01-10 09:42:21,274 - INFO - FIP ID 7b1cda34-2d70-4a38-b124-051fac53641a(87.32.191.132) validation in Control node passed 2025-01-10 09:42:21,305 - DEBUG - agent_label query returned:{'nh': {'type': 'tunnel', 'ref_count': '15', 'valid': 'true', 'policy': 'disabled', 'sip': '10.20.0.7', 'dip': '10.20.0.27', 'vrf': 'default-domain:default-project:ip-fabric:__default__', 'mac': ['fa:16:3e:6f:fb:60'], 'tunnel_type': 'MPLSoUDP', 'nh_index': '25', '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.5', 'dest_vn_list': ['default-domain:ctest-FloatingipBasicTestSanity-44244481:ctest-fvn-98630718'], '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} 2025-01-10 09:42:21,305 - DEBUG - Route for FIP IP 87.32.191.132 is present in agent 10.0.0.37 2025-01-10 09:42:21,305 - DEBUG - FIP 87.32.191.132 verification for VM ctest-vn1_vm1_name-84242918 in Agent 10.0.0.37 passed 2025-01-10 09:42:21,332 - DEBUG - agent_label query returned:{'nh': {'type': 'interface', 'ref_count': '12', 'valid': 'true', 'policy': 'enabled', 'itf': 'tap0de775d0-d3', 'mac': ['2:d:e7:75:d0:d3'], '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.5', 'dest_vn_list': ['default-domain:ctest-FloatingipBasicTestSanity-44244481:ctest-fvn-98630718'], '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} 2025-01-10 09:42:21,332 - DEBUG - Route for FIP IP 87.32.191.132 is present in agent 10.0.0.33 2025-01-10 09:42:21,332 - DEBUG - FIP 87.32.191.132 verification for VM ctest-vn1_vm1_name-84242918 in Agent 10.0.0.33 passed 2025-01-10 09:42:21,332 - INFO - FIP ID 7b1cda34-2d70-4a38-b124-051fac53641a(87.32.191.132) validation in agents passed 2025-01-10 09:42:21,333 - DEBUG - Requesting: http://10.0.0.30:8082/floating-ip/7b1cda34-2d70-4a38-b124-051fac53641a 2025-01-10 09:42:21,348 - DEBUG - FIP 7b1cda34-2d70-4a38-b124-051fac53641a is present in API server 2025-01-10 09:42:21,348 - INFO - FIP ID 7b1cda34-2d70-4a38-b124-051fac53641a(87.32.191.132) validation in API Server passed 2025-01-10 09:42:21,348 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-01-10 09:42:21,349 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 87.32.191.131, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.33, gateway password: c0ntrail123 2025-01-10 09:42:21,349 - DEBUG - ping -s 56 -c 3 -W 1 87.32.191.131 2025-01-10 09:42:24,249 - DEBUG - PING 87.32.191.131 (87.32.191.131): 56 data bytes 64 bytes from 87.32.191.131: seq=0 ttl=64 time=9.450 ms 64 bytes from 87.32.191.131: seq=1 ttl=64 time=1.594 ms 64 bytes from 87.32.191.131: seq=2 ttl=64 time=1.282 ms --- 87.32.191.131 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 1.282/4.108/9.450 ms 2025-01-10 09:42:24,249 - INFO - Ping to IP 87.32.191.131 from VM ctest-vn1_vm1_name-84242918 passed 2025-01-10 09:42:24,249 - DEBUG - Disassociating port from FIP ID : 7b1cda34-2d70-4a38-b124-051fac53641a 2025-01-10 09:42:24,347 - DEBUG - Deleting FIP ID 7b1cda34-2d70-4a38-b124-051fac53641a 2025-01-10 09:42:24,457 - INFO - Deleting the FIP pool ctest-some-pool-09764020 2025-01-10 09:42:24,599 - DEBUG - Control-node Ifmap-view does not have FIP pool ctest-some-pool-09764020 information 2025-01-10 09:42:24,606 - DEBUG - Control-node Ifmap-view does not have FIP pool ctest-some-pool-09764020 information 2025-01-10 09:42:24,615 - DEBUG - Control-node Ifmap-view does not have FIP pool ctest-some-pool-09764020 information 2025-01-10 09:42:24,615 - INFO - Deleting VM ctest-fvn_vm1_name-17033943 2025-01-10 09:42:24,683 - INFO - Deleting VM ctest-vn1_vm1_name-84242918 2025-01-10 09:42:24,753 - INFO - Deleting VN ctest-vn1-15680884 2025-01-10 09:42:24,805 - DEBUG - VN e3d5476f-effe-4739-84c9-87c95e2891bc still in use: Unable to complete operation on network e3d5476f-effe-4739-84c9-87c95e2891bc. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-3ec603fb-b74f-4d4c-9612-ba0ba9d25410'] 2025-01-10 09:42:24,805 - WARNING - Deleting VN ctest-vn1-15680884 failed..Will retry 2025-01-10 09:42:27,012 - DEBUG - Response for deleting network () 2025-01-10 09:42:27,012 - DEBUG - Requesting: http://10.0.0.30:8082/routing-instance/574c3e66-1b42-4289-af88-0330b95779c5 2025-01-10 09:42:27,033 - DEBUG - Response Code: 404 2025-01-10 09:42:27,034 - DEBUG - Requesting: http://10.0.0.30:8082/domains 2025-01-10 09:42:27,043 - DEBUG - Requesting: http://10.0.0.30:8082/domain/9d97cda5-b041-47d1-8a65-cf8886a92f43 2025-01-10 09:42:27,079 - DEBUG - Requesting: http://10.0.0.30:8082/project/bcc7f3f4-6ff8-4621-8d5a-76676b856d28 2025-01-10 09:42:27,188 - INFO - Validated that VN ctest-vn1-15680884 is not found in API Server 2025-01-10 09:42:27,204 - DEBUG - VN ctest-vn1-15680884 is not present in Agent 10.0.0.37 2025-01-10 09:42:27,217 - DEBUG - VN ctest-vn1-15680884 is not present in Agent 10.0.0.33 2025-01-10 09:42:27,217 - INFO - Validated that VN ctest-vn1-15680884 is not in any agent 2025-01-10 09:42:27,259 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn1-15680884 info 2025-01-10 09:42:27,259 - INFO - Deleting VN ctest-fvn-98630718 2025-01-10 09:42:27,481 - DEBUG - Response for deleting network () 2025-01-10 09:42:27,481 - DEBUG - Requesting: http://10.0.0.30:8082/routing-instance/7cee7a62-cd04-4b00-a839-04021ad6bdff 2025-01-10 09:42:27,499 - DEBUG - Response Code: 404 2025-01-10 09:42:27,500 - DEBUG - Requesting: http://10.0.0.30:8082/domains 2025-01-10 09:42:27,511 - DEBUG - Requesting: http://10.0.0.30:8082/domain/9d97cda5-b041-47d1-8a65-cf8886a92f43 2025-01-10 09:42:27,526 - DEBUG - Requesting: http://10.0.0.30:8082/project/bcc7f3f4-6ff8-4621-8d5a-76676b856d28 2025-01-10 09:42:27,628 - INFO - Validated that VN ctest-fvn-98630718 is not found in API Server 2025-01-10 09:42:27,641 - DEBUG - VN ctest-fvn-98630718 is not present in Agent 10.0.0.37 2025-01-10 09:42:27,656 - DEBUG - VN ctest-fvn-98630718 is not present in Agent 10.0.0.33 2025-01-10 09:42:27,656 - INFO - Validated that VN ctest-fvn-98630718 is not in any agent 2025-01-10 09:42:27,703 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-fvn-98630718 info 2025-01-10 09:42:29,018 - DEBUG - No XMPP flaps were noticed during the test
2025-01-10 09:42:29,018 - INFO - END TEST : test_floating_ip : PASSED[0:00:34]
2025-01-10 09:42:29,018 - INFO - -------------------------------------------------------------------------------- 2025-01-10 09:42:29,851 - INFO - Deleted project: ctest-FloatingipBasicTestSanity-44244481, ID : bcc7f3f4-6ff8-4621-8d5a-76676b856d28