2026-04-27 04:10:04,826 - INFO - Domain Default found not creating 2026-04-27 04:10:05,082 - INFO - Project ctest-TestSubInterfaces-93242471 not found, creating it 2026-04-27 04:10:05,891 - INFO - Created Project:ctest-TestSubInterfaces-93242471, ID : a712dcc1-33b9-4079-b815-1cb26bd02aac 2026-04-27 04:10:08,826 - DEBUG - Response for create_network : {'network': {'id': '22874ff8-faf9-4cf9-9984-5204f01e5793', 'name': 'ctest-vn-07478506', 'tenant_id': 'a712dcc133b94079b8151cb26bd02aac', 'project_id': 'a712dcc133b94079b8151cb26bd02aac', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSubInterfaces-93242471', 'ctest-vn-07478506'], 'port_security_enabled': True, 'description': ''}} 2026-04-27 04:10:09,204 - DEBUG - Response for create_subnet : {'subnet': {'id': '6745d105-e45e-426b-968d-85c945715d59', 'name': '', 'tenant_id': 'a712dcc133b94079b8151cb26bd02aac', 'network_id': '22874ff8-faf9-4cf9-9984-5204f01e5793', 'ip_version': 4, 'cidr': '168.166.76.128/26', 'allocation_pools': [{'start': '168.166.76.130', 'end': '168.166.76.190'}], 'gateway_ip': '168.166.76.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '168.166.76.130', 'tags': [], 'project_id': 'a712dcc133b94079b8151cb26bd02aac'}} 2026-04-27 04:10:09,238 - INFO - Created VN ctest-vn-07478506 2026-04-27 04:10:09,258 - DEBUG - VN ctest-vn-07478506 UUID is 22874ff8-faf9-4cf9-9984-5204f01e5793 2026-04-27 04:10:09,830 - DEBUG - Response for create_network : {'network': {'id': '162ae831-067c-4ad1-94d2-929395762686', 'name': 'ctest-vn-82076117', 'tenant_id': 'a712dcc133b94079b8151cb26bd02aac', 'project_id': 'a712dcc133b94079b8151cb26bd02aac', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSubInterfaces-93242471', 'ctest-vn-82076117'], 'port_security_enabled': True, 'description': ''}} 2026-04-27 04:10:10,171 - DEBUG - Response for create_subnet : {'subnet': {'id': '58c37094-bce0-4492-b006-22fe1a17cc20', 'name': '', 'tenant_id': 'a712dcc133b94079b8151cb26bd02aac', 'network_id': '162ae831-067c-4ad1-94d2-929395762686', 'ip_version': 4, 'cidr': '102.13.185.128/26', 'allocation_pools': [{'start': '102.13.185.130', 'end': '102.13.185.190'}], 'gateway_ip': '102.13.185.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '102.13.185.130', 'tags': [], 'project_id': 'a712dcc133b94079b8151cb26bd02aac'}} 2026-04-27 04:10:10,199 - INFO - Created VN ctest-vn-82076117 2026-04-27 04:10:10,216 - DEBUG - VN ctest-vn-82076117 UUID is 162ae831-067c-4ad1-94d2-929395762686 2026-04-27 04:10:10,751 - DEBUG - Response for create_network : {'network': {'id': 'd7ac498f-f5f0-48e4-93ec-c46be7c70a14', 'name': 'ctest-vn-25849729', 'tenant_id': 'a712dcc133b94079b8151cb26bd02aac', 'project_id': 'a712dcc133b94079b8151cb26bd02aac', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSubInterfaces-93242471', 'ctest-vn-25849729'], 'port_security_enabled': True, 'description': ''}} 2026-04-27 04:10:11,067 - DEBUG - Response for create_subnet : {'subnet': {'id': 'd11b314f-42f4-4d26-930f-d7c150424fa4', 'name': '', 'tenant_id': 'a712dcc133b94079b8151cb26bd02aac', 'network_id': 'd7ac498f-f5f0-48e4-93ec-c46be7c70a14', 'ip_version': 4, 'cidr': '137.125.169.128/26', 'allocation_pools': [{'start': '137.125.169.130', 'end': '137.125.169.190'}], 'gateway_ip': '137.125.169.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '137.125.169.130', 'tags': [], 'project_id': 'a712dcc133b94079b8151cb26bd02aac'}} 2026-04-27 04:10:11,106 - INFO - Created VN ctest-vn-25849729 2026-04-27 04:10:11,127 - DEBUG - VN ctest-vn-25849729 UUID is d7ac498f-f5f0-48e4-93ec-c46be7c70a14 2026-04-27 04:10:11,236 - DEBUG - Read virtual machine interface {'fq_name': ['default-domain', 'ctest-TestSubInterfaces-93242471', 'ctest-vmi-47338191']} 2026-04-27 04:10:11,700 - DEBUG - Response for create_port : {'port': {'name': 'ctest-vmi-47338191', 'id': '002f7b98-2f4b-4eb1-aa09-1a1298aafa0f', 'tenant_id': 'a712dcc133b94079b8151cb26bd02aac', 'network_id': '162ae831-067c-4ad1-94d2-929395762686', 'mac_address': '02:00:2f:7b:98:2f', 'binding:vnic_type': 'normal', 'binding:vif_details': {'port_filter': True}, 'binding:vif_type': 'unbound', 'binding:host_id': None, 'allowed_address_pairs': [], 'fixed_ips': [{'ip_address': '102.13.185.131', 'subnet_id': '58c37094-bce0-4492-b006-22fe1a17cc20'}], 'security_groups': ['014d0c80-3169-4ebb-85a0-d9cfd6856d01'], 'admin_state_up': True, 'device_id': '', 'device_owner': '', 'status': 'DOWN', 'port_security_enabled': True, 'description': '', 'project_id': 'a712dcc133b94079b8151cb26bd02aac'}} 2026-04-27 04:10:11,700 - DEBUG - Created port 002f7b98-2f4b-4eb1-aa09-1a1298aafa0f 2026-04-27 04:10:11,784 - DEBUG - Read virtual machine interface {'fq_name': ['default-domain', 'ctest-TestSubInterfaces-93242471', 'ctest-vmi-27182152']} 2026-04-27 04:10:12,439 - DEBUG - Created port 3d0e90b8-59bf-4e54-a59f-210b3cb00119 2026-04-27 04:10:12,575 - DEBUG - Read virtual machine interface {'fq_name': ['default-domain', 'ctest-TestSubInterfaces-93242471', 'ctest-vmi-61174970']} 2026-04-27 04:10:13,032 - DEBUG - Response for create_port : {'port': {'name': 'ctest-vmi-61174970', 'id': '13bfa5a1-1b6b-42d4-9651-89094ec3359e', 'tenant_id': 'a712dcc133b94079b8151cb26bd02aac', 'network_id': '162ae831-067c-4ad1-94d2-929395762686', 'mac_address': '02:13:bf:a5:a1:1b', 'binding:vnic_type': 'normal', 'binding:vif_details': {'port_filter': True}, 'binding:vif_type': 'unbound', 'binding:host_id': None, 'allowed_address_pairs': [], 'fixed_ips': [{'ip_address': '102.13.185.132', 'subnet_id': '58c37094-bce0-4492-b006-22fe1a17cc20'}], 'security_groups': ['014d0c80-3169-4ebb-85a0-d9cfd6856d01'], 'admin_state_up': True, 'device_id': '', 'device_owner': '', 'status': 'DOWN', 'port_security_enabled': True, 'description': '', 'project_id': 'a712dcc133b94079b8151cb26bd02aac'}} 2026-04-27 04:10:13,032 - DEBUG - Created port 13bfa5a1-1b6b-42d4-9651-89094ec3359e 2026-04-27 04:10:13,122 - DEBUG - Read virtual machine interface {'fq_name': ['default-domain', 'ctest-TestSubInterfaces-93242471', 'ctest-vmi-28538068']} 2026-04-27 04:10:13,779 - DEBUG - Created port 24762817-009b-4442-98ae-1258e6fcebc7 2026-04-27 04:10:13,916 - DEBUG - Read virtual machine interface {'fq_name': ['default-domain', 'ctest-TestSubInterfaces-93242471', 'ctest-vmi-89634720']} 2026-04-27 04:10:14,457 - DEBUG - Response for create_port : {'port': {'name': 'ctest-vmi-89634720', 'id': 'c0fb2abc-6212-4802-a46b-bde701edb35a', 'tenant_id': 'a712dcc133b94079b8151cb26bd02aac', 'network_id': '162ae831-067c-4ad1-94d2-929395762686', 'mac_address': '02:c0:fb:2a:bc:62', 'binding:vnic_type': 'normal', 'binding:vif_details': {'port_filter': True}, 'binding:vif_type': 'unbound', 'binding:host_id': None, 'allowed_address_pairs': [], 'fixed_ips': [{'ip_address': '102.13.185.133', 'subnet_id': '58c37094-bce0-4492-b006-22fe1a17cc20'}], 'security_groups': ['014d0c80-3169-4ebb-85a0-d9cfd6856d01'], 'admin_state_up': True, 'device_id': '', 'device_owner': '', 'status': 'DOWN', 'port_security_enabled': True, 'description': '', 'project_id': 'a712dcc133b94079b8151cb26bd02aac'}} 2026-04-27 04:10:14,457 - DEBUG - Created port c0fb2abc-6212-4802-a46b-bde701edb35a 2026-04-27 04:10:14,552 - DEBUG - Read virtual machine interface {'fq_name': ['default-domain', 'ctest-TestSubInterfaces-93242471', 'ctest-vmi-56492808']} 2026-04-27 04:10:15,216 - DEBUG - Created port 3545534d-e3d7-4392-beb4-51c719a194de 2026-04-27 04:10:15,347 - INFO - ================================================================================ 2026-04-27 04:10:15,347 - INFO - STARTING TEST : test_vlan_interface_2 2026-04-27 04:10:15,348 - INFO - TEST DESCRIPTION : Test ping/hping between tagged-untagged vmis across VNs 2026-04-27 04:10:16,703 - DEBUG - Nothing to compare xmpp stats {'10.0.0.33': {'10.20.0.193': '0', '10.20.0.18': '0'}, '10.0.0.65': {'10.20.0.25': '0', '10.20.0.18': '0'}} with 2026-04-27 04:10:16,703 - INFO - Initial checks done. Running the testcase now 2026-04-27 04:10:16,703 - INFO - 2026-04-27 04:10:16,965 - DEBUG - Services list from nova: [, , , ] 2026-04-27 04:10:17,630 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5690-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5690-1) 2026-04-27 04:10:19,328 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5690-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5690-2) 2026-04-27 04:10:20,516 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5690-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5690-1) 2026-04-27 04:10:20,706 - INFO - Adding interface with subnet_id 6745d105-e45e-426b-968d-85c945715d59, port_id None to router 673f10e1-30c5-4b01-93c5-f884893bdb4c 2026-04-27 04:10:21,362 - INFO - Adding interface with subnet_id d11b314f-42f4-4d26-930f-d7c150424fa4, port_id None to router 673f10e1-30c5-4b01-93c5-f884893bdb4c 2026-04-27 04:10:22,110 - INFO - Waiting for VM ctest-TestSubInterfaces-93242471-98701118 to be up.. 2026-04-27 04:10:22,262 - DEBUG - VM is in ACTIVE state now 2026-04-27 04:10:22,262 - INFO - VM name : ctest-TestSubInterfaces-93242471-98701118 2026-04-27 04:10:22,401 - DEBUG - VM ctest-TestSubInterfaces-93242471-98701118 ID is ee7d8a4c-be4e-485e-bb6f-6269e26fc8ba 2026-04-27 04:10:22,444 - DEBUG - VM ctest-TestSubInterfaces-93242471-98701118 launched on Node an-jenkins-deploy-platform-ansible-os-5690-1 2026-04-27 04:10:22,574 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/ee7d8a4c-be4e-485e-bb6f-6269e26fc8ba 2026-04-27 04:10:22,908 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/ee7d8a4c-be4e-485e-bb6f-6269e26fc8ba 2026-04-27 04:10:22,957 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/002f7b98-2f4b-4eb1-aa09-1a1298aafa0f 2026-04-27 04:10:26,205 - DEBUG - (True, 'PING 169.254.0.5 (169.254.0.5) 56(84) bytes of data.\r\n\r\n--- 169.254.0.5 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1019ms') 2026-04-27 04:10:26,205 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-TestSubInterfaces-93242471-98701118 failed! 2026-04-27 04:10:26,231 - DEBUG - Gateway for vn default-domain:ctest-TestSubInterfaces-93242471:ctest-vn-82076117 is 102.13.185.129 and allocation pool is NOT set 2026-04-27 04:10:30,302 - DEBUG - (True, 'PING 169.254.0.5 (169.254.0.5) 56(84) bytes of data.\r\n\r\n--- 169.254.0.5 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1001ms') 2026-04-27 04:10:30,302 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-TestSubInterfaces-93242471-98701118 failed! 2026-04-27 04:10:30,324 - DEBUG - Gateway for vn default-domain:ctest-TestSubInterfaces-93242471:ctest-vn-82076117 is 102.13.185.129 and allocation pool is NOT set 2026-04-27 04:10:34,430 - DEBUG - (True, 'PING 169.254.0.5 (169.254.0.5) 56(84) bytes of data.\r\n\r\n--- 169.254.0.5 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1026ms') 2026-04-27 04:10:34,430 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-TestSubInterfaces-93242471-98701118 failed! 2026-04-27 04:10:34,457 - DEBUG - Gateway for vn default-domain:ctest-TestSubInterfaces-93242471:ctest-vn-82076117 is 102.13.185.129 and allocation pool is NOT set 2026-04-27 04:10:38,531 - DEBUG - (True, 'PING 169.254.0.5 (169.254.0.5) 56(84) bytes of data.\r\n\r\n--- 169.254.0.5 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1006ms') 2026-04-27 04:10:38,532 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-TestSubInterfaces-93242471-98701118 failed! 2026-04-27 04:10:38,555 - DEBUG - Gateway for vn default-domain:ctest-TestSubInterfaces-93242471:ctest-vn-82076117 is 102.13.185.129 and allocation pool is NOT set 2026-04-27 04:10:42,622 - DEBUG - (True, 'PING 169.254.0.5 (169.254.0.5) 56(84) bytes of data.\r\n\r\n--- 169.254.0.5 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1003ms') 2026-04-27 04:10:42,622 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-TestSubInterfaces-93242471-98701118 failed! 2026-04-27 04:10:42,647 - DEBUG - Gateway for vn default-domain:ctest-TestSubInterfaces-93242471:ctest-vn-82076117 is 102.13.185.129 and allocation pool is NOT set 2026-04-27 04:10:46,717 - DEBUG - (True, 'PING 169.254.0.5 (169.254.0.5) 56(84) bytes of data.\r\n\r\n--- 169.254.0.5 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1001ms') 2026-04-27 04:10:46,717 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-TestSubInterfaces-93242471-98701118 failed! 2026-04-27 04:10:46,738 - DEBUG - Gateway for vn default-domain:ctest-TestSubInterfaces-93242471:ctest-vn-82076117 is 102.13.185.129 and allocation pool is NOT set 2026-04-27 04:10:50,820 - DEBUG - (True, 'PING 169.254.0.5 (169.254.0.5) 56(84) bytes of data.\r\n\r\n--- 169.254.0.5 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1010ms') 2026-04-27 04:10:50,820 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-TestSubInterfaces-93242471-98701118 failed! 2026-04-27 04:10:50,844 - DEBUG - Gateway for vn default-domain:ctest-TestSubInterfaces-93242471:ctest-vn-82076117 is 102.13.185.129 and allocation pool is NOT set 2026-04-27 04:10:54,941 - DEBUG - (True, 'PING 169.254.0.5 (169.254.0.5) 56(84) bytes of data.\r\n\r\n--- 169.254.0.5 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1029ms') 2026-04-27 04:10:54,941 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-TestSubInterfaces-93242471-98701118 failed! 2026-04-27 04:10:54,965 - DEBUG - Gateway for vn default-domain:ctest-TestSubInterfaces-93242471:ctest-vn-82076117 is 102.13.185.129 and allocation pool is NOT set 2026-04-27 04:10:59,030 - DEBUG - (True, 'PING 169.254.0.5 (169.254.0.5) 56(84) bytes of data.\r\n\r\n--- 169.254.0.5 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1011ms') 2026-04-27 04:10:59,030 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-TestSubInterfaces-93242471-98701118 failed! 2026-04-27 04:10:59,051 - DEBUG - Gateway for vn default-domain:ctest-TestSubInterfaces-93242471:ctest-vn-82076117 is 102.13.185.129 and allocation pool is NOT set 2026-04-27 04:11:03,135 - DEBUG - (True, 'PING 169.254.0.5 (169.254.0.5) 56(84) bytes of data.\r\n\r\n--- 169.254.0.5 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1018ms') 2026-04-27 04:11:03,136 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-TestSubInterfaces-93242471-98701118 failed! 2026-04-27 04:11:03,168 - DEBUG - Gateway for vn default-domain:ctest-TestSubInterfaces-93242471:ctest-vn-82076117 is 102.13.185.129 and allocation pool is NOT set 2026-04-27 04:11:07,264 - DEBUG - (True, 'PING 169.254.0.5 (169.254.0.5) 56(84) bytes of data.\r\n\r\n--- 169.254.0.5 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1030ms') 2026-04-27 04:11:07,264 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-TestSubInterfaces-93242471-98701118 failed! 2026-04-27 04:11:07,284 - DEBUG - Gateway for vn default-domain:ctest-TestSubInterfaces-93242471:ctest-vn-82076117 is 102.13.185.129 and allocation pool is NOT set 2026-04-27 04:11:11,351 - DEBUG - (True, 'PING 169.254.0.5 (169.254.0.5) 56(84) bytes of data.\r\n\r\n--- 169.254.0.5 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1010ms') 2026-04-27 04:11:11,351 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-TestSubInterfaces-93242471-98701118 failed! 2026-04-27 04:11:11,372 - DEBUG - Gateway for vn default-domain:ctest-TestSubInterfaces-93242471:ctest-vn-82076117 is 102.13.185.129 and allocation pool is NOT set 2026-04-27 04:11:13,440 - 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=7.10 ms\r\n64 bytes from 169.254.0.5: icmp_seq=2 ttl=63 time=2.64 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 = 2.640/4.868/7.097/2.228 ms') 2026-04-27 04:11:13,441 - INFO - Ping to Metadata IP 169.254.0.5 of VM ctest-TestSubInterfaces-93242471-98701118 passed 2026-04-27 04:11:13,532 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-04-27 04:11:13,532 - DEBUG - Waiting to SSH to VM ctest-TestSubInterfaces-93242471-98701118, IP 102.13.185.131, Port 22 2026-04-27 04:11:13,601 - 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-04-27 04:11:13,731 - DEBUG - VM ctest-TestSubInterfaces-93242471-98701118 is NOT ready for SSH connections, VM status: ACTIVE 2026-04-27 04:11:18,732 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-04-27 04:11:18,732 - DEBUG - Waiting to SSH to VM ctest-TestSubInterfaces-93242471-98701118, IP 102.13.185.131, Port 22 2026-04-27 04:11:18,804 - 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-04-27 04:11:18,924 - DEBUG - VM ctest-TestSubInterfaces-93242471-98701118 is NOT ready for SSH connections, VM status: ACTIVE 2026-04-27 04:11:23,925 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-04-27 04:11:23,926 - DEBUG - Waiting to SSH to VM ctest-TestSubInterfaces-93242471-98701118, IP 102.13.185.131, Port 22 2026-04-27 04:11:23,996 - 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-04-27 04:11:24,120 - DEBUG - VM ctest-TestSubInterfaces-93242471-98701118 is NOT ready for SSH connections, VM status: ACTIVE 2026-04-27 04:11:29,120 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-04-27 04:11:29,120 - DEBUG - Waiting to SSH to VM ctest-TestSubInterfaces-93242471-98701118, IP 102.13.185.131, Port 22 2026-04-27 04:11:29,193 - 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-04-27 04:11:29,308 - DEBUG - VM ctest-TestSubInterfaces-93242471-98701118 is NOT ready for SSH connections, VM status: ACTIVE 2026-04-27 04:11:34,310 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-04-27 04:11:34,311 - DEBUG - Waiting to SSH to VM ctest-TestSubInterfaces-93242471-98701118, IP 102.13.185.131, Port 22 2026-04-27 04:11:34,380 - 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-04-27 04:11:34,504 - DEBUG - VM ctest-TestSubInterfaces-93242471-98701118 is NOT ready for SSH connections, VM status: ACTIVE 2026-04-27 04:11:39,505 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-04-27 04:11:39,505 - DEBUG - Waiting to SSH to VM ctest-TestSubInterfaces-93242471-98701118, IP 102.13.185.131, Port 22 2026-04-27 04:11:39,684 - DEBUG - VM ctest-TestSubInterfaces-93242471-98701118 is ready for SSH connections 2026-04-27 04:11:39,684 - INFO - Waiting for VM ctest-TestSubInterfaces-93242471-77929772 to be up.. 2026-04-27 04:11:39,811 - DEBUG - VM is in ACTIVE state now 2026-04-27 04:11:39,811 - INFO - VM name : ctest-TestSubInterfaces-93242471-77929772 2026-04-27 04:11:39,930 - DEBUG - VM ctest-TestSubInterfaces-93242471-77929772 ID is 89da8547-af6f-41bc-beb9-44195b64708f 2026-04-27 04:11:39,931 - DEBUG - VM ctest-TestSubInterfaces-93242471-77929772 launched on Node an-jenkins-deploy-platform-ansible-os-5690-2 2026-04-27 04:11:40,084 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/89da8547-af6f-41bc-beb9-44195b64708f 2026-04-27 04:11:40,102 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/a0d4f0d2-09fc-4059-a266-713e62aaaba1 2026-04-27 04:11:41,313 - 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=8.38 ms\r\n64 bytes from 169.254.0.5: icmp_seq=2 ttl=63 time=3.90 ms\r\n\r\n--- 169.254.0.5 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 3.897/6.136/8.375/2.239 ms') 2026-04-27 04:11:41,313 - INFO - Ping to Metadata IP 169.254.0.5 of VM ctest-TestSubInterfaces-93242471-77929772 passed 2026-04-27 04:11:41,403 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-04-27 04:11:41,403 - DEBUG - Waiting to SSH to VM ctest-TestSubInterfaces-93242471-77929772, IP 137.125.169.132, Port 22 2026-04-27 04:11:41,575 - DEBUG - VM ctest-TestSubInterfaces-93242471-77929772 is ready for SSH connections 2026-04-27 04:11:41,575 - INFO - Waiting for VM ctest-TestSubInterfaces-93242471-53258068 to be up.. 2026-04-27 04:11:41,709 - DEBUG - VM is in ACTIVE state now 2026-04-27 04:11:41,709 - INFO - VM name : ctest-TestSubInterfaces-93242471-53258068 2026-04-27 04:11:41,830 - DEBUG - VM ctest-TestSubInterfaces-93242471-53258068 ID is c8d077d6-6d16-4c24-b3b8-cc8b5370d48e 2026-04-27 04:11:41,830 - DEBUG - VM ctest-TestSubInterfaces-93242471-53258068 launched on Node an-jenkins-deploy-platform-ansible-os-5690-1 2026-04-27 04:11:41,965 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/c8d077d6-6d16-4c24-b3b8-cc8b5370d48e 2026-04-27 04:11:41,984 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/c0fb2abc-6212-4802-a46b-bde701edb35a 2026-04-27 04:11:43,172 - DEBUG - (True, 'PING 169.254.0.7 (169.254.0.7) 56(84) bytes of data.\r\n64 bytes from 169.254.0.7: icmp_seq=1 ttl=63 time=5.39 ms\r\n64 bytes from 169.254.0.7: icmp_seq=2 ttl=63 time=1.44 ms\r\n\r\n--- 169.254.0.7 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 1.443/3.414/5.386/1.971 ms') 2026-04-27 04:11:43,172 - INFO - Ping to Metadata IP 169.254.0.7 of VM ctest-TestSubInterfaces-93242471-53258068 passed 2026-04-27 04:11:43,263 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-04-27 04:11:43,263 - DEBUG - Waiting to SSH to VM ctest-TestSubInterfaces-93242471-53258068, IP 102.13.185.133, Port 22 2026-04-27 04:11:43,436 - DEBUG - VM ctest-TestSubInterfaces-93242471-53258068 is ready for SSH connections 2026-04-27 04:11:43,436 - DEBUG - Running remote_cmd, Cmd : ifconfig -a| grep 168.166.76.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.33, gateway password: c0ntrail123 2026-04-27 04:11:43,436 - DEBUG - ifconfig -a| grep 168.166.76.131 -A2 -B4| grep -i 'hwaddr\|flags' | awk '{print $1}' | cut -d ':' -f 1 2026-04-27 04:12:06,372 - DEBUG - None 2026-04-27 04:12:11,373 - DEBUG - Running remote_cmd, Cmd : ifconfig -a| grep 168.166.76.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.33, gateway password: c0ntrail123 2026-04-27 04:12:11,373 - DEBUG - ifconfig -a| grep 168.166.76.131 -A2 -B4| grep -i 'hwaddr\|flags' | awk '{print $1}' | cut -d ':' -f 1 2026-04-27 04:12:14,240 - DEBUG - None 2026-04-27 04:12:19,240 - DEBUG - Running remote_cmd, Cmd : ifconfig -a| grep 168.166.76.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.33, gateway password: c0ntrail123 2026-04-27 04:12:19,241 - DEBUG - ifconfig -a| grep 168.166.76.131 -A2 -B4| grep -i 'hwaddr\|flags' | awk '{print $1}' | cut -d ':' -f 1 2026-04-27 04:12:21,082 - DEBUG - eth0.100 2026-04-27 04:12:21,082 - INFO - Interface eth0.100 is found on VM ee7d8a4c-be4e-485e-bb6f-6269e26fc8ba 2026-04-27 04:12:21,082 - DEBUG - Running remote_cmd, Cmd : ifconfig -a| grep 137.125.169.131 -A2 -B4| grep -i 'hwaddr\|flags' | awk '{print $1}' | cut -d ':' -f 1, host_string: ubuntu@169.254.0.7, password: ubuntugateway: ubuntu@10.0.0.33, gateway password: c0ntrail123 2026-04-27 04:12:21,083 - DEBUG - ifconfig -a| grep 137.125.169.131 -A2 -B4| grep -i 'hwaddr\|flags' | awk '{print $1}' | cut -d ':' -f 1 2026-04-27 04:13:02,587 - DEBUG - eth0.100 2026-04-27 04:13:02,587 - INFO - Interface eth0.100 is found on VM c8d077d6-6d16-4c24-b3b8-cc8b5370d48e 2026-04-27 04:13:02,588 - DEBUG - Running remote_cmd, Cmd : route add -net 137.125.169.128/26 dev eth0.100, host_string: ubuntu@169.254.0.5, password: ubuntugateway: ubuntu@10.0.0.33, gateway password: c0ntrail123 2026-04-27 04:13:02,588 - DEBUG - route add -net 137.125.169.128/26 dev eth0.100 2026-04-27 04:13:03,209 - DEBUG - None 2026-04-27 04:13:03,209 - DEBUG - Running remote_cmd, Cmd : route add -net 168.166.76.128/26 dev eth0.100, host_string: ubuntu@169.254.0.7, password: ubuntugateway: ubuntu@10.0.0.33, gateway password: c0ntrail123 2026-04-27 04:13:03,209 - DEBUG - route add -net 168.166.76.128/26 dev eth0.100 2026-04-27 04:13:03,836 - DEBUG - None 2026-04-27 04:13:03,836 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-04-27 04:13:03,836 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 137.125.169.132, host_string: ubuntu@169.254.0.5, password: ubuntugateway: ubuntu@10.0.0.33, gateway password: c0ntrail123 2026-04-27 04:13:03,837 - DEBUG - ping -s 56 -c 3 -W 1 137.125.169.132 2026-04-27 04:13:06,348 - DEBUG - PING 137.125.169.132 (137.125.169.132) 56(84) bytes of data. 64 bytes from 137.125.169.132: icmp_req=1 ttl=63 time=9.12 ms 64 bytes from 137.125.169.132: icmp_req=2 ttl=63 time=1.65 ms 64 bytes from 137.125.169.132: icmp_req=3 ttl=63 time=1.34 ms --- 137.125.169.132 ping statistics --- 3 packets transmitted, 3 received, 0% packet loss, time 2004ms rtt min/avg/max/mdev = 1.345/4.039/9.122/3.596 ms 2026-04-27 04:13:06,348 - INFO - Ping to IP 137.125.169.132 from VM ctest-TestSubInterfaces-93242471-98701118 passed 2026-04-27 04:13:06,348 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 137.125.169.131, host_string: ubuntu@169.254.0.5, password: ubuntugateway: ubuntu@10.0.0.33, gateway password: c0ntrail123 2026-04-27 04:13:06,348 - DEBUG - ping -s 56 -c 3 -W 1 137.125.169.131 2026-04-27 04:13:08,863 - DEBUG - PING 137.125.169.131 (137.125.169.131) 56(84) bytes of data. 64 bytes from 137.125.169.131: icmp_req=1 ttl=63 time=7.86 ms 64 bytes from 137.125.169.131: icmp_req=2 ttl=63 time=1.04 ms 64 bytes from 137.125.169.131: icmp_req=3 ttl=63 time=0.864 ms --- 137.125.169.131 ping statistics --- 3 packets transmitted, 3 received, 0% packet loss, time 2004ms rtt min/avg/max/mdev = 0.864/3.258/7.864/3.257 ms 2026-04-27 04:13:08,863 - INFO - Ping to IP 137.125.169.131 from VM ctest-TestSubInterfaces-93242471-98701118 passed 2026-04-27 04:13:08,863 - INFO - Deleting interface with subnet_id d11b314f-42f4-4d26-930f-d7c150424fa4, port_id None from router 673f10e1-30c5-4b01-93c5-f884893bdb4c 2026-04-27 04:13:09,244 - INFO - Deleting interface with subnet_id 6745d105-e45e-426b-968d-85c945715d59, port_id None from router 673f10e1-30c5-4b01-93c5-f884893bdb4c 2026-04-27 04:13:09,711 - INFO - Detaching port c0fb2abc-6212-4802-a46b-bde701edb35a from VM ctest-TestSubInterfaces-93242471-53258068 2026-04-27 04:13:09,778 - INFO - Deleting VM ctest-TestSubInterfaces-93242471-53258068 2026-04-27 04:13:09,887 - INFO - Deleting VM ctest-TestSubInterfaces-93242471-77929772 2026-04-27 04:13:10,002 - INFO - Detaching port 002f7b98-2f4b-4eb1-aa09-1a1298aafa0f from VM ctest-TestSubInterfaces-93242471-98701118 2026-04-27 04:13:10,067 - INFO - Deleting VM ctest-TestSubInterfaces-93242471-98701118 2026-04-27 04:13:11,544 - DEBUG - No XMPP flaps were noticed during the test
2026-04-27 04:13:11,544 - INFO - END TEST : test_vlan_interface_2 : PASSED[0:02:56]
2026-04-27 04:13:11,544 - INFO - -------------------------------------------------------------------------------- 2026-04-27 04:13:11,797 - INFO - Deleted port 3545534d-e3d7-4392-beb4-51c719a194de 2026-04-27 04:13:12,149 - DEBUG - Response for delete_port : () 2026-04-27 04:13:12,149 - INFO - Deleted port c0fb2abc-6212-4802-a46b-bde701edb35a 2026-04-27 04:13:12,342 - INFO - Deleted port 24762817-009b-4442-98ae-1258e6fcebc7 2026-04-27 04:13:12,598 - DEBUG - Response for delete_port : () 2026-04-27 04:13:12,598 - INFO - Deleted port 13bfa5a1-1b6b-42d4-9651-89094ec3359e 2026-04-27 04:13:12,814 - INFO - Deleted port 3d0e90b8-59bf-4e54-a59f-210b3cb00119 2026-04-27 04:13:13,068 - DEBUG - Response for delete_port : () 2026-04-27 04:13:13,068 - INFO - Deleted port 002f7b98-2f4b-4eb1-aa09-1a1298aafa0f 2026-04-27 04:13:13,069 - INFO - Deleting VN ctest-vn-25849729 2026-04-27 04:13:13,340 - DEBUG - Response for deleting network () 2026-04-27 04:13:13,340 - INFO - Deleting VN ctest-vn-82076117 2026-04-27 04:13:13,603 - DEBUG - Response for deleting network () 2026-04-27 04:13:13,603 - INFO - Deleting VN ctest-vn-07478506 2026-04-27 04:13:13,854 - DEBUG - Response for deleting network () 2026-04-27 04:13:14,870 - INFO - Deleted project: ctest-TestSubInterfaces-93242471, ID : a712dcc1-33b9-4079-b815-1cb26bd02aac