is in ACTIVE state now
2025-08-25 21:20:55,786 - INFO - VM name : ctest-vn2-vm1-20646770
2025-08-25 21:20:55,868 - DEBUG - VM ctest-vn2-vm1-20646770 ID is edfd1410-a889-489b-b749-ccc097bc161c
2025-08-25 21:20:55,869 - DEBUG - VM ctest-vn2-vm1-20646770 launched on Node cn-jenkins-deploy-platform-ansible-os-3929-1
2025-08-25 21:20:55,960 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/edfd1410-a889-489b-b749-ccc097bc161c
2025-08-25 21:20:55,971 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/f7cf6527-3e8e-482a-9612-f2690b7a2085
2025-08-25 21:20:57,230 - 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.96 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.757 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 = 0.757/3.858/6.960/3.101 ms')
2025-08-25 21:20:57,230 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-20646770 passed
2025-08-25 21:20:57,353 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-25 21:20:57,353 - DEBUG - Waiting to SSH to VM ctest-vn2-vm1-20646770, IP 45.29.116.3, Port 22
2025-08-25 21:20:57,423 - 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-08-25 21:20:57,534 - DEBUG - VM ctest-vn2-vm1-20646770 is NOT ready for SSH connections, VM status: ACTIVE
2025-08-25 21:21:02,535 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-25 21:21:02,535 - DEBUG - Waiting to SSH to VM ctest-vn2-vm1-20646770, IP 45.29.116.3, Port 22
2025-08-25 21:21:02,701 - DEBUG - VM ctest-vn2-vm1-20646770 is ready for SSH connections
2025-08-25 21:21:02,701 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-25 21:21:02,701 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 45.29.116.3, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.38, gateway password: c0ntrail123
2025-08-25 21:21:02,701 - DEBUG - ping -s 56 -c 3 -W 1 45.29.116.3
2025-08-25 21:21:07,366 - DEBUG - PING 45.29.116.3 (45.29.116.3): 56 data bytes
--- 45.29.116.3 ping statistics ---
3 packets transmitted, 0 packets received, 100% packet loss
2025-08-25 21:21:07,366 - WARNING - Ping to IP 45.29.116.3 from VM ctest-vn1-vm1-35634217 failed
2025-08-25 21:21:07,366 - INFO - Adding interface with subnet_id d8bca18a-f26a-48e2-bb5d-7f3ed4ddb9f5, port_id None to router 3fd5815b-e615-4fc4-99af-ebbbc4563ebf
2025-08-25 21:21:07,726 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-25 21:21:07,726 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 45.29.116.3, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.38, gateway password: c0ntrail123
2025-08-25 21:21:07,726 - DEBUG - ping -s 56 -c 3 -W 1 45.29.116.3
2025-08-25 21:21:10,970 - DEBUG - PING 45.29.116.3 (45.29.116.3): 56 data bytes
64 bytes from 45.29.116.3: seq=1 ttl=63 time=2.998 ms
64 bytes from 45.29.116.3: seq=2 ttl=63 time=0.829 ms
--- 45.29.116.3 ping statistics ---
3 packets transmitted, 2 packets received, 33% packet loss
round-trip min/avg/max = 0.829/1.913/2.998 ms
2025-08-25 21:21:10,970 - WARNING - Ping to IP 45.29.116.3 from VM ctest-vn1-vm1-35634217 failed
2025-08-25 21:21:11,970 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 45.29.116.3, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.38, gateway password: c0ntrail123
2025-08-25 21:21:11,970 - DEBUG - ping -s 56 -c 3 -W 1 45.29.116.3
2025-08-25 21:21:14,236 - DEBUG - PING 45.29.116.3 (45.29.116.3): 56 data bytes
64 bytes from 45.29.116.3: seq=0 ttl=63 time=1.558 ms
64 bytes from 45.29.116.3: seq=1 ttl=63 time=0.847 ms
64 bytes from 45.29.116.3: seq=2 ttl=63 time=0.896 ms
--- 45.29.116.3 ping statistics ---
3 packets transmitted, 3 packets received, 0% packet loss
round-trip min/avg/max = 0.847/1.100/1.558 ms
2025-08-25 21:21:14,236 - INFO - Ping to IP 45.29.116.3 from VM ctest-vn1-vm1-35634217 passed
2025-08-25 21:21:14,236 - INFO - Deleting interface with subnet_id 9f172e2e-59b3-4310-b059-0f46ec9d1b66, port_id None from router 3fd5815b-e615-4fc4-99af-ebbbc4563ebf
2025-08-25 21:21:14,381 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-25 21:21:14,381 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 45.29.116.3, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.38, gateway password: c0ntrail123
2025-08-25 21:21:14,381 - DEBUG - ping -s 56 -c 3 -W 1 45.29.116.3
2025-08-25 21:21:17,585 - DEBUG - PING 45.29.116.3 (45.29.116.3): 56 data bytes
--- 45.29.116.3 ping statistics ---
3 packets transmitted, 0 packets received, 100% packet loss
2025-08-25 21:21:17,586 - WARNING - Ping to IP 45.29.116.3 from VM ctest-vn1-vm1-35634217 failed
2025-08-25 21:21:17,586 - INFO - Adding interface with subnet_id 9f172e2e-59b3-4310-b059-0f46ec9d1b66, port_id None to router 3fd5815b-e615-4fc4-99af-ebbbc4563ebf
2025-08-25 21:21:17,827 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-25 21:21:17,827 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 45.29.116.3, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.38, gateway password: c0ntrail123
2025-08-25 21:21:17,827 - DEBUG - ping -s 56 -c 3 -W 1 45.29.116.3
2025-08-25 21:21:20,054 - DEBUG - PING 45.29.116.3 (45.29.116.3): 56 data bytes
64 bytes from 45.29.116.3: seq=0 ttl=63 time=1.730 ms
64 bytes from 45.29.116.3: seq=1 ttl=63 time=0.779 ms
64 bytes from 45.29.116.3: seq=2 ttl=63 time=0.880 ms
--- 45.29.116.3 ping statistics ---
3 packets transmitted, 3 packets received, 0% packet loss
round-trip min/avg/max = 0.779/1.129/1.730 ms
2025-08-25 21:21:20,055 - INFO - Ping to IP 45.29.116.3 from VM ctest-vn1-vm1-35634217 passed
2025-08-25 21:21:20,055 - INFO - Deleting interface with subnet_id d8bca18a-f26a-48e2-bb5d-7f3ed4ddb9f5, port_id None from router 3fd5815b-e615-4fc4-99af-ebbbc4563ebf
2025-08-25 21:21:20,202 - INFO - Deleting interface with subnet_id 9f172e2e-59b3-4310-b059-0f46ec9d1b66, port_id None from router 3fd5815b-e615-4fc4-99af-ebbbc4563ebf
2025-08-25 21:21:20,405 - INFO - Deleting VM ctest-vn2-vm1-20646770
2025-08-25 21:21:20,483 - INFO - Deleting VM ctest-vn1-vm1-35634217
2025-08-25 21:21:20,548 - INFO - Deleting VN ctest-vn2-72451048
2025-08-25 21:21:20,588 - DEBUG - VN 2aca147b-0f6d-45c0-8cb8-5e5edd5b8d8e still in use: Unable to complete operation on network 2aca147b-0f6d-45c0-8cb8-5e5edd5b8d8e. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-839df75d-b0b8-4350-95e9-e2cb90e42849']
2025-08-25 21:21:20,589 - WARNING - Deleting VN ctest-vn2-72451048 failed..Will retry
2025-08-25 21:21:22,713 - DEBUG - Response for deleting network ()
2025-08-25 21:21:22,714 - INFO - Deleting VN ctest-vn1-24276374
2025-08-25 21:21:22,868 - DEBUG - Response for deleting network ()
2025-08-25 21:21:23,123 - DEBUG - No XMPP flaps were noticed during the test
2025-08-25 21:21:23,123 - INFO - END TEST : test_basic_router_behavior : PASSED[0:00:44]
2025-08-25 21:21:23,123 - INFO - --------------------------------------------------------------------------------
2025-08-25 21:21:23,790 - INFO - Deleted project: ctest-TestRouters-30558353, ID : ff909066-d138-49bd-babb-9dc4f1f232c7