is in ACTIVE state now
2026-08-03 04:29:34,883 - INFO - VM name : ctest-TestvifStats-20510914-48463312
2026-08-03 04:29:34,975 - DEBUG - VM ctest-TestvifStats-20510914-48463312 ID is 887af280-7bd4-4da6-b503-1a9a9136af9c
2026-08-03 04:29:34,975 - DEBUG - VM ctest-TestvifStats-20510914-48463312 launched on Node an-jenkins-deploy-platform-ansible-os-6129-2
2026-08-03 04:29:35,100 - DEBUG - Requesting: http://10.0.0.161:8082/virtual-machine/887af280-7bd4-4da6-b503-1a9a9136af9c
2026-08-03 04:29:35,115 - DEBUG - Requesting: http://10.0.0.161:8082/virtual-machine-interface/0ab1f1fd-1f25-4c1d-853b-1fb75600b59d
2026-08-03 04:29:36,325 - 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.83 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=21.8 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 = 4.832/13.303/21.774/8.471 ms')
2026-08-03 04:29:36,325 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-TestvifStats-20510914-48463312 passed
2026-08-03 04:29:36,406 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-08-03 04:29:36,406 - DEBUG - Waiting to SSH to VM ctest-TestvifStats-20510914-48463312, IP 148.108.215.196, Port 22
2026-08-03 04:29:36,484 - DEBUG - Error on ssh to ubuntu@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-03 04:29:36,577 - DEBUG - VM ctest-TestvifStats-20510914-48463312 is NOT ready for SSH connections, VM status: ACTIVE
2026-08-03 04:29:41,578 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-08-03 04:29:41,578 - DEBUG - Waiting to SSH to VM ctest-TestvifStats-20510914-48463312, IP 148.108.215.196, Port 22
2026-08-03 04:29:41,647 - DEBUG - Error on ssh to ubuntu@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-03 04:29:41,797 - DEBUG - VM ctest-TestvifStats-20510914-48463312 is NOT ready for SSH connections, VM status: ACTIVE
2026-08-03 04:29:46,798 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-08-03 04:29:46,799 - DEBUG - Waiting to SSH to VM ctest-TestvifStats-20510914-48463312, IP 148.108.215.196, Port 22
2026-08-03 04:29:46,970 - DEBUG - VM ctest-TestvifStats-20510914-48463312 is ready for SSH connections
2026-08-03 04:29:46,970 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-08-03 04:29:46,970 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 148.108.215.196, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.19, gateway password: c0ntrail123
2026-08-03 04:29:46,970 - DEBUG - ping -s 56 -c 3 -W 1 148.108.215.196
2026-08-03 04:30:48,553 - DEBUG - PING 148.108.215.196 (148.108.215.196) 56(84) bytes of data.
64 bytes from 148.108.215.196: icmp_req=1 ttl=64 time=10.9 ms
64 bytes from 148.108.215.196: icmp_req=2 ttl=64 time=1.29 ms
64 bytes from 148.108.215.196: icmp_req=3 ttl=64 time=1.18 ms
--- 148.108.215.196 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2005ms
rtt min/avg/max/mdev = 1.182/4.473/10.939/4.572 ms
2026-08-03 04:30:48,554 - INFO - Ping to IP 148.108.215.196 from VM ctest-TestvifStats-20510914-42138802 passed
2026-08-03 04:30:48,554 - INFO - 10.0.0.19
2026-08-03 04:30:48,554 - INFO - vif --get 0 | grep "RX packets" | tr -s ' ' | cut -f1-3 -d ' '
2026-08-03 04:30:48,689 - INFO - 13443
2026-08-03 04:30:48,800 - INFO - TX packets:14661
2026-08-03 04:30:48,800 - INFO - 14661
2026-08-03 04:30:48,800 - INFO - vif --get 1 | grep "RX packets" | tr -s ' ' | cut -f1-3 -d ' '
2026-08-03 04:30:48,917 - INFO - 22873
2026-08-03 04:30:49,054 - INFO - TX packets:22424
2026-08-03 04:30:49,055 - INFO - 22424
2026-08-03 04:30:49,055 - INFO - vif --get 2 | grep "RX packets" | tr -s ' ' | cut -f1-3 -d ' '
2026-08-03 04:30:49,176 - INFO - 9557
2026-08-03 04:30:49,304 - INFO - TX packets:8400
2026-08-03 04:30:49,304 - INFO - 8400
2026-08-03 04:30:49,304 - INFO - vif --get 3 | grep "RX packets" | tr -s ' ' | cut -f1-3 -d ' '
2026-08-03 04:30:49,413 - INFO - 296
2026-08-03 04:30:49,524 - INFO - TX packets:355
2026-08-03 04:30:49,524 - INFO - 355
2026-08-03 04:30:49,524 - INFO - vif --get 4 | grep "RX packets" | tr -s ' ' | cut -f1-3 -d ' '
2026-08-03 04:30:49,646 - INFO - 249
2026-08-03 04:30:49,757 - INFO - TX packets:290
2026-08-03 04:30:49,758 - INFO - 290
2026-08-03 04:30:49,758 - INFO - 10.0.0.19
2026-08-03 04:30:49,873 - INFO - Vif stats cleared successfully on all cores for all interfaces
2026-08-03 04:30:49,873 - INFO - 10.0.0.19
2026-08-03 04:30:49,873 - INFO - vif --get 0 | grep "RX packets" | tr -s ' ' | cut -f1-3 -d ' '
2026-08-03 04:30:49,989 - INFO - 0
2026-08-03 04:30:50,115 - INFO - TX packets:0
2026-08-03 04:30:50,115 - INFO - 0
2026-08-03 04:30:50,115 - INFO - vif --get 1 | grep "RX packets" | tr -s ' ' | cut -f1-3 -d ' '
2026-08-03 04:30:50,224 - INFO - 0
2026-08-03 04:30:50,340 - INFO - TX packets:0
2026-08-03 04:30:50,340 - INFO - 0
2026-08-03 04:30:50,340 - INFO - vif --get 2 | grep "RX packets" | tr -s ' ' | cut -f1-3 -d ' '
2026-08-03 04:30:50,463 - INFO - 1
2026-08-03 04:30:50,588 - INFO - TX packets:1
2026-08-03 04:30:50,588 - INFO - 1
2026-08-03 04:30:50,588 - INFO - vif --get 3 | grep "RX packets" | tr -s ' ' | cut -f1-3 -d ' '
2026-08-03 04:30:50,725 - INFO - 0
2026-08-03 04:30:50,850 - INFO - TX packets:1
2026-08-03 04:30:50,850 - INFO - 1
2026-08-03 04:30:50,851 - INFO - vif --get 4 | grep "RX packets" | tr -s ' ' | cut -f1-3 -d ' '
2026-08-03 04:30:50,971 - INFO - 1
2026-08-03 04:30:51,085 - INFO - TX packets:1
2026-08-03 04:30:51,085 - INFO - 1
2026-08-03 04:30:51,085 - INFO - Deleting VM ctest-TestvifStats-20510914-48463312
2026-08-03 04:30:51,203 - INFO - Deleting VM ctest-TestvifStats-20510914-42138802
2026-08-03 04:30:51,313 - INFO - Deleting VN ctest-vn-53756712
2026-08-03 04:30:51,389 - DEBUG - VN 52316dd6-7455-4d26-8e27-5ed03c1b82bf still in use: Unable to complete operation on network 52316dd6-7455-4d26-8e27-5ed03c1b82bf. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-33cf308a-5b0d-4169-b34b-66d0a7d4ac0d']
2026-08-03 04:30:51,390 - WARNING - Deleting VN ctest-vn-53756712 failed..Will retry
2026-08-03 04:30:53,472 - DEBUG - VN 52316dd6-7455-4d26-8e27-5ed03c1b82bf still in use: Unable to complete operation on network 52316dd6-7455-4d26-8e27-5ed03c1b82bf. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-c6b37e22-67df-4953-94dd-499768a92ee1']
2026-08-03 04:30:53,472 - WARNING - Deleting VN ctest-vn-53756712 failed..Will retry
2026-08-03 04:30:55,677 - DEBUG - Response for deleting network ()
2026-08-03 04:30:55,677 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/a561e2c6-2206-4e92-a809-233711aa4437
2026-08-03 04:30:55,687 - DEBUG - Response Code: 404
2026-08-03 04:30:55,687 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2026-08-03 04:30:55,695 - DEBUG - Requesting: http://10.0.0.249:8082/domain/724de60e-9f87-4d23-867c-6bd9a9880f5b
2026-08-03 04:30:55,717 - DEBUG - Requesting: http://10.0.0.249:8082/project/b32f3d3e-8ceb-46da-b353-73620a2045f7
2026-08-03 04:30:55,860 - INFO - Validated that VN ctest-vn-53756712 is not found in API Server
2026-08-03 04:30:55,878 - DEBUG - VN ctest-vn-53756712 is not present in Agent 10.0.0.38
2026-08-03 04:30:55,889 - DEBUG - VN ctest-vn-53756712 is not present in Agent 10.0.0.19
2026-08-03 04:30:55,889 - INFO - Validated that VN ctest-vn-53756712 is not in any agent
2026-08-03 04:30:55,956 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn-53756712 info
2026-08-03 04:30:57,361 - DEBUG - No XMPP flaps were noticed during the test
2026-08-03 04:30:57,361 - INFO - END TEST : test_vif_statistics : PASSED[0:03:17]
2026-08-03 04:30:57,361 - INFO - --------------------------------------------------------------------------------
2026-08-03 04:30:58,985 - INFO - Deleted project: ctest-TestvifStats-20510914, ID : b32f3d3e-8ceb-46da-b353-73620a2045f7