is in ACTIVE state now
2026-08-20 19:34:37,956 - INFO - VM name : ctest-TestBasicPolicy-40091747-06522386
2026-08-20 19:34:38,040 - DEBUG - VM ctest-TestBasicPolicy-40091747-06522386 ID is fe9fd863-02f1-4900-bf7c-e0e8b68cf8b1
2026-08-20 19:34:38,040 - DEBUG - VM ctest-TestBasicPolicy-40091747-06522386 launched on Node cn-jenkins-deploy-platform-ansible-os-6195-1
2026-08-20 19:34:38,118 - DEBUG - Requesting: http://10.0.0.189:8082/virtual-machine/fe9fd863-02f1-4900-bf7c-e0e8b68cf8b1
2026-08-20 19:34:38,127 - DEBUG - Requesting: http://10.0.0.189:8082/virtual-machine-interface/20ecea28-a977-4a6c-b9d9-1bfe910ee830
2026-08-20 19:34:41,370 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1010ms')
2026-08-20 19:34:41,370 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-TestBasicPolicy-40091747-06522386 failed!
2026-08-20 19:34:41,426 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicy-40091747:ctest-vn-50554553 is 194.38.223.129 and allocation pool is NOT set
2026-08-20 19:34:43,488 - 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=11.6 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=2.72 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 2.723/7.147/11.572/4.424 ms')
2026-08-20 19:34:43,488 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-TestBasicPolicy-40091747-06522386 passed
2026-08-20 19:34:43,642 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-08-20 19:34:43,642 - DEBUG - Waiting to SSH to VM ctest-TestBasicPolicy-40091747-06522386, IP 194.38.223.131, Port 22
2026-08-20 19:34:43,712 - 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': ''}
2026-08-20 19:34:43,792 - DEBUG - VM ctest-TestBasicPolicy-40091747-06522386 is NOT ready for SSH connections, VM status: ACTIVE
2026-08-20 19:34:48,793 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-08-20 19:34:48,793 - DEBUG - Waiting to SSH to VM ctest-TestBasicPolicy-40091747-06522386, IP 194.38.223.131, Port 22
2026-08-20 19:34:48,949 - DEBUG - VM ctest-TestBasicPolicy-40091747-06522386 is ready for SSH connections
2026-08-20 19:34:48,950 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-08-20 19:34:48,950 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 194.38.223.131, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.189, gateway password: c0ntrail123
2026-08-20 19:34:48,950 - DEBUG - ping -s 56 -c 3 -W 1 194.38.223.131
2026-08-20 19:34:52,955 - DEBUG - PING 194.38.223.131 (194.38.223.131): 56 data bytes
64 bytes from 194.38.223.131: seq=0 ttl=63 time=4.091 ms
64 bytes from 194.38.223.131: seq=1 ttl=63 time=0.912 ms
64 bytes from 194.38.223.131: seq=2 ttl=63 time=0.914 ms
--- 194.38.223.131 ping statistics ---
3 packets transmitted, 3 packets received, 0% packet loss
round-trip min/avg/max = 0.912/1.972/4.091 ms
2026-08-20 19:34:52,955 - INFO - Ping to IP 194.38.223.131 from VM ctest-TestBasicPolicy-40091747-35398017 passed
2026-08-20 19:34:53,142 - DEBUG - Policy Update Response {"network-policy": {"uuid": "c9e242a1-78b2-40e0-a462-21773f0015e2", "href": "http://10.0.0.189:8082/network-policy/c9e242a1-78b2-40e0-a462-21773f0015e2"}}
2026-08-20 19:34:53,142 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-08-20 19:34:53,142 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 194.38.223.131, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.189, gateway password: c0ntrail123
2026-08-20 19:34:53,142 - DEBUG - ping -s 56 -c 3 -W 1 194.38.223.131
2026-08-20 19:34:56,273 - DEBUG - PING 194.38.223.131 (194.38.223.131): 56 data bytes
64 bytes from 194.38.223.131: seq=0 ttl=63 time=1.473 ms
--- 194.38.223.131 ping statistics ---
3 packets transmitted, 1 packets received, 66% packet loss
round-trip min/avg/max = 1.473/1.473/1.473 ms
2026-08-20 19:34:56,273 - WARNING - Ping to IP 194.38.223.131 from VM ctest-TestBasicPolicy-40091747-35398017 failed
2026-08-20 19:34:56,274 - INFO - Deleting VM ctest-TestBasicPolicy-40091747-06522386
2026-08-20 19:34:56,343 - INFO - Deleting VM ctest-TestBasicPolicy-40091747-35398017
2026-08-20 19:34:56,704 - INFO - Deleted policy ctest-policy-allow-all-77980291
2026-08-20 19:34:56,704 - INFO - Deleting VN ctest-vn-50554553
2026-08-20 19:34:56,736 - DEBUG - VN 13a8d6fc-f14b-4d0d-a535-7215081c854c still in use: Unable to complete operation on network 13a8d6fc-f14b-4d0d-a535-7215081c854c. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-4171b038-f8f2-4af3-a8f5-aa0edcd46621']
2026-08-20 19:34:56,736 - WARNING - Deleting VN ctest-vn-50554553 failed..Will retry
2026-08-20 19:34:58,849 - DEBUG - Response for deleting network ()
2026-08-20 19:34:58,849 - DEBUG - Requesting: http://10.0.0.189:8082/routing-instance/d1c1df29-f305-4a91-8cae-cb0164d46e3e
2026-08-20 19:34:58,856 - DEBUG - Response Code: 404
2026-08-20 19:34:58,856 - DEBUG - Requesting: http://10.0.0.189:8082/domains
2026-08-20 19:34:58,862 - DEBUG - Requesting: http://10.0.0.189:8082/domain/eaacb39e-cadf-4029-83dc-14c90db4b95c
2026-08-20 19:34:58,917 - DEBUG - Requesting: http://10.0.0.189:8082/project/7d27488c-1a9c-4131-a548-43d90488dad0
2026-08-20 19:34:59,001 - INFO - Validated that VN ctest-vn-50554553 is not found in API Server
2026-08-20 19:34:59,014 - DEBUG - VN ctest-vn-50554553 is not present in Agent 10.0.0.189
2026-08-20 19:34:59,014 - INFO - Validated that VN ctest-vn-50554553 is not in any agent
2026-08-20 19:34:59,031 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn-50554553 info
2026-08-20 19:34:59,031 - INFO - Deleting VN ctest-vn-88184632
2026-08-20 19:34:59,141 - DEBUG - Response for deleting network ()
2026-08-20 19:34:59,141 - DEBUG - Requesting: http://10.0.0.189:8082/routing-instance/f194e7f1-046f-4b83-8812-0f250d5b8be0
2026-08-20 19:34:59,150 - DEBUG - Response Code: 404
2026-08-20 19:34:59,150 - DEBUG - Requesting: http://10.0.0.189:8082/domains
2026-08-20 19:34:59,156 - DEBUG - Requesting: http://10.0.0.189:8082/domain/eaacb39e-cadf-4029-83dc-14c90db4b95c
2026-08-20 19:34:59,211 - DEBUG - Requesting: http://10.0.0.189:8082/project/7d27488c-1a9c-4131-a548-43d90488dad0
2026-08-20 19:34:59,284 - INFO - Validated that VN ctest-vn-88184632 is not found in API Server
2026-08-20 19:34:59,294 - DEBUG - VN ctest-vn-88184632 is not present in Agent 10.0.0.189
2026-08-20 19:34:59,294 - INFO - Validated that VN ctest-vn-88184632 is not in any agent
2026-08-20 19:34:59,309 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn-88184632 info
2026-08-20 19:34:59,568 - DEBUG - No XMPP flaps were noticed during the test
2026-08-20 19:34:59,569 - INFO - END TEST : test_basic_policy_allow_deny : PASSED[0:01:08]
2026-08-20 19:34:59,569 - INFO - --------------------------------------------------------------------------------
2026-08-20 19:35:00,341 - INFO - Deleted project: ctest-TestBasicPolicy-40091747, ID : 7d27488c-1a9c-4131-a548-43d90488dad0