is in ACTIVE state now
2025-03-27 16:20:05,713 - INFO - VM name : ctest-fvn_vm1_name-34434595
2025-03-27 16:20:05,798 - DEBUG - VM ctest-fvn_vm1_name-34434595 ID is 3cd26c86-60ad-4ed4-8e96-e749a643ed46
2025-03-27 16:20:05,798 - DEBUG - VM ctest-fvn_vm1_name-34434595 launched on Node cn-jenkins-deploy-platform-ansible-os-2979-1
2025-03-27 16:20:05,881 - DEBUG - Requesting: http://10.0.0.54:8082/virtual-machine/3cd26c86-60ad-4ed4-8e96-e749a643ed46
2025-03-27 16:20:05,893 - DEBUG - Requesting: http://10.0.0.54:8082/virtual-machine-interface/e5441639-b20b-4cc9-b44c-85150bc856ed
2025-03-27 16:20:07,114 - 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.69 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.502 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 0.502/2.093/3.685/1.591 ms')
2025-03-27 16:20:07,114 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-fvn_vm1_name-34434595 passed
2025-03-27 16:20:07,273 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-03-27 16:20:07,273 - DEBUG - Waiting to SSH to VM ctest-fvn_vm1_name-34434595, IP 43.244.149.195, Port 22
2025-03-27 16:20:07,358 - DEBUG - VM ctest-fvn_vm1_name-34434595 is ready for SSH connections
2025-03-27 16:20:07,499 - INFO - FIP 43.244.149.196 verification for passed on all Control-nodes
2025-03-27 16:20:07,499 - INFO - FIP ID da89700d-0804-4b92-a193-b8bdd0cdf0ab(43.244.149.196) validation in Control node passed
2025-03-27 16:20:07,525 - DEBUG - agent_label query returned:{'nh': {'type': 'interface', 'ref_count': '14', 'valid': 'true', 'policy': 'enabled', 'itf': 'tape9cfca38-ca', 'mac': ['2:e9:cf:ca:38:ca'], 'mcast': 'disabled', 'nh_index': '23', 'vxlan_flag': 'false', 'intf_flags': '1', 'isid': '0', 'learning_enabled': 'false', 'etree_leaf': 'false', 'layer2_control_word': 'false', 'crypt_all_traffic': 'false', 'crypt_path_available': 'false', 'crypt_interface': None}, 'label': '25', 'vxlan_id': '0', 'peer': '10.20.0.13', 'dest_vn_list': ['default-domain:ctest-FloatingipBasicTestSanity-08536785:ctest-fvn-22481347'], '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-03-27 16:20:07,525 - DEBUG - Route for FIP IP 43.244.149.196 is present in agent 10.0.0.54
2025-03-27 16:20:07,525 - DEBUG - FIP 43.244.149.196 verification for VM ctest-vn1_vm1_name-50882234 in Agent 10.0.0.54 passed
2025-03-27 16:20:07,525 - INFO - FIP ID da89700d-0804-4b92-a193-b8bdd0cdf0ab(43.244.149.196) validation in agents passed
2025-03-27 16:20:07,525 - DEBUG - Requesting: http://10.0.0.54:8082/floating-ip/da89700d-0804-4b92-a193-b8bdd0cdf0ab
2025-03-27 16:20:07,536 - DEBUG - FIP da89700d-0804-4b92-a193-b8bdd0cdf0ab is present in API server
2025-03-27 16:20:07,536 - INFO - FIP ID da89700d-0804-4b92-a193-b8bdd0cdf0ab(43.244.149.196) validation in API Server passed
2025-03-27 16:20:07,537 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-03-27 16:20:07,537 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 43.244.149.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.54, gateway password: c0ntrail123
2025-03-27 16:20:07,537 - DEBUG - ping -s 56 -c 3 -W 1 43.244.149.195
2025-03-27 16:20:10,672 - DEBUG - PING 43.244.149.195 (43.244.149.195): 56 data bytes
64 bytes from 43.244.149.195: seq=0 ttl=63 time=5.054 ms
64 bytes from 43.244.149.195: seq=1 ttl=63 time=1.068 ms
64 bytes from 43.244.149.195: seq=2 ttl=63 time=0.943 ms
--- 43.244.149.195 ping statistics ---
3 packets transmitted, 3 packets received, 0% packet loss
round-trip min/avg/max = 0.943/2.355/5.054 ms
2025-03-27 16:20:10,672 - INFO - Ping to IP 43.244.149.195 from VM ctest-vn1_vm1_name-50882234 passed
2025-03-27 16:20:10,672 - DEBUG - Disassociating port from FIP ID : da89700d-0804-4b92-a193-b8bdd0cdf0ab
2025-03-27 16:20:10,735 - DEBUG - Deleting FIP ID da89700d-0804-4b92-a193-b8bdd0cdf0ab
2025-03-27 16:20:10,818 - INFO - Deleting the FIP pool ctest-some-pool-30117038
2025-03-27 16:20:10,934 - DEBUG - Control-node Ifmap-view does not have FIP pool ctest-some-pool-30117038 information
2025-03-27 16:20:10,934 - INFO - Deleting VM ctest-fvn_vm1_name-34434595
2025-03-27 16:20:11,026 - INFO - Deleting VM ctest-vn1_vm1_name-50882234
2025-03-27 16:20:11,121 - INFO - Deleting VN ctest-vn1-76747388
2025-03-27 16:20:11,164 - DEBUG - VN 78e64eb6-c7ba-4545-8710-0403c7db8242 still in use: Unable to complete operation on network 78e64eb6-c7ba-4545-8710-0403c7db8242. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-c4d61b26-133b-4fe7-bb6f-d952938dafd6']
2025-03-27 16:20:11,164 - WARNING - Deleting VN ctest-vn1-76747388 failed..Will retry
2025-03-27 16:20:13,289 - DEBUG - Response for deleting network ()
2025-03-27 16:20:13,289 - DEBUG - Requesting: http://10.0.0.54:8082/routing-instance/76c9891d-bd86-4472-9f05-10bf9de668fe
2025-03-27 16:20:13,297 - DEBUG - Response Code: 404
2025-03-27 16:20:13,301 - DEBUG - Requesting: http://10.0.0.54:8082/domains
2025-03-27 16:20:13,310 - DEBUG - Requesting: http://10.0.0.54:8082/domain/669846c2-f2f6-4644-b578-6bfd319d05e4
2025-03-27 16:20:13,369 - DEBUG - Requesting: http://10.0.0.54:8082/project/e40b385a-b08d-4813-8e14-ddecc5e0b1be
2025-03-27 16:20:13,450 - INFO - Validated that VN ctest-vn1-76747388 is not found in API Server
2025-03-27 16:20:13,463 - DEBUG - VN ctest-vn1-76747388 is not present in Agent 10.0.0.54
2025-03-27 16:20:13,463 - INFO - Validated that VN ctest-vn1-76747388 is not in any agent
2025-03-27 16:20:13,481 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn1-76747388 info
2025-03-27 16:20:13,481 - INFO - Deleting VN ctest-fvn-22481347
2025-03-27 16:20:13,595 - DEBUG - Response for deleting network ()
2025-03-27 16:20:13,595 - DEBUG - Requesting: http://10.0.0.54:8082/routing-instance/45495c54-68c7-4e0b-b7b4-0cb97d264a67
2025-03-27 16:20:13,602 - DEBUG - Response Code: 404
2025-03-27 16:20:13,602 - DEBUG - Requesting: http://10.0.0.54:8082/domains
2025-03-27 16:20:13,613 - DEBUG - Requesting: http://10.0.0.54:8082/domain/669846c2-f2f6-4644-b578-6bfd319d05e4
2025-03-27 16:20:13,671 - DEBUG - Requesting: http://10.0.0.54:8082/project/e40b385a-b08d-4813-8e14-ddecc5e0b1be
2025-03-27 16:20:13,773 - INFO - Validated that VN ctest-fvn-22481347 is not found in API Server
2025-03-27 16:20:13,786 - DEBUG - VN ctest-fvn-22481347 is not present in Agent 10.0.0.54
2025-03-27 16:20:13,786 - INFO - Validated that VN ctest-fvn-22481347 is not in any agent
2025-03-27 16:20:13,810 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-fvn-22481347 info
2025-03-27 16:20:14,076 - DEBUG - No XMPP flaps were noticed during the test
2025-03-27 16:20:14,076 - INFO - END TEST : test_floating_ip : PASSED[0:00:31]
2025-03-27 16:20:14,076 - INFO - --------------------------------------------------------------------------------
2025-03-27 16:20:14,838 - INFO - Deleted project: ctest-FloatingipBasicTestSanity-08536785, ID : e40b385a-b08d-4813-8e14-ddecc5e0b1be