is in ACTIVE state now
2025-10-31 09:01:57,516 - INFO - VM name : ctest-TestBasicPolicy-50973276-65310765
2025-10-31 09:01:57,612 - DEBUG - VM ctest-TestBasicPolicy-50973276-65310765 ID is 8753a47d-7cd6-4cfd-ae61-4b7743767a36
2025-10-31 09:01:57,613 - DEBUG - VM ctest-TestBasicPolicy-50973276-65310765 launched on Node cn-jenkins-deploy-platform-ansible-os-4573-1
2025-10-31 09:01:57,708 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-machine/8753a47d-7cd6-4cfd-ae61-4b7743767a36
2025-10-31 09:01:57,717 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-machine-interface/89a4ed34-2f17-477e-9d34-7b5d86e939ce
2025-10-31 09:01:58,981 - 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=6.30 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=2.50 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.502/4.401/6.300/1.899 ms')
2025-10-31 09:01:58,981 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-TestBasicPolicy-50973276-65310765 passed
2025-10-31 09:01:59,136 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-31 09:01:59,137 - DEBUG - Waiting to SSH to VM ctest-TestBasicPolicy-50973276-65310765, IP 3.61.255.3, Port 22
2025-10-31 09:01:59,203 - 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-10-31 09:01:59,290 - DEBUG - VM ctest-TestBasicPolicy-50973276-65310765 is NOT ready for SSH connections, VM status: ACTIVE
2025-10-31 09:02:04,291 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-31 09:02:04,291 - DEBUG - Waiting to SSH to VM ctest-TestBasicPolicy-50973276-65310765, IP 3.61.255.3, Port 22
2025-10-31 09:02:04,467 - DEBUG - VM ctest-TestBasicPolicy-50973276-65310765 is ready for SSH connections
2025-10-31 09:02:04,467 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-31 09:02:04,467 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 3.61.255.3, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.254, gateway password: c0ntrail123
2025-10-31 09:02:04,467 - DEBUG - ping -s 56 -c 3 -W 1 3.61.255.3
2025-10-31 09:02:08,568 - DEBUG - PING 3.61.255.3 (3.61.255.3): 56 data bytes
64 bytes from 3.61.255.3: seq=0 ttl=63 time=4.221 ms
64 bytes from 3.61.255.3: seq=1 ttl=63 time=1.377 ms
64 bytes from 3.61.255.3: seq=2 ttl=63 time=1.227 ms
--- 3.61.255.3 ping statistics ---
3 packets transmitted, 3 packets received, 0% packet loss
round-trip min/avg/max = 1.227/2.275/4.221 ms
2025-10-31 09:02:08,568 - INFO - Ping to IP 3.61.255.3 from VM ctest-TestBasicPolicy-50973276-98724725 passed
2025-10-31 09:02:08,752 - DEBUG - Policy Update Response {"network-policy": {"uuid": "5798fbd4-bab4-4c86-b0c9-33f9a986c079", "href": "http://10.0.0.254:8082/network-policy/5798fbd4-bab4-4c86-b0c9-33f9a986c079"}}
2025-10-31 09:02:08,752 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-31 09:02:08,752 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 3.61.255.3, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.254, gateway password: c0ntrail123
2025-10-31 09:02:08,753 - DEBUG - ping -s 56 -c 3 -W 1 3.61.255.3
2025-10-31 09:02:11,987 - DEBUG - PING 3.61.255.3 (3.61.255.3): 56 data bytes
--- 3.61.255.3 ping statistics ---
3 packets transmitted, 0 packets received, 100% packet loss
2025-10-31 09:02:11,988 - WARNING - Ping to IP 3.61.255.3 from VM ctest-TestBasicPolicy-50973276-98724725 failed
2025-10-31 09:02:11,988 - INFO - Deleting VM ctest-TestBasicPolicy-50973276-65310765
2025-10-31 09:02:12,092 - INFO - Deleting VM ctest-TestBasicPolicy-50973276-98724725
2025-10-31 09:02:12,476 - INFO - Deleted policy ctest-policy-allow-all-48018257
2025-10-31 09:02:12,476 - INFO - Deleting VN ctest-vn-76523744
2025-10-31 09:02:12,513 - DEBUG - VN 8cfdc7a6-211b-4680-9684-74fbd22907e8 still in use: Unable to complete operation on network 8cfdc7a6-211b-4680-9684-74fbd22907e8. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-5bf08bf8-4d5f-479a-bf9d-e461bd249907']
2025-10-31 09:02:12,513 - WARNING - Deleting VN ctest-vn-76523744 failed..Will retry
2025-10-31 09:02:14,633 - DEBUG - Response for deleting network ()
2025-10-31 09:02:14,633 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/970d8a52-df28-446c-8fb3-02802298ff14
2025-10-31 09:02:14,642 - DEBUG - Response Code: 404
2025-10-31 09:02:14,643 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-10-31 09:02:14,650 - DEBUG - Requesting: http://10.0.0.254:8082/domain/2edc14c0-6a3e-4d2a-9edf-b1e7cdce0fb0
2025-10-31 09:02:14,707 - DEBUG - Requesting: http://10.0.0.254:8082/project/28a16e2e-228d-4e21-b81f-4b4ba8457724
2025-10-31 09:02:14,781 - INFO - Validated that VN ctest-vn-76523744 is not found in API Server
2025-10-31 09:02:14,792 - DEBUG - VN ctest-vn-76523744 is not present in Agent 10.0.0.254
2025-10-31 09:02:14,792 - INFO - Validated that VN ctest-vn-76523744 is not in any agent
2025-10-31 09:02:14,807 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn-76523744 info
2025-10-31 09:02:14,807 - INFO - Deleting VN ctest-vn-37539573
2025-10-31 09:02:14,931 - DEBUG - Response for deleting network ()
2025-10-31 09:02:14,931 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/3820b9a2-0e84-4912-ac09-8b7b2736d239
2025-10-31 09:02:14,937 - DEBUG - Response Code: 404
2025-10-31 09:02:14,937 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-10-31 09:02:14,944 - DEBUG - Requesting: http://10.0.0.254:8082/domain/2edc14c0-6a3e-4d2a-9edf-b1e7cdce0fb0
2025-10-31 09:02:14,998 - DEBUG - Requesting: http://10.0.0.254:8082/project/28a16e2e-228d-4e21-b81f-4b4ba8457724
2025-10-31 09:02:15,072 - INFO - Validated that VN ctest-vn-37539573 is not found in API Server
2025-10-31 09:02:15,083 - DEBUG - VN ctest-vn-37539573 is not present in Agent 10.0.0.254
2025-10-31 09:02:15,083 - INFO - Validated that VN ctest-vn-37539573 is not in any agent
2025-10-31 09:02:15,100 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn-37539573 info
2025-10-31 09:02:15,362 - DEBUG - No XMPP flaps were noticed during the test
2025-10-31 09:02:15,363 - INFO - END TEST : test_basic_policy_allow_deny : PASSED[0:00:36]
2025-10-31 09:02:15,363 - INFO - --------------------------------------------------------------------------------
2025-10-31 09:02:16,088 - INFO - Deleted project: ctest-TestBasicPolicy-50973276, ID : 28a16e2e-228d-4e21-b81f-4b4ba8457724