is in ACTIVE state now
2025-01-13 02:19:53,856 - INFO - VM name : ctest-TestBasicPolicy-09968244-29946521
2025-01-13 02:19:53,945 - DEBUG - VM ctest-TestBasicPolicy-09968244-29946521 ID is 32d94322-d0ab-4907-994a-807e52f59210
2025-01-13 02:19:53,946 - DEBUG - VM ctest-TestBasicPolicy-09968244-29946521 launched on Node cn-jenkins-deploy-platform-ansible-os-2604-1.
2025-01-13 02:19:54,031 - DEBUG - Requesting: http://10.0.0.12:8082/virtual-machine/32d94322-d0ab-4907-994a-807e52f59210
2025-01-13 02:19:54,043 - DEBUG - Requesting: http://10.0.0.12:8082/virtual-machine-interface/a5f60a1b-d26f-4e63-9233-1d7e1c90baee
2025-01-13 02:19:55,303 - 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.89 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=2.75 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 = 2.754/3.321/3.888/0.567 ms')
2025-01-13 02:19:55,303 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-TestBasicPolicy-09968244-29946521 passed
2025-01-13 02:19:55,463 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-01-13 02:19:55,464 - DEBUG - Waiting to SSH to VM ctest-TestBasicPolicy-09968244-29946521, IP 118.60.213.195, Port 22
2025-01-13 02:19:55,533 - 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-01-13 02:19:55,620 - DEBUG - VM ctest-TestBasicPolicy-09968244-29946521 is NOT ready for SSH connections, VM status: ACTIVE
2025-01-13 02:20:00,621 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-01-13 02:20:00,621 - DEBUG - Waiting to SSH to VM ctest-TestBasicPolicy-09968244-29946521, IP 118.60.213.195, Port 22
2025-01-13 02:20:00,788 - DEBUG - VM ctest-TestBasicPolicy-09968244-29946521 is ready for SSH connections
2025-01-13 02:20:00,788 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-01-13 02:20:00,788 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 118.60.213.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.12, gateway password: c0ntrail123
2025-01-13 02:20:00,788 - DEBUG - ping -s 56 -c 3 -W 1 118.60.213.195
2025-01-13 02:20:05,080 - DEBUG - PING 118.60.213.195 (118.60.213.195): 56 data bytes
64 bytes from 118.60.213.195: seq=0 ttl=63 time=4.512 ms
64 bytes from 118.60.213.195: seq=1 ttl=63 time=1.265 ms
64 bytes from 118.60.213.195: seq=2 ttl=63 time=0.984 ms
--- 118.60.213.195 ping statistics ---
3 packets transmitted, 3 packets received, 0% packet loss
round-trip min/avg/max = 0.984/2.253/4.512 ms
2025-01-13 02:20:05,080 - INFO - Ping to IP 118.60.213.195 from VM ctest-TestBasicPolicy-09968244-90327259 passed
2025-01-13 02:20:05,291 - DEBUG - Policy Update Response {"network-policy": {"uuid": "9c92168b-1f32-42f6-8522-655de51f492f", "href": "http://10.0.0.12:8082/network-policy/9c92168b-1f32-42f6-8522-655de51f492f"}}
2025-01-13 02:20:05,291 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-01-13 02:20:05,291 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 118.60.213.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.12, gateway password: c0ntrail123
2025-01-13 02:20:05,291 - DEBUG - ping -s 56 -c 3 -W 1 118.60.213.195
2025-01-13 02:20:08,576 - DEBUG - PING 118.60.213.195 (118.60.213.195): 56 data bytes
64 bytes from 118.60.213.195: seq=0 ttl=63 time=1.789 ms
--- 118.60.213.195 ping statistics ---
3 packets transmitted, 1 packets received, 66% packet loss
round-trip min/avg/max = 1.789/1.789/1.789 ms
2025-01-13 02:20:08,576 - WARNING - Ping to IP 118.60.213.195 from VM ctest-TestBasicPolicy-09968244-90327259 failed
2025-01-13 02:20:08,577 - INFO - Deleting VM ctest-TestBasicPolicy-09968244-29946521
2025-01-13 02:20:08,666 - INFO - Deleting VM ctest-TestBasicPolicy-09968244-90327259
2025-01-13 02:20:09,208 - INFO - Deleted policy ctest-policy-allow-all-34073275
2025-01-13 02:20:09,209 - INFO - Deleting VN ctest-vn-66664986
2025-01-13 02:20:09,262 - DEBUG - VN 04f017d3-dee9-446f-aaba-18f95976ac7b still in use: Unable to complete operation on network 04f017d3-dee9-446f-aaba-18f95976ac7b. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-e4809b34-e7c6-455d-bac8-496ebf2986d7']
2025-01-13 02:20:09,262 - WARNING - Deleting VN ctest-vn-66664986 failed..Will retry
2025-01-13 02:20:11,400 - DEBUG - Response for deleting network ()
2025-01-13 02:20:11,400 - DEBUG - Requesting: http://10.0.0.12:8082/routing-instance/19674499-a70b-4cc7-9abd-88b5286b18d5
2025-01-13 02:20:11,409 - DEBUG - Response Code: 404
2025-01-13 02:20:11,409 - DEBUG - Requesting: http://10.0.0.12:8082/domains
2025-01-13 02:20:11,418 - DEBUG - Requesting: http://10.0.0.12:8082/domain/316045fe-f080-4781-9f74-be9903cea8a2
2025-01-13 02:20:11,478 - DEBUG - Requesting: http://10.0.0.12:8082/project/f15a6038-3a60-4b4c-bf79-d5e085b6b1b1
2025-01-13 02:20:11,569 - INFO - Validated that VN ctest-vn-66664986 is not found in API Server
2025-01-13 02:20:11,583 - DEBUG - VN ctest-vn-66664986 is not present in Agent 10.0.0.12
2025-01-13 02:20:11,583 - INFO - Validated that VN ctest-vn-66664986 is not in any agent
2025-01-13 02:20:11,602 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn-66664986 info
2025-01-13 02:20:11,603 - INFO - Deleting VN ctest-vn-21361885
2025-01-13 02:20:11,743 - DEBUG - Response for deleting network ()
2025-01-13 02:20:11,744 - DEBUG - Requesting: http://10.0.0.12:8082/routing-instance/eb0db79b-91ff-4731-9344-a18dda24e128
2025-01-13 02:20:11,755 - DEBUG - Response Code: 404
2025-01-13 02:20:11,756 - DEBUG - Requesting: http://10.0.0.12:8082/domains
2025-01-13 02:20:11,763 - DEBUG - Requesting: http://10.0.0.12:8082/domain/316045fe-f080-4781-9f74-be9903cea8a2
2025-01-13 02:20:11,826 - DEBUG - Requesting: http://10.0.0.12:8082/project/f15a6038-3a60-4b4c-bf79-d5e085b6b1b1
2025-01-13 02:20:11,925 - INFO - Validated that VN ctest-vn-21361885 is not found in API Server
2025-01-13 02:20:11,942 - DEBUG - VN ctest-vn-21361885 is not present in Agent 10.0.0.12
2025-01-13 02:20:11,942 - INFO - Validated that VN ctest-vn-21361885 is not in any agent
2025-01-13 02:20:11,965 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn-21361885 info
2025-01-13 02:20:12,238 - DEBUG - No XMPP flaps were noticed during the test
2025-01-13 02:20:12,238 - INFO - END TEST : test_basic_policy_allow_deny : PASSED[0:00:37]
2025-01-13 02:20:12,238 - INFO - --------------------------------------------------------------------------------
2025-01-13 02:20:13,010 - INFO - Deleted project: ctest-TestBasicPolicy-09968244, ID : f15a6038-3a60-4b4c-bf79-d5e085b6b1b1