is in ACTIVE state now
2026-02-26 02:20:59,697 - INFO - VM name : ctest-fvn_vm1_name-79744650
2026-02-26 02:20:59,808 - DEBUG - VM ctest-fvn_vm1_name-79744650 ID is 5468029c-af5f-4aad-b2ff-0a6e0d133a54
2026-02-26 02:20:59,808 - DEBUG - VM ctest-fvn_vm1_name-79744650 launched on Node an-jenkins-deploy-platform-ansible-os-5402-1
2026-02-26 02:20:59,918 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine/5468029c-af5f-4aad-b2ff-0a6e0d133a54
2026-02-26 02:20:59,937 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine-interface/cdb215ed-c1ae-4a11-b702-d277089de20c
2026-02-26 02:21:01,067 - 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.97 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=1.39 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 = 1.391/3.681/5.972/2.290 ms')
2026-02-26 02:21:01,067 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-fvn_vm1_name-79744650 passed
2026-02-26 02:21:01,139 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-02-26 02:21:01,140 - DEBUG - Waiting to SSH to VM ctest-fvn_vm1_name-79744650, IP 218.54.222.131, Port 22
2026-02-26 02:21:01,155 - 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-02-26 02:21:01,272 - DEBUG - VM ctest-fvn_vm1_name-79744650 is NOT ready for SSH connections, VM status: ACTIVE
2026-02-26 02:21:06,273 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-02-26 02:21:06,273 - DEBUG - Waiting to SSH to VM ctest-fvn_vm1_name-79744650, IP 218.54.222.131, Port 22
2026-02-26 02:21:06,304 - 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-02-26 02:21:06,414 - DEBUG - VM ctest-fvn_vm1_name-79744650 is NOT ready for SSH connections, VM status: ACTIVE
2026-02-26 02:21:11,414 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-02-26 02:21:11,415 - DEBUG - Waiting to SSH to VM ctest-fvn_vm1_name-79744650, IP 218.54.222.131, Port 22
2026-02-26 02:21:11,501 - DEBUG - VM ctest-fvn_vm1_name-79744650 is ready for SSH connections
2026-02-26 02:21:11,686 - INFO - Route for FIP 218.54.222.132 is fine on Control-node 10.0.0.73
2026-02-26 02:21:11,697 - INFO - Route for FIP 218.54.222.132 is fine on Control-node 10.0.0.65
2026-02-26 02:21:11,707 - INFO - Route for FIP 218.54.222.132 is fine on Control-node 10.0.0.131
2026-02-26 02:21:11,707 - INFO - FIP 218.54.222.132 verification for passed on all Control-nodes
2026-02-26 02:21:11,707 - INFO - FIP ID 175e6c59-2f1e-404e-8e1e-6f3186464864(218.54.222.132) validation in Control node passed
2026-02-26 02:21:11,739 - DEBUG - agent_label query returned:{'nh': {'type': 'tunnel', 'ref_count': '15', 'valid': 'true', 'policy': 'disabled', 'sip': '10.20.0.18', 'dip': '10.20.0.192', 'vrf': 'default-domain:default-project:ip-fabric:__default__', 'mac': ['fa:16:3e:95:95:3c'], 'tunnel_type': 'MPLSoUDP', 'nh_index': '20', '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.129', 'dest_vn_list': ['default-domain:ctest-FloatingipBasicTestSanity-66322086:ctest-fvn-00005805'], 'unresolved': 'false', 'sg_list': ['8000005'], '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-02-26 02:21:11,739 - DEBUG - Route for FIP IP 218.54.222.132 is present in agent 10.0.0.23
2026-02-26 02:21:11,739 - DEBUG - FIP 218.54.222.132 verification for VM ctest-vn1_vm1_name-53523629 in Agent 10.0.0.23 passed
2026-02-26 02:21:11,764 - DEBUG - agent_label query returned:{'nh': {'type': 'interface', 'ref_count': '42', 'valid': 'true', 'policy': 'enabled', 'itf': 'tapf6ec5817-cf', 'mac': ['2:f6:ec:58:17:cf'], 'mcast': 'disabled', 'nh_index': '31', '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-66322086:ctest-fvn-00005805'], 'unresolved': 'false', 'sg_list': ['8000005'], '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-02-26 02:21:11,764 - DEBUG - Route for FIP IP 218.54.222.132 is present in agent 10.0.0.48
2026-02-26 02:21:11,764 - DEBUG - FIP 218.54.222.132 verification for VM ctest-vn1_vm1_name-53523629 in Agent 10.0.0.48 passed
2026-02-26 02:21:11,764 - INFO - FIP ID 175e6c59-2f1e-404e-8e1e-6f3186464864(218.54.222.132) validation in agents passed
2026-02-26 02:21:11,764 - DEBUG - Requesting: http://10.0.0.65:8082/floating-ip/175e6c59-2f1e-404e-8e1e-6f3186464864
2026-02-26 02:21:11,773 - DEBUG - FIP 175e6c59-2f1e-404e-8e1e-6f3186464864 is present in API server
2026-02-26 02:21:11,774 - INFO - FIP ID 175e6c59-2f1e-404e-8e1e-6f3186464864(218.54.222.132) validation in API Server passed
2026-02-26 02:21:11,774 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-02-26 02:21:11,774 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 218.54.222.131, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.48, gateway password: c0ntrail123
2026-02-26 02:21:11,774 - DEBUG - ping -s 56 -c 3 -W 1 218.54.222.131
2026-02-26 02:21:56,447 - DEBUG - PING 218.54.222.131 (218.54.222.131) 56(84) bytes of data.
64 bytes from 218.54.222.131: icmp_req=1 ttl=64 time=9.62 ms
64 bytes from 218.54.222.131: icmp_req=2 ttl=64 time=1.04 ms
64 bytes from 218.54.222.131: icmp_req=3 ttl=64 time=1.22 ms
--- 218.54.222.131 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2004ms
rtt min/avg/max/mdev = 1.043/3.962/9.622/4.002 ms
2026-02-26 02:21:56,448 - INFO - Ping to IP 218.54.222.131 from VM ctest-vn1_vm1_name-53523629 passed
2026-02-26 02:21:56,448 - DEBUG - Disassociating port from FIP ID : 175e6c59-2f1e-404e-8e1e-6f3186464864
2026-02-26 02:21:56,530 - DEBUG - Deleting FIP ID 175e6c59-2f1e-404e-8e1e-6f3186464864
2026-02-26 02:21:56,617 - INFO - Deleting the FIP pool ctest-some-pool-65466422
2026-02-26 02:21:56,739 - DEBUG - Control-node Ifmap-view does not have FIP pool ctest-some-pool-65466422 information
2026-02-26 02:21:56,746 - DEBUG - Control-node Ifmap-view does not have FIP pool ctest-some-pool-65466422 information
2026-02-26 02:21:56,751 - DEBUG - Control-node Ifmap-view does not have FIP pool ctest-some-pool-65466422 information
2026-02-26 02:21:56,751 - INFO - Deleting VM ctest-fvn_vm1_name-79744650
2026-02-26 02:21:56,835 - INFO - Deleting VM ctest-vn1_vm1_name-53523629
2026-02-26 02:21:56,928 - INFO - Deleting VN ctest-vn1-40342716
2026-02-26 02:21:56,974 - DEBUG - VN 37cb9534-0909-43c6-bfb7-4930fdbfaa18 still in use: Unable to complete operation on network 37cb9534-0909-43c6-bfb7-4930fdbfaa18. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-400d3eb8-76af-4810-99c1-281e7e1f125d']
2026-02-26 02:21:56,974 - WARNING - Deleting VN ctest-vn1-40342716 failed..Will retry
2026-02-26 02:21:59,137 - DEBUG - Response for deleting network ()
2026-02-26 02:21:59,137 - DEBUG - Requesting: http://10.0.0.65:8082/routing-instance/4c9e9ddb-75f2-4025-9cf5-a86dac5f3900
2026-02-26 02:21:59,155 - DEBUG - Response Code: 404
2026-02-26 02:21:59,155 - DEBUG - Requesting: http://10.0.0.65:8082/domains
2026-02-26 02:21:59,163 - DEBUG - Requesting: http://10.0.0.65:8082/domain/ca8c29a2-0264-4f8f-adb5-14cf22895ebe
2026-02-26 02:21:59,220 - DEBUG - Requesting: http://10.0.0.65:8082/project/d63f091f-ca45-4da2-b100-4f7384a65c95
2026-02-26 02:21:59,311 - INFO - Validated that VN ctest-vn1-40342716 is not found in API Server
2026-02-26 02:21:59,326 - DEBUG - VN ctest-vn1-40342716 is not present in Agent 10.0.0.23
2026-02-26 02:21:59,336 - DEBUG - VN ctest-vn1-40342716 is not present in Agent 10.0.0.48
2026-02-26 02:21:59,336 - INFO - Validated that VN ctest-vn1-40342716 is not in any agent
2026-02-26 02:21:59,373 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn1-40342716 info
2026-02-26 02:21:59,373 - INFO - Deleting VN ctest-fvn-00005805
2026-02-26 02:21:59,547 - DEBUG - Response for deleting network ()
2026-02-26 02:21:59,547 - DEBUG - Requesting: http://10.0.0.65:8082/routing-instance/c808bfcf-85c3-4119-990a-c9182889b76c
2026-02-26 02:21:59,554 - DEBUG - Response Code: 404
2026-02-26 02:21:59,555 - DEBUG - Requesting: http://10.0.0.65:8082/domains
2026-02-26 02:21:59,571 - DEBUG - Requesting: http://10.0.0.65:8082/domain/ca8c29a2-0264-4f8f-adb5-14cf22895ebe
2026-02-26 02:21:59,588 - DEBUG - Requesting: http://10.0.0.65:8082/project/d63f091f-ca45-4da2-b100-4f7384a65c95
2026-02-26 02:21:59,688 - INFO - Validated that VN ctest-fvn-00005805 is not found in API Server
2026-02-26 02:21:59,705 - DEBUG - VN ctest-fvn-00005805 is not present in Agent 10.0.0.23
2026-02-26 02:21:59,718 - DEBUG - VN ctest-fvn-00005805 is not present in Agent 10.0.0.48
2026-02-26 02:21:59,718 - INFO - Validated that VN ctest-fvn-00005805 is not in any agent
2026-02-26 02:21:59,754 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-fvn-00005805 info
2026-02-26 02:22:01,047 - DEBUG - No XMPP flaps were noticed during the test
2026-02-26 02:22:01,047 - INFO - END TEST : test_floating_ip : PASSED[0:03:51]
2026-02-26 02:22:01,047 - INFO - --------------------------------------------------------------------------------
2026-02-26 02:22:02,065 - INFO - Deleted project: ctest-FloatingipBasicTestSanity-66322086, ID : d63f091f-ca45-4da2-b100-4f7384a65c95