is in ACTIVE state now
2026-03-16 03:31:05,533 - INFO - VM name : ctest-TestSubInterfaces-80632185-47234388
2026-03-16 03:31:05,620 - DEBUG - VM ctest-TestSubInterfaces-80632185-47234388 ID is ee9d970b-9c6b-4d5a-8a02-639a89867517
2026-03-16 03:31:05,620 - DEBUG - VM ctest-TestSubInterfaces-80632185-47234388 launched on Node an-jenkins-deploy-platform-ansible-os-5469-1
2026-03-16 03:31:05,717 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/ee9d970b-9c6b-4d5a-8a02-639a89867517
2026-03-16 03:31:05,729 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/d1f1e80b-71ac-4460-b8a7-34fb56ea4874
2026-03-16 03:31:06,917 - 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=5.27 ms\r\n64 bytes from 169.254.0.5: icmp_seq=2 ttl=63 time=0.710 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 = 0.710/2.992/5.274/2.282 ms')
2026-03-16 03:31:06,918 - INFO - Ping to Metadata IP 169.254.0.5 of VM ctest-TestSubInterfaces-80632185-47234388 passed
2026-03-16 03:31:06,993 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-03-16 03:31:06,993 - DEBUG - Waiting to SSH to VM ctest-TestSubInterfaces-80632185-47234388, IP 87.234.137.69, Port 22
2026-03-16 03:31:07,064 - 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': ''}
2026-03-16 03:31:07,161 - DEBUG - VM ctest-TestSubInterfaces-80632185-47234388 is NOT ready for SSH connections, VM status: ACTIVE
2026-03-16 03:31:12,162 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-03-16 03:31:12,162 - DEBUG - Waiting to SSH to VM ctest-TestSubInterfaces-80632185-47234388, IP 87.234.137.69, Port 22
2026-03-16 03:31:12,231 - 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': ''}
2026-03-16 03:31:12,371 - DEBUG - VM ctest-TestSubInterfaces-80632185-47234388 is NOT ready for SSH connections, VM status: ACTIVE
2026-03-16 03:31:17,372 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-03-16 03:31:17,372 - DEBUG - Waiting to SSH to VM ctest-TestSubInterfaces-80632185-47234388, IP 87.234.137.69, Port 22
2026-03-16 03:31:17,529 - DEBUG - VM ctest-TestSubInterfaces-80632185-47234388 is ready for SSH connections
2026-03-16 03:31:17,529 - DEBUG - Running remote_cmd, Cmd : ifconfig -a| grep 51.5.226.195 -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.41, gateway password: c0ntrail123
2026-03-16 03:31:17,529 - DEBUG - ifconfig -a| grep 51.5.226.195 -A2 -B4| grep -i 'hwaddr\|flags' | awk '{print $1}' | cut -d ':' -f 1
2026-03-16 03:32:26,099 - DEBUG - eth0.100
2026-03-16 03:32:26,099 - INFO - Interface eth0.100 is found on VM 7aab8cc7-1abe-4a08-959c-24e3bf61427c
2026-03-16 03:32:26,099 - DEBUG - Running remote_cmd, Cmd : ifconfig -a| grep 188.245.35.3 -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.41, gateway password: c0ntrail123
2026-03-16 03:32:26,100 - DEBUG - ifconfig -a| grep 188.245.35.3 -A2 -B4| grep -i 'hwaddr\|flags' | awk '{print $1}' | cut -d ':' -f 1
2026-03-16 03:33:06,077 - DEBUG - eth0.100
2026-03-16 03:33:06,077 - INFO - Interface eth0.100 is found on VM ee9d970b-9c6b-4d5a-8a02-639a89867517
2026-03-16 03:33:06,077 - DEBUG - Running remote_cmd, Cmd : route add -net 188.245.35.0/26 dev eth0.100, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.41, gateway password: c0ntrail123
2026-03-16 03:33:06,078 - DEBUG - route add -net 188.245.35.0/26 dev eth0.100
2026-03-16 03:33:06,603 - DEBUG - None
2026-03-16 03:33:06,603 - DEBUG - Running remote_cmd, Cmd : route add -net 51.5.226.192/26 dev eth0.100, host_string: ubuntu@169.254.0.5, password: ubuntugateway: ubuntu@10.0.0.41, gateway password: c0ntrail123
2026-03-16 03:33:06,603 - DEBUG - route add -net 51.5.226.192/26 dev eth0.100
2026-03-16 03:33:07,189 - DEBUG - None
2026-03-16 03:33:07,189 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-03-16 03:33:07,189 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 188.245.35.4, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.41, gateway password: c0ntrail123
2026-03-16 03:33:07,189 - DEBUG - ping -s 56 -c 3 -W 1 188.245.35.4
2026-03-16 03:33:09,784 - DEBUG - PING 188.245.35.4 (188.245.35.4) 56(84) bytes of data.
64 bytes from 188.245.35.4: icmp_req=1 ttl=63 time=12.2 ms
64 bytes from 188.245.35.4: icmp_req=2 ttl=63 time=1.26 ms
64 bytes from 188.245.35.4: icmp_req=3 ttl=63 time=1.04 ms
--- 188.245.35.4 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2004ms
rtt min/avg/max/mdev = 1.044/4.856/12.260/5.236 ms
2026-03-16 03:33:09,784 - INFO - Ping to IP 188.245.35.4 from VM ctest-TestSubInterfaces-80632185-00738284 passed
2026-03-16 03:33:09,785 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 188.245.35.3, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.41, gateway password: c0ntrail123
2026-03-16 03:33:09,785 - DEBUG - ping -s 56 -c 3 -W 1 188.245.35.3
2026-03-16 03:33:12,296 - DEBUG - PING 188.245.35.3 (188.245.35.3) 56(84) bytes of data.
64 bytes from 188.245.35.3: icmp_req=1 ttl=63 time=10.7 ms
64 bytes from 188.245.35.3: icmp_req=2 ttl=63 time=1.02 ms
64 bytes from 188.245.35.3: icmp_req=3 ttl=63 time=0.930 ms
--- 188.245.35.3 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2004ms
rtt min/avg/max/mdev = 0.930/4.223/10.711/4.587 ms
2026-03-16 03:33:12,297 - INFO - Ping to IP 188.245.35.3 from VM ctest-TestSubInterfaces-80632185-00738284 passed
2026-03-16 03:33:12,297 - INFO - Deleting interface with subnet_id 3e2c5de2-de41-4e53-a0fb-bb9b997f6044, port_id None from router 86388db3-a9e9-4200-9b74-deb121b72401
2026-03-16 03:33:12,577 - INFO - Deleting interface with subnet_id ee4b1644-ceda-4882-9ecb-fff29dd108c1, port_id None from router 86388db3-a9e9-4200-9b74-deb121b72401
2026-03-16 03:33:12,899 - INFO - Detaching port d1f1e80b-71ac-4460-b8a7-34fb56ea4874 from VM ctest-TestSubInterfaces-80632185-47234388
2026-03-16 03:33:12,935 - INFO - Deleting VM ctest-TestSubInterfaces-80632185-47234388
2026-03-16 03:33:13,046 - INFO - Deleting VM ctest-TestSubInterfaces-80632185-14368326
2026-03-16 03:33:13,124 - INFO - Detaching port 897ffde2-00f3-4672-9094-31826926076d from VM ctest-TestSubInterfaces-80632185-00738284
2026-03-16 03:33:13,158 - INFO - Deleting VM ctest-TestSubInterfaces-80632185-00738284
2026-03-16 03:33:14,551 - DEBUG - No XMPP flaps were noticed during the test
2026-03-16 03:33:14,551 - INFO - END TEST : test_vlan_interface_2 : PASSED[0:03:24]
2026-03-16 03:33:14,551 - INFO - --------------------------------------------------------------------------------
2026-03-16 03:33:14,721 - INFO - Deleted port c6739877-0fc2-465a-b15e-45d376b0917d
2026-03-16 03:33:15,007 - DEBUG - Response for delete_port : ()
2026-03-16 03:33:15,007 - INFO - Deleted port d1f1e80b-71ac-4460-b8a7-34fb56ea4874
2026-03-16 03:33:15,150 - INFO - Deleted port 096cb6e2-fbb3-4d78-8000-dee51b227600
2026-03-16 03:33:15,363 - DEBUG - Response for delete_port : ()
2026-03-16 03:33:15,363 - INFO - Deleted port 07a475fe-d3da-4673-baa8-97db24217c68
2026-03-16 03:33:15,508 - INFO - Deleted port 27f18857-e1ff-4711-8a63-018deeedea5b
2026-03-16 03:33:15,768 - DEBUG - Response for delete_port : ()
2026-03-16 03:33:15,768 - INFO - Deleted port 897ffde2-00f3-4672-9094-31826926076d
2026-03-16 03:33:15,768 - INFO - Deleting VN ctest-vn-50406089
2026-03-16 03:33:15,962 - DEBUG - Response for deleting network ()
2026-03-16 03:33:15,963 - INFO - Deleting VN ctest-vn-57006495
2026-03-16 03:33:16,163 - DEBUG - Response for deleting network ()
2026-03-16 03:33:16,163 - INFO - Deleting VN ctest-vn-28061211
2026-03-16 03:33:16,373 - DEBUG - Response for deleting network ()
2026-03-16 03:33:17,196 - INFO - Deleted project: ctest-TestSubInterfaces-80632185, ID : 8d160375-cf48-41c0-b388-62f13531279a