is in ACTIVE state now
2025-04-24 19:34:00,907 - INFO - VM name : ctest-TestBasicPolicy-62196395-12961098
2025-04-24 19:34:00,989 - DEBUG - VM ctest-TestBasicPolicy-62196395-12961098 ID is 702473eb-6ff2-44c4-91cd-d4640963b9e4
2025-04-24 19:34:00,989 - DEBUG - VM ctest-TestBasicPolicy-62196395-12961098 launched on Node cn-jenkins-deploy-platform-ansible-os-3207-1
2025-04-24 19:34:01,077 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-machine/702473eb-6ff2-44c4-91cd-d4640963b9e4
2025-04-24 19:34:01,087 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-machine-interface/4c7d4c6a-5e3c-49b9-a291-7c0b80616df3
2025-04-24 19:34:02,357 - 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=4.90 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.548 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.548/2.722/4.896/2.174 ms')
2025-04-24 19:34:02,357 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-TestBasicPolicy-62196395-12961098 passed
2025-04-24 19:34:02,509 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-04-24 19:34:02,509 - DEBUG - Waiting to SSH to VM ctest-TestBasicPolicy-62196395-12961098, IP 161.253.39.195, Port 22
2025-04-24 19:34:02,669 - DEBUG - VM ctest-TestBasicPolicy-62196395-12961098 is ready for SSH connections
2025-04-24 19:34:02,669 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-04-24 19:34:02,669 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 161.253.39.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.254, gateway password: c0ntrail123
2025-04-24 19:34:02,669 - DEBUG - ping -s 56 -c 3 -W 1 161.253.39.195
2025-04-24 19:34:06,718 - DEBUG - PING 161.253.39.195 (161.253.39.195): 56 data bytes
64 bytes from 161.253.39.195: seq=0 ttl=63 time=3.777 ms
64 bytes from 161.253.39.195: seq=1 ttl=63 time=0.926 ms
64 bytes from 161.253.39.195: seq=2 ttl=63 time=0.796 ms
--- 161.253.39.195 ping statistics ---
3 packets transmitted, 3 packets received, 0% packet loss
round-trip min/avg/max = 0.796/1.833/3.777 ms
2025-04-24 19:34:06,718 - INFO - Ping to IP 161.253.39.195 from VM ctest-TestBasicPolicy-62196395-96173402 passed
2025-04-24 19:34:06,901 - DEBUG - Policy Update Response {"network-policy": {"uuid": "2ef14c4b-a3c3-4593-a4b9-2c92ae7e0eb5", "href": "http://10.0.0.254:8082/network-policy/2ef14c4b-a3c3-4593-a4b9-2c92ae7e0eb5"}}
2025-04-24 19:34:06,901 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-04-24 19:34:06,901 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 161.253.39.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.254, gateway password: c0ntrail123
2025-04-24 19:34:06,902 - DEBUG - ping -s 56 -c 3 -W 1 161.253.39.195
2025-04-24 19:34:10,117 - DEBUG - PING 161.253.39.195 (161.253.39.195): 56 data bytes
64 bytes from 161.253.39.195: seq=0 ttl=63 time=1.550 ms
--- 161.253.39.195 ping statistics ---
3 packets transmitted, 1 packets received, 66% packet loss
round-trip min/avg/max = 1.550/1.550/1.550 ms
2025-04-24 19:34:10,118 - WARNING - Ping to IP 161.253.39.195 from VM ctest-TestBasicPolicy-62196395-96173402 failed
2025-04-24 19:34:10,118 - INFO - Deleting VM ctest-TestBasicPolicy-62196395-12961098
2025-04-24 19:34:10,186 - INFO - Deleting VM ctest-TestBasicPolicy-62196395-96173402
2025-04-24 19:34:10,579 - INFO - Deleted policy ctest-policy-allow-all-83817781
2025-04-24 19:34:10,580 - INFO - Deleting VN ctest-vn-81008996
2025-04-24 19:34:10,615 - DEBUG - VN 70e7abcc-75fd-4adb-813a-33c8f615f33f still in use: Unable to complete operation on network 70e7abcc-75fd-4adb-813a-33c8f615f33f. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-86d60d37-8ee3-4622-8748-3e053ff358e3']
2025-04-24 19:34:10,615 - WARNING - Deleting VN ctest-vn-81008996 failed..Will retry
2025-04-24 19:34:12,714 - DEBUG - Response for deleting network ()
2025-04-24 19:34:12,714 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/ba40df83-bfb2-45d8-bb09-c99be9b05ece
2025-04-24 19:34:12,721 - DEBUG - Response Code: 404
2025-04-24 19:34:12,721 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-04-24 19:34:12,727 - DEBUG - Requesting: http://10.0.0.254:8082/domain/f9f6d5b2-4674-4c14-b2d6-6a6862386506
2025-04-24 19:34:12,788 - DEBUG - Requesting: http://10.0.0.254:8082/project/ee7b816e-42b2-45a7-970e-169207280d2b
2025-04-24 19:34:12,864 - INFO - Validated that VN ctest-vn-81008996 is not found in API Server
2025-04-24 19:34:12,876 - DEBUG - VN ctest-vn-81008996 is not present in Agent 10.0.0.254
2025-04-24 19:34:12,876 - INFO - Validated that VN ctest-vn-81008996 is not in any agent
2025-04-24 19:34:12,892 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn-81008996 info
2025-04-24 19:34:12,892 - INFO - Deleting VN ctest-vn-69494467
2025-04-24 19:34:12,997 - DEBUG - Response for deleting network ()
2025-04-24 19:34:12,997 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/f6381a4c-67ac-4dba-b5da-548318dac01b
2025-04-24 19:34:13,003 - DEBUG - Response Code: 404
2025-04-24 19:34:13,004 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-04-24 19:34:13,010 - DEBUG - Requesting: http://10.0.0.254:8082/domain/f9f6d5b2-4674-4c14-b2d6-6a6862386506
2025-04-24 19:34:13,067 - DEBUG - Requesting: http://10.0.0.254:8082/project/ee7b816e-42b2-45a7-970e-169207280d2b
2025-04-24 19:34:13,142 - INFO - Validated that VN ctest-vn-69494467 is not found in API Server
2025-04-24 19:34:13,153 - DEBUG - VN ctest-vn-69494467 is not present in Agent 10.0.0.254
2025-04-24 19:34:13,153 - INFO - Validated that VN ctest-vn-69494467 is not in any agent
2025-04-24 19:34:13,170 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn-69494467 info
2025-04-24 19:34:13,424 - DEBUG - No XMPP flaps were noticed during the test
2025-04-24 19:34:13,424 - INFO - END TEST : test_basic_policy_allow_deny : PASSED[0:00:30]
2025-04-24 19:34:13,424 - INFO - --------------------------------------------------------------------------------
2025-04-24 19:34:14,169 - INFO - Deleted project: ctest-TestBasicPolicy-62196395, ID : ee7b816e-42b2-45a7-970e-169207280d2b