is in ACTIVE state now
2025-11-17 03:36:35,688 - INFO - VM name : ctest-TestSubInterfaces-67017589-47939043
2025-11-17 03:36:35,780 - DEBUG - VM ctest-TestSubInterfaces-67017589-47939043 ID is d149c2e1-f0cb-4a36-b310-76a8bf670122
2025-11-17 03:36:35,781 - DEBUG - VM ctest-TestSubInterfaces-67017589-47939043 launched on Node an-jenkins-deploy-platform-ansible-os-4736-1
2025-11-17 03:36:35,862 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/d149c2e1-f0cb-4a36-b310-76a8bf670122
2025-11-17 03:36:35,874 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/45a561aa-8365-41bb-9e45-d30b7384affe
2025-11-17 03:36:37,031 - DEBUG - (True, 'PING 169.254.0.5 (169.254.0.5) 56(84) bytes of data.\r\n64 bytes from 169.254.0.5: icmp_seq=1 ttl=63 time=11.1 ms\r\n64 bytes from 169.254.0.5: icmp_seq=2 ttl=63 time=1.95 ms\r\n\r\n--- 169.254.0.5 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 1.951/6.523/11.096/4.572 ms')
2025-11-17 03:36:37,031 - INFO - Ping to Metadata IP 169.254.0.5 of VM ctest-TestSubInterfaces-67017589-47939043 passed
2025-11-17 03:36:37,098 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-11-17 03:36:37,098 - DEBUG - Waiting to SSH to VM ctest-TestSubInterfaces-67017589-47939043, IP 172.35.113.197, Port 22
2025-11-17 03:36:37,176 - DEBUG - Error on ssh to ubuntu@169.254.0.5:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.5/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.5/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.5/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2025-11-17 03:36:37,259 - DEBUG - VM ctest-TestSubInterfaces-67017589-47939043 is NOT ready for SSH connections, VM status: ACTIVE
2025-11-17 03:36:42,260 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-11-17 03:36:42,260 - DEBUG - Waiting to SSH to VM ctest-TestSubInterfaces-67017589-47939043, IP 172.35.113.197, Port 22
2025-11-17 03:36:42,325 - DEBUG - Error on ssh to ubuntu@169.254.0.5:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.5/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.5/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.5/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2025-11-17 03:36:42,429 - DEBUG - VM ctest-TestSubInterfaces-67017589-47939043 is NOT ready for SSH connections, VM status: ACTIVE
2025-11-17 03:36:47,430 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-11-17 03:36:47,430 - DEBUG - Waiting to SSH to VM ctest-TestSubInterfaces-67017589-47939043, IP 172.35.113.197, Port 22
2025-11-17 03:36:47,498 - DEBUG - Error on ssh to ubuntu@169.254.0.5:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.5/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.5/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.5/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2025-11-17 03:36:47,586 - DEBUG - VM ctest-TestSubInterfaces-67017589-47939043 is NOT ready for SSH connections, VM status: ACTIVE
2025-11-17 03:36:52,587 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-11-17 03:36:52,587 - DEBUG - Waiting to SSH to VM ctest-TestSubInterfaces-67017589-47939043, IP 172.35.113.197, Port 22
2025-11-17 03:36:52,654 - DEBUG - Error on ssh to ubuntu@169.254.0.5:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.5/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.5/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.5/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2025-11-17 03:36:52,733 - DEBUG - VM ctest-TestSubInterfaces-67017589-47939043 is NOT ready for SSH connections, VM status: ACTIVE
2025-11-17 03:36:57,734 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-11-17 03:36:57,734 - DEBUG - Waiting to SSH to VM ctest-TestSubInterfaces-67017589-47939043, IP 172.35.113.197, Port 22
2025-11-17 03:36:57,801 - DEBUG - Error on ssh to ubuntu@169.254.0.5:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.5/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.5/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.5/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2025-11-17 03:36:57,912 - DEBUG - VM ctest-TestSubInterfaces-67017589-47939043 is NOT ready for SSH connections, VM status: ACTIVE
2025-11-17 03:37:02,913 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-11-17 03:37:02,913 - DEBUG - Waiting to SSH to VM ctest-TestSubInterfaces-67017589-47939043, IP 172.35.113.197, Port 22
2025-11-17 03:37:02,982 - DEBUG - Error on ssh to ubuntu@169.254.0.5:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.5/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.5/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.5/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2025-11-17 03:37:03,066 - DEBUG - VM ctest-TestSubInterfaces-67017589-47939043 is NOT ready for SSH connections, VM status: ACTIVE
2025-11-17 03:37:08,066 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-11-17 03:37:08,066 - DEBUG - Waiting to SSH to VM ctest-TestSubInterfaces-67017589-47939043, IP 172.35.113.197, Port 22
2025-11-17 03:37:08,237 - DEBUG - VM ctest-TestSubInterfaces-67017589-47939043 is ready for SSH connections
2025-11-17 03:37:08,238 - DEBUG - Running remote_cmd, Cmd : ifconfig -a| grep 100.40.186.131 -A2 -B4| grep -i 'hwaddr\|flags' | awk '{print $1}' | cut -d ':' -f 1, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.50, gateway password: c0ntrail123
2025-11-17 03:37:08,238 - DEBUG - ifconfig -a| grep 100.40.186.131 -A2 -B4| grep -i 'hwaddr\|flags' | awk '{print $1}' | cut -d ':' -f 1
2025-11-17 03:37:58,403 - DEBUG - eth0.100
2025-11-17 03:37:58,403 - INFO - Interface eth0.100 is found on VM a3da4756-5fe3-4364-9315-20629635fb3b
2025-11-17 03:37:58,404 - DEBUG - Running remote_cmd, Cmd : ifconfig -a| grep 169.174.29.131 -A2 -B4| grep -i 'hwaddr\|flags' | awk '{print $1}' | cut -d ':' -f 1, host_string: ubuntu@169.254.0.5, password: ubuntugateway: ubuntu@10.0.0.50, gateway password: c0ntrail123
2025-11-17 03:37:58,404 - DEBUG - ifconfig -a| grep 169.174.29.131 -A2 -B4| grep -i 'hwaddr\|flags' | awk '{print $1}' | cut -d ':' -f 1
2025-11-17 03:38:41,376 - DEBUG - eth0.100
2025-11-17 03:38:41,376 - INFO - Interface eth0.100 is found on VM d149c2e1-f0cb-4a36-b310-76a8bf670122
2025-11-17 03:38:41,376 - DEBUG - Running remote_cmd, Cmd : route add -net 169.174.29.128/26 dev eth0.100, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.50, gateway password: c0ntrail123
2025-11-17 03:38:41,376 - DEBUG - route add -net 169.174.29.128/26 dev eth0.100
2025-11-17 03:38:42,009 - DEBUG - None
2025-11-17 03:38:42,010 - DEBUG - Running remote_cmd, Cmd : route add -net 100.40.186.128/26 dev eth0.100, host_string: ubuntu@169.254.0.5, password: ubuntugateway: ubuntu@10.0.0.50, gateway password: c0ntrail123
2025-11-17 03:38:42,010 - DEBUG - route add -net 100.40.186.128/26 dev eth0.100
2025-11-17 03:38:42,592 - DEBUG - None
2025-11-17 03:38:42,592 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-11-17 03:38:42,592 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 169.174.29.132, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.50, gateway password: c0ntrail123
2025-11-17 03:38:42,592 - DEBUG - ping -s 56 -c 3 -W 1 169.174.29.132
2025-11-17 03:38:45,141 - DEBUG - PING 169.174.29.132 (169.174.29.132) 56(84) bytes of data.
64 bytes from 169.174.29.132: icmp_req=1 ttl=63 time=15.0 ms
64 bytes from 169.174.29.132: icmp_req=2 ttl=63 time=1.38 ms
64 bytes from 169.174.29.132: icmp_req=3 ttl=63 time=1.11 ms
--- 169.174.29.132 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2004ms
rtt min/avg/max/mdev = 1.115/5.837/15.011/6.488 ms
2025-11-17 03:38:45,141 - INFO - Ping to IP 169.174.29.132 from VM ctest-TestSubInterfaces-67017589-28907114 passed
2025-11-17 03:38:45,141 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 169.174.29.131, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.50, gateway password: c0ntrail123
2025-11-17 03:38:45,141 - DEBUG - ping -s 56 -c 3 -W 1 169.174.29.131
2025-11-17 03:38:47,722 - DEBUG - PING 169.174.29.131 (169.174.29.131) 56(84) bytes of data.
64 bytes from 169.174.29.131: icmp_req=1 ttl=63 time=8.85 ms
64 bytes from 169.174.29.131: icmp_req=2 ttl=63 time=0.940 ms
64 bytes from 169.174.29.131: icmp_req=3 ttl=63 time=0.788 ms
--- 169.174.29.131 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2003ms
rtt min/avg/max/mdev = 0.788/3.526/8.850/3.765 ms
2025-11-17 03:38:47,722 - INFO - Ping to IP 169.174.29.131 from VM ctest-TestSubInterfaces-67017589-28907114 passed
2025-11-17 03:38:47,722 - INFO - Deleting interface with subnet_id bf2af70c-6917-457b-ac9e-a27c359c1bbc, port_id None from router 66531f21-745b-4722-b112-414b134945dc
2025-11-17 03:38:47,976 - INFO - Deleting interface with subnet_id 59743645-d870-4ca6-b3c3-065acb5564c9, port_id None from router 66531f21-745b-4722-b112-414b134945dc
2025-11-17 03:38:48,289 - INFO - Detaching port 45a561aa-8365-41bb-9e45-d30b7384affe from VM ctest-TestSubInterfaces-67017589-47939043
2025-11-17 03:38:48,335 - INFO - Deleting VM ctest-TestSubInterfaces-67017589-47939043
2025-11-17 03:38:48,482 - INFO - Deleting VM ctest-TestSubInterfaces-67017589-26682003
2025-11-17 03:38:48,558 - INFO - Detaching port 7c08cb18-33d3-4d87-9a95-3da157431a16 from VM ctest-TestSubInterfaces-67017589-28907114
2025-11-17 03:38:48,592 - INFO - Deleting VM ctest-TestSubInterfaces-67017589-28907114
2025-11-17 03:38:49,968 - DEBUG - No XMPP flaps were noticed during the test
2025-11-17 03:38:49,968 - INFO - END TEST : test_vlan_interface_2 : PASSED[0:03:29]
2025-11-17 03:38:49,968 - INFO - --------------------------------------------------------------------------------
2025-11-17 03:38:50,082 - INFO - Deleted port 0c2557a6-aaa7-41d7-a00d-254ca07294f8
2025-11-17 03:38:50,326 - DEBUG - Response for delete_port : ()
2025-11-17 03:38:50,326 - INFO - Deleted port 45a561aa-8365-41bb-9e45-d30b7384affe
2025-11-17 03:38:50,449 - INFO - Deleted port f35b70ca-2961-4916-8432-6e1d0d189b33
2025-11-17 03:38:50,608 - DEBUG - Response for delete_port : ()
2025-11-17 03:38:50,608 - INFO - Deleted port 2e85d653-bd9a-4be7-a460-5db77fddd3c1
2025-11-17 03:38:50,736 - INFO - Deleted port 7cb6b493-9e48-4b62-8d51-2017129e4638
2025-11-17 03:38:51,006 - DEBUG - Response for delete_port : ()
2025-11-17 03:38:51,006 - INFO - Deleted port 7c08cb18-33d3-4d87-9a95-3da157431a16
2025-11-17 03:38:51,006 - INFO - Deleting VN ctest-vn-72176624
2025-11-17 03:38:51,232 - DEBUG - Response for deleting network ()
2025-11-17 03:38:51,232 - INFO - Deleting VN ctest-vn-34310890
2025-11-17 03:38:51,403 - DEBUG - Response for deleting network ()
2025-11-17 03:38:51,403 - INFO - Deleting VN ctest-vn-63468948
2025-11-17 03:38:51,576 - DEBUG - Response for deleting network ()
2025-11-17 03:38:52,493 - INFO - Deleted project: ctest-TestSubInterfaces-67017589, ID : 3f18430d-85f9-4d51-af82-ad664c1280e5