is in ACTIVE state now
2025-12-15 03:50:57,523 - INFO - VM name : ctest-TestvifStats-82028712-77811386
2025-12-15 03:50:57,634 - DEBUG - VM ctest-TestvifStats-82028712-77811386 ID is c9b926d3-5b37-4c4c-b650-916e70bffbec
2025-12-15 03:50:57,634 - DEBUG - VM ctest-TestvifStats-82028712-77811386 launched on Node an-jenkins-deploy-platform-ansible-os-4894-2
2025-12-15 03:50:57,728 - DEBUG - Requesting: http://10.0.0.65:8082/virtual-machine/c9b926d3-5b37-4c4c-b650-916e70bffbec
2025-12-15 03:50:57,739 - DEBUG - Requesting: http://10.0.0.65:8082/virtual-machine-interface/5e3added-a8aa-4883-ba8b-9d3325e00760
2025-12-15 03:51:00,951 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1029ms')
2025-12-15 03:51:00,951 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-TestvifStats-82028712-77811386 failed!
2025-12-15 03:51:00,967 - DEBUG - Gateway for vn default-domain:ctest-TestvifStats-82028712:ctest-vn-58164924 is 66.184.125.1 and allocation pool is NOT set
2025-12-15 03:51:05,047 - 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=2 ttl=63 time=43.3 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 1 received, 50% packet loss, time 1019ms\r\nrtt min/avg/max/mdev = 43.261/43.261/43.261/0.000 ms')
2025-12-15 03:51:05,047 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-TestvifStats-82028712-77811386 passed
2025-12-15 03:51:05,122 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-12-15 03:51:05,122 - DEBUG - Waiting to SSH to VM ctest-TestvifStats-82028712-77811386, IP 66.184.125.4, Port 22
2025-12-15 03:51:05,189 - 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': ''}
2025-12-15 03:51:05,276 - DEBUG - VM ctest-TestvifStats-82028712-77811386 is NOT ready for SSH connections, VM status: ACTIVE
2025-12-15 03:51:10,276 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-12-15 03:51:10,277 - DEBUG - Waiting to SSH to VM ctest-TestvifStats-82028712-77811386, IP 66.184.125.4, Port 22
2025-12-15 03:51:10,344 - 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': ''}
2025-12-15 03:51:10,427 - DEBUG - VM ctest-TestvifStats-82028712-77811386 is NOT ready for SSH connections, VM status: ACTIVE
2025-12-15 03:51:15,427 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-12-15 03:51:15,427 - DEBUG - Waiting to SSH to VM ctest-TestvifStats-82028712-77811386, IP 66.184.125.4, Port 22
2025-12-15 03:51:15,493 - 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': ''}
2025-12-15 03:51:15,564 - DEBUG - VM ctest-TestvifStats-82028712-77811386 is NOT ready for SSH connections, VM status: ACTIVE
2025-12-15 03:51:20,565 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-12-15 03:51:20,565 - DEBUG - Waiting to SSH to VM ctest-TestvifStats-82028712-77811386, IP 66.184.125.4, Port 22
2025-12-15 03:51:20,623 - 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': ''}
2025-12-15 03:51:20,706 - DEBUG - VM ctest-TestvifStats-82028712-77811386 is NOT ready for SSH connections, VM status: ACTIVE
2025-12-15 03:51:25,706 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-12-15 03:51:25,706 - DEBUG - Waiting to SSH to VM ctest-TestvifStats-82028712-77811386, IP 66.184.125.4, Port 22
2025-12-15 03:51:25,878 - DEBUG - VM ctest-TestvifStats-82028712-77811386 is ready for SSH connections
2025-12-15 03:51:25,878 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-12-15 03:51:25,878 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 66.184.125.4, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.27, gateway password: c0ntrail123
2025-12-15 03:51:25,878 - DEBUG - ping -s 56 -c 3 -W 1 66.184.125.4
2025-12-15 03:52:07,032 - DEBUG - PING 66.184.125.4 (66.184.125.4) 56(84) bytes of data.
64 bytes from 66.184.125.4: icmp_req=1 ttl=64 time=11.3 ms
64 bytes from 66.184.125.4: icmp_req=2 ttl=64 time=0.972 ms
64 bytes from 66.184.125.4: icmp_req=3 ttl=64 time=0.744 ms
--- 66.184.125.4 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2004ms
rtt min/avg/max/mdev = 0.744/4.362/11.371/4.957 ms
2025-12-15 03:52:07,033 - INFO - Ping to IP 66.184.125.4 from VM ctest-TestvifStats-82028712-80418347 passed
2025-12-15 03:52:07,033 - INFO - 10.0.0.27
2025-12-15 03:52:07,033 - INFO - vif --get 0 | grep "RX packets" | tr -s ' ' | cut -f1-3 -d ' '
2025-12-15 03:52:07,148 - INFO - 14460
2025-12-15 03:52:07,249 - INFO - TX packets:14015
2025-12-15 03:52:07,249 - INFO - 14015
2025-12-15 03:52:07,249 - INFO - vif --get 1 | grep "RX packets" | tr -s ' ' | cut -f1-3 -d ' '
2025-12-15 03:52:07,353 - INFO - 21813
2025-12-15 03:52:07,461 - INFO - TX packets:22822
2025-12-15 03:52:07,461 - INFO - 22822
2025-12-15 03:52:07,461 - INFO - vif --get 2 | grep "RX packets" | tr -s ' ' | cut -f1-3 -d ' '
2025-12-15 03:52:07,574 - INFO - 9832
2025-12-15 03:52:07,691 - INFO - TX packets:8387
2025-12-15 03:52:07,691 - INFO - 8387
2025-12-15 03:52:07,692 - INFO - vif --get 3 | grep "RX packets" | tr -s ' ' | cut -f1-3 -d ' '
2025-12-15 03:52:07,789 - INFO - 286
2025-12-15 03:52:07,897 - INFO - TX packets:341
2025-12-15 03:52:07,897 - INFO - 341
2025-12-15 03:52:07,897 - INFO - vif --get 4 | grep "RX packets" | tr -s ' ' | cut -f1-3 -d ' '
2025-12-15 03:52:08,001 - INFO - 228
2025-12-15 03:52:08,109 - INFO - TX packets:281
2025-12-15 03:52:08,109 - INFO - 281
2025-12-15 03:52:08,109 - INFO - 10.0.0.27
2025-12-15 03:52:08,213 - INFO - Vif stats cleared successfully on all cores for all interfaces
2025-12-15 03:52:08,213 - INFO - 10.0.0.27
2025-12-15 03:52:08,213 - INFO - vif --get 0 | grep "RX packets" | tr -s ' ' | cut -f1-3 -d ' '
2025-12-15 03:52:08,317 - INFO - 0
2025-12-15 03:52:08,424 - INFO - TX packets:0
2025-12-15 03:52:08,424 - INFO - 0
2025-12-15 03:52:08,424 - INFO - vif --get 1 | grep "RX packets" | tr -s ' ' | cut -f1-3 -d ' '
2025-12-15 03:52:08,541 - INFO - 0
2025-12-15 03:52:08,649 - INFO - TX packets:0
2025-12-15 03:52:08,649 - INFO - 0
2025-12-15 03:52:08,649 - INFO - vif --get 2 | grep "RX packets" | tr -s ' ' | cut -f1-3 -d ' '
2025-12-15 03:52:08,753 - INFO - 1
2025-12-15 03:52:08,857 - INFO - TX packets:1
2025-12-15 03:52:08,857 - INFO - 1
2025-12-15 03:52:08,857 - INFO - vif --get 3 | grep "RX packets" | tr -s ' ' | cut -f1-3 -d ' '
2025-12-15 03:52:08,961 - INFO - 1
2025-12-15 03:52:09,069 - INFO - TX packets:1
2025-12-15 03:52:09,069 - INFO - 1
2025-12-15 03:52:09,069 - INFO - vif --get 4 | grep "RX packets" | tr -s ' ' | cut -f1-3 -d ' '
2025-12-15 03:52:09,174 - INFO - 0
2025-12-15 03:52:09,282 - INFO - TX packets:0
2025-12-15 03:52:09,282 - INFO - 0
2025-12-15 03:52:09,282 - INFO - Deleting VM ctest-TestvifStats-82028712-77811386
2025-12-15 03:52:09,354 - INFO - Deleting VM ctest-TestvifStats-82028712-80418347
2025-12-15 03:52:09,425 - INFO - Deleting VN ctest-vn-58164924
2025-12-15 03:52:09,471 - DEBUG - VN db17aba7-0bfe-4fc2-afe7-b0f153f0cac8 still in use: Unable to complete operation on network db17aba7-0bfe-4fc2-afe7-b0f153f0cac8. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-5ec2f086-0b29-41f3-b3ee-af9e614b5dbe']
2025-12-15 03:52:09,471 - WARNING - Deleting VN ctest-vn-58164924 failed..Will retry
2025-12-15 03:52:11,615 - DEBUG - Response for deleting network ()
2025-12-15 03:52:11,615 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/35f09891-47d8-46aa-8427-2e78a2ca3cd2
2025-12-15 03:52:11,621 - DEBUG - Response Code: 404
2025-12-15 03:52:11,621 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-12-15 03:52:11,626 - DEBUG - Requesting: http://10.0.0.23:8082/domain/747a8d7b-37d3-4985-88af-26a6e8bf7d0e
2025-12-15 03:52:11,651 - DEBUG - Requesting: http://10.0.0.23:8082/project/09fde9ae-c4dd-4a20-b1fb-e5549e0bff79
2025-12-15 03:52:11,744 - INFO - Validated that VN ctest-vn-58164924 is not found in API Server
2025-12-15 03:52:11,754 - DEBUG - VN ctest-vn-58164924 is not present in Agent 10.0.0.22
2025-12-15 03:52:11,764 - DEBUG - VN ctest-vn-58164924 is not present in Agent 10.0.0.27
2025-12-15 03:52:11,764 - INFO - Validated that VN ctest-vn-58164924 is not in any agent
2025-12-15 03:52:11,795 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn-58164924 info
2025-12-15 03:52:13,089 - DEBUG - No XMPP flaps were noticed during the test
2025-12-15 03:52:13,089 - INFO - END TEST : test_vif_statistics : PASSED[0:02:32]
2025-12-15 03:52:13,089 - INFO - --------------------------------------------------------------------------------
2025-12-15 03:52:13,830 - INFO - Deleted project: ctest-TestvifStats-82028712, ID : 09fde9ae-c4dd-4a20-b1fb-e5549e0bff79