is in ACTIVE state now
2025-02-04 07:05:05,881 - INFO - VM name : ctest-TestBasicPolicy-83953764-49014812
2025-02-04 07:05:05,959 - DEBUG - VM ctest-TestBasicPolicy-83953764-49014812 ID is 6df3418b-e621-41f6-a518-5b73b644faf7
2025-02-04 07:05:05,959 - DEBUG - VM ctest-TestBasicPolicy-83953764-49014812 launched on Node cn-jenkins-deploy-platform-ansible-os-2684-1
2025-02-04 07:05:06,032 - DEBUG - Requesting: http://10.0.0.12:8082/virtual-machine/6df3418b-e621-41f6-a518-5b73b644faf7
2025-02-04 07:05:06,043 - DEBUG - Requesting: http://10.0.0.12:8082/virtual-machine-interface/afe18417-c910-40e0-8677-ae298ba16899
2025-02-04 07:05:07,281 - 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.65 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.390 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.390/2.020/3.651/1.630 ms')
2025-02-04 07:05:07,281 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-TestBasicPolicy-83953764-49014812 passed
2025-02-04 07:05:07,435 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-02-04 07:05:07,436 - DEBUG - Waiting to SSH to VM ctest-TestBasicPolicy-83953764-49014812, IP 85.0.227.195, Port 22
2025-02-04 07:05:07,501 - DEBUG - Error on ssh to cirros@169.254.0.4:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.4/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.4/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.4/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2025-02-04 07:05:07,598 - DEBUG - VM ctest-TestBasicPolicy-83953764-49014812 is NOT ready for SSH connections, VM status: ACTIVE
2025-02-04 07:05:12,599 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-02-04 07:05:12,599 - DEBUG - Waiting to SSH to VM ctest-TestBasicPolicy-83953764-49014812, IP 85.0.227.195, Port 22
2025-02-04 07:05:12,755 - DEBUG - VM ctest-TestBasicPolicy-83953764-49014812 is ready for SSH connections
2025-02-04 07:05:12,755 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-02-04 07:05:12,756 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 85.0.227.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.12, gateway password: c0ntrail123
2025-02-04 07:05:12,756 - DEBUG - ping -s 56 -c 3 -W 1 85.0.227.195
2025-02-04 07:05:16,780 - DEBUG - PING 85.0.227.195 (85.0.227.195): 56 data bytes
64 bytes from 85.0.227.195: seq=0 ttl=63 time=4.689 ms
64 bytes from 85.0.227.195: seq=1 ttl=63 time=0.869 ms
64 bytes from 85.0.227.195: seq=2 ttl=63 time=0.872 ms
--- 85.0.227.195 ping statistics ---
3 packets transmitted, 3 packets received, 0% packet loss
round-trip min/avg/max = 0.869/2.143/4.689 ms
2025-02-04 07:05:16,780 - INFO - Ping to IP 85.0.227.195 from VM ctest-TestBasicPolicy-83953764-31068168 passed
2025-02-04 07:05:16,963 - DEBUG - Policy Update Response {"network-policy": {"uuid": "5870c8b8-de84-4ab2-924d-6f75dfc06062", "href": "http://10.0.0.12:8082/network-policy/5870c8b8-de84-4ab2-924d-6f75dfc06062"}}
2025-02-04 07:05:16,963 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-02-04 07:05:16,963 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 85.0.227.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.12, gateway password: c0ntrail123
2025-02-04 07:05:16,963 - DEBUG - ping -s 56 -c 3 -W 1 85.0.227.195
2025-02-04 07:05:20,175 - DEBUG - PING 85.0.227.195 (85.0.227.195): 56 data bytes
64 bytes from 85.0.227.195: seq=0 ttl=63 time=1.330 ms
--- 85.0.227.195 ping statistics ---
3 packets transmitted, 1 packets received, 66% packet loss
round-trip min/avg/max = 1.330/1.330/1.330 ms
2025-02-04 07:05:20,175 - WARNING - Ping to IP 85.0.227.195 from VM ctest-TestBasicPolicy-83953764-31068168 failed
2025-02-04 07:05:20,175 - INFO - Deleting VM ctest-TestBasicPolicy-83953764-49014812
2025-02-04 07:05:20,248 - INFO - Deleting VM ctest-TestBasicPolicy-83953764-31068168
2025-02-04 07:05:20,633 - INFO - Deleted policy ctest-policy-allow-all-89072572
2025-02-04 07:05:20,633 - INFO - Deleting VN ctest-vn-31006461
2025-02-04 07:05:20,668 - DEBUG - VN 2dfeb303-9a47-41a4-b37a-348c1fede4f6 still in use: Unable to complete operation on network 2dfeb303-9a47-41a4-b37a-348c1fede4f6. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-e137e836-53c2-422a-80af-5fbb37edb3e6']
2025-02-04 07:05:20,668 - WARNING - Deleting VN ctest-vn-31006461 failed..Will retry
2025-02-04 07:05:22,779 - DEBUG - Response for deleting network ()
2025-02-04 07:05:22,779 - DEBUG - Requesting: http://10.0.0.12:8082/routing-instance/acd50e5d-dd22-4b39-8256-5e6e0157c3bc
2025-02-04 07:05:22,788 - DEBUG - Response Code: 404
2025-02-04 07:05:22,789 - DEBUG - Requesting: http://10.0.0.12:8082/domains
2025-02-04 07:05:22,796 - DEBUG - Requesting: http://10.0.0.12:8082/domain/ba13f9de-04b3-4f3c-949e-421777e97252
2025-02-04 07:05:22,849 - DEBUG - Requesting: http://10.0.0.12:8082/project/eef6e7cf-88ec-44c0-9e98-2720c67c1dd3
2025-02-04 07:05:22,923 - INFO - Validated that VN ctest-vn-31006461 is not found in API Server
2025-02-04 07:05:22,934 - DEBUG - VN ctest-vn-31006461 is not present in Agent 10.0.0.12
2025-02-04 07:05:22,934 - INFO - Validated that VN ctest-vn-31006461 is not in any agent
2025-02-04 07:05:22,951 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn-31006461 info
2025-02-04 07:05:22,951 - INFO - Deleting VN ctest-vn-62335788
2025-02-04 07:05:23,068 - DEBUG - Response for deleting network ()
2025-02-04 07:05:23,068 - DEBUG - Requesting: http://10.0.0.12:8082/routing-instance/c1c722c5-c545-436e-a4ad-e7d713af1215
2025-02-04 07:05:23,074 - DEBUG - Response Code: 404
2025-02-04 07:05:23,074 - DEBUG - Requesting: http://10.0.0.12:8082/domains
2025-02-04 07:05:23,084 - DEBUG - Requesting: http://10.0.0.12:8082/domain/ba13f9de-04b3-4f3c-949e-421777e97252
2025-02-04 07:05:23,141 - DEBUG - Requesting: http://10.0.0.12:8082/project/eef6e7cf-88ec-44c0-9e98-2720c67c1dd3
2025-02-04 07:05:23,213 - INFO - Validated that VN ctest-vn-62335788 is not found in API Server
2025-02-04 07:05:23,225 - DEBUG - VN ctest-vn-62335788 is not present in Agent 10.0.0.12
2025-02-04 07:05:23,225 - INFO - Validated that VN ctest-vn-62335788 is not in any agent
2025-02-04 07:05:23,240 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn-62335788 info
2025-02-04 07:05:23,494 - DEBUG - No XMPP flaps were noticed during the test
2025-02-04 07:05:23,494 - INFO - END TEST : test_basic_policy_allow_deny : PASSED[0:00:35]
2025-02-04 07:05:23,494 - INFO - --------------------------------------------------------------------------------
2025-02-04 07:05:24,160 - INFO - Deleted project: ctest-TestBasicPolicy-83953764, ID : eef6e7cf-88ec-44c0-9e98-2720c67c1dd3