2026-06-03 18:02:50,883 - INFO - Domain Default found not creating
2026-06-03 18:02:51,092 - INFO - Project ctest-TestQos-60291285 not found, creating it
2026-06-03 18:02:51,684 - INFO - Created Project:ctest-TestQos-60291285, ID : 1b180239-b2f7-4dcc-8de1-fdbbcbac9da0
2026-06-03 18:02:54,288 - DEBUG - Response for create_network : {'network': {'id': '1769bbd9-01b7-4632-a5ea-fb5da23a0c3a', 'name': 'ctest-vn-21114724', 'tenant_id': '1b180239b2f74dcc8de1fdbbcbac9da0', 'project_id': '1b180239b2f74dcc8de1fdbbcbac9da0', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestQos-60291285', 'ctest-vn-21114724'], 'port_security_enabled': True, 'description': ''}}
2026-06-03 18:02:54,569 - DEBUG - Response for create_subnet : {'subnet': {'id': 'a5e66337-ceaa-43de-a5bd-1947fcebf4e9', 'name': '', 'tenant_id': '1b180239b2f74dcc8de1fdbbcbac9da0', 'network_id': '1769bbd9-01b7-4632-a5ea-fb5da23a0c3a', 'ip_version': 4, 'cidr': '29.54.168.128/26', 'allocation_pools': [{'start': '29.54.168.130', 'end': '29.54.168.190'}], 'gateway_ip': '29.54.168.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '29.54.168.130', 'tags': [], 'project_id': '1b180239b2f74dcc8de1fdbbcbac9da0'}}
2026-06-03 18:02:54,810 - DEBUG - Response for create_subnet : {'subnet': {'id': 'c80a0ded-9b7b-486b-9169-00eed8d8d090', 'name': '', 'tenant_id': '1b180239b2f74dcc8de1fdbbcbac9da0', 'network_id': '1769bbd9-01b7-4632-a5ea-fb5da23a0c3a', 'ip_version': 6, 'cidr': '23d9:db3f:21f0:5bcc:84e0:6aa6::/96', 'allocation_pools': [{'start': '23d9:db3f:21f0:5bcc:84e0:6aa6:0:2', 'end': '23d9:db3f:21f0:5bcc:84e0:6aa6:ffff:fffe'}], 'gateway_ip': '23d9:db3f:21f0:5bcc:84e0:6aa6:0:1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '23d9:db3f:21f0:5bcc:84e0:6aa6:0:2', 'tags': [], 'project_id': '1b180239b2f74dcc8de1fdbbcbac9da0'}}
2026-06-03 18:02:54,839 - INFO - Created VN ctest-vn-21114724
2026-06-03 18:02:54,852 - DEBUG - VN ctest-vn-21114724 UUID is 1769bbd9-01b7-4632-a5ea-fb5da23a0c3a
2026-06-03 18:02:55,263 - DEBUG - Response for create_network : {'network': {'id': 'ef1f9761-1462-486f-aec0-2276f022098c', 'name': 'ctest-vn-35809154', 'tenant_id': '1b180239b2f74dcc8de1fdbbcbac9da0', 'project_id': '1b180239b2f74dcc8de1fdbbcbac9da0', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestQos-60291285', 'ctest-vn-35809154'], 'port_security_enabled': True, 'description': ''}}
2026-06-03 18:02:55,561 - DEBUG - Response for create_subnet : {'subnet': {'id': 'ab82b534-b0f0-4598-9e91-7ca17482082a', 'name': '', 'tenant_id': '1b180239b2f74dcc8de1fdbbcbac9da0', 'network_id': 'ef1f9761-1462-486f-aec0-2276f022098c', 'ip_version': 4, 'cidr': '46.199.229.0/26', 'allocation_pools': [{'start': '46.199.229.2', 'end': '46.199.229.62'}], 'gateway_ip': '46.199.229.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '46.199.229.2', 'tags': [], 'project_id': '1b180239b2f74dcc8de1fdbbcbac9da0'}}
2026-06-03 18:02:55,795 - DEBUG - Response for create_subnet : {'subnet': {'id': '883e46ab-bc7e-4d40-963c-4ce91d93875e', 'name': '', 'tenant_id': '1b180239b2f74dcc8de1fdbbcbac9da0', 'network_id': 'ef1f9761-1462-486f-aec0-2276f022098c', 'ip_version': 6, 'cidr': '393a:5887:aac9:e120:47b3:dd68::/96', 'allocation_pools': [{'start': '393a:5887:aac9:e120:47b3:dd68:0:2', 'end': '393a:5887:aac9:e120:47b3:dd68:ffff:fffe'}], 'gateway_ip': '393a:5887:aac9:e120:47b3:dd68:0:1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '393a:5887:aac9:e120:47b3:dd68:0:2', 'tags': [], 'project_id': '1b180239b2f74dcc8de1fdbbcbac9da0'}}
2026-06-03 18:02:55,826 - INFO - Created VN ctest-vn-35809154
2026-06-03 18:02:55,841 - DEBUG - VN ctest-vn-35809154 UUID is ef1f9761-1462-486f-aec0-2276f022098c
2026-06-03 18:02:56,024 - DEBUG - Services list from nova: [, , , ]
2026-06-03 18:02:57,148 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5883-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5883-1)
2026-06-03 18:02:58,576 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5883-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5883-2)
2026-06-03 18:03:00,203 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5883-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5883-2)
2026-06-03 18:03:00,204 - INFO - Waiting for VM ctest-TestQos-60291285-47129343 to be up..
2026-06-03 18:03:00,306 - DEBUG - VM is in ACTIVE state now
2026-06-03 18:03:00,306 - INFO - VM name : ctest-TestQos-60291285-47129343
2026-06-03 18:03:00,414 - DEBUG - VM ctest-TestQos-60291285-47129343 ID is 886d7b11-5e3a-4c7c-b622-47fb40e087dd
2026-06-03 18:03:00,464 - DEBUG - VM ctest-TestQos-60291285-47129343 launched on Node an-jenkins-deploy-platform-ansible-os-5883-1
2026-06-03 18:03:00,574 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/886d7b11-5e3a-4c7c-b622-47fb40e087dd
2026-06-03 18:03:01,025 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/886d7b11-5e3a-4c7c-b622-47fb40e087dd
2026-06-03 18:03:01,109 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine-interface/cc882ef5-6d86-429f-98dc-6647ed6b4138
2026-06-03 18:03:04,303 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1017ms')
2026-06-03 18:03:04,303 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestQos-60291285-47129343 failed!
2026-06-03 18:03:04,320 - DEBUG - Gateway for vn default-domain:ctest-TestQos-60291285:ctest-vn-21114724 is 29.54.168.129 and allocation pool is NOT set
2026-06-03 18:03:04,320 - DEBUG - Gateway for vn default-domain:ctest-TestQos-60291285:ctest-vn-21114724 is 23d9:db3f:21f0:5bcc:84e0:6aa6:0:1 and allocation pool is NOT set
2026-06-03 18:03:08,398 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1010ms')
2026-06-03 18:03:08,398 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestQos-60291285-47129343 failed!
2026-06-03 18:03:08,414 - DEBUG - Gateway for vn default-domain:ctest-TestQos-60291285:ctest-vn-21114724 is 29.54.168.129 and allocation pool is NOT set
2026-06-03 18:03:08,414 - DEBUG - Gateway for vn default-domain:ctest-TestQos-60291285:ctest-vn-21114724 is 23d9:db3f:21f0:5bcc:84e0:6aa6:0:1 and allocation pool is NOT set
2026-06-03 18:03:12,488 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1015ms')
2026-06-03 18:03:12,488 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestQos-60291285-47129343 failed!
2026-06-03 18:03:12,505 - DEBUG - Gateway for vn default-domain:ctest-TestQos-60291285:ctest-vn-21114724 is 29.54.168.129 and allocation pool is NOT set
2026-06-03 18:03:12,505 - DEBUG - Gateway for vn default-domain:ctest-TestQos-60291285:ctest-vn-21114724 is 23d9:db3f:21f0:5bcc:84e0:6aa6:0:1 and allocation pool is NOT set
2026-06-03 18:03:16,592 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1016ms')
2026-06-03 18:03:16,592 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestQos-60291285-47129343 failed!
2026-06-03 18:03:16,611 - DEBUG - Gateway for vn default-domain:ctest-TestQos-60291285:ctest-vn-21114724 is 29.54.168.129 and allocation pool is NOT set
2026-06-03 18:03:16,611 - DEBUG - Gateway for vn default-domain:ctest-TestQos-60291285:ctest-vn-21114724 is 23d9:db3f:21f0:5bcc:84e0:6aa6:0:1 and allocation pool is NOT set
2026-06-03 18:03:20,688 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1006ms')
2026-06-03 18:03:20,688 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestQos-60291285-47129343 failed!
2026-06-03 18:03:20,704 - DEBUG - Gateway for vn default-domain:ctest-TestQos-60291285:ctest-vn-21114724 is 29.54.168.129 and allocation pool is NOT set
2026-06-03 18:03:20,704 - DEBUG - Gateway for vn default-domain:ctest-TestQos-60291285:ctest-vn-21114724 is 23d9:db3f:21f0:5bcc:84e0:6aa6:0:1 and allocation pool is NOT set
2026-06-03 18:03:24,781 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1014ms')
2026-06-03 18:03:24,781 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestQos-60291285-47129343 failed!
2026-06-03 18:03:24,797 - DEBUG - Gateway for vn default-domain:ctest-TestQos-60291285:ctest-vn-21114724 is 29.54.168.129 and allocation pool is NOT set
2026-06-03 18:03:24,798 - DEBUG - Gateway for vn default-domain:ctest-TestQos-60291285:ctest-vn-21114724 is 23d9:db3f:21f0:5bcc:84e0:6aa6:0:1 and allocation pool is NOT set
2026-06-03 18:03:28,878 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1014ms')
2026-06-03 18:03:28,879 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestQos-60291285-47129343 failed!
2026-06-03 18:03:28,894 - DEBUG - Gateway for vn default-domain:ctest-TestQos-60291285:ctest-vn-21114724 is 29.54.168.129 and allocation pool is NOT set
2026-06-03 18:03:28,894 - DEBUG - Gateway for vn default-domain:ctest-TestQos-60291285:ctest-vn-21114724 is 23d9:db3f:21f0:5bcc:84e0:6aa6:0:1 and allocation pool is NOT set
2026-06-03 18:03:32,975 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1015ms')
2026-06-03 18:03:32,975 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestQos-60291285-47129343 failed!
2026-06-03 18:03:32,992 - DEBUG - Gateway for vn default-domain:ctest-TestQos-60291285:ctest-vn-21114724 is 29.54.168.129 and allocation pool is NOT set
2026-06-03 18:03:32,992 - DEBUG - Gateway for vn default-domain:ctest-TestQos-60291285:ctest-vn-21114724 is 23d9:db3f:21f0:5bcc:84e0:6aa6:0:1 and allocation pool is NOT set
2026-06-03 18:03:37,068 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1014ms')
2026-06-03 18:03:37,069 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestQos-60291285-47129343 failed!
2026-06-03 18:03:37,084 - DEBUG - Gateway for vn default-domain:ctest-TestQos-60291285:ctest-vn-21114724 is 29.54.168.129 and allocation pool is NOT set
2026-06-03 18:03:37,084 - DEBUG - Gateway for vn default-domain:ctest-TestQos-60291285:ctest-vn-21114724 is 23d9:db3f:21f0:5bcc:84e0:6aa6:0:1 and allocation pool is NOT set
2026-06-03 18:03:41,164 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1016ms')
2026-06-03 18:03:41,164 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestQos-60291285-47129343 failed!
2026-06-03 18:03:41,179 - DEBUG - Gateway for vn default-domain:ctest-TestQos-60291285:ctest-vn-21114724 is 29.54.168.129 and allocation pool is NOT set
2026-06-03 18:03:41,179 - DEBUG - Gateway for vn default-domain:ctest-TestQos-60291285:ctest-vn-21114724 is 23d9:db3f:21f0:5bcc:84e0:6aa6:0:1 and allocation pool is NOT set
2026-06-03 18:03:45,255 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1016ms')
2026-06-03 18:03:45,256 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestQos-60291285-47129343 failed!
2026-06-03 18:03:45,272 - DEBUG - Gateway for vn default-domain:ctest-TestQos-60291285:ctest-vn-21114724 is 29.54.168.129 and allocation pool is NOT set
2026-06-03 18:03:45,272 - DEBUG - Gateway for vn default-domain:ctest-TestQos-60291285:ctest-vn-21114724 is 23d9:db3f:21f0:5bcc:84e0:6aa6:0:1 and allocation pool is NOT set
2026-06-03 18:03:47,331 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n64 bytes from 169.254.0.3: icmp_seq=1 ttl=63 time=10.7 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.760 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 0.760/5.730/10.700/4.970 ms')
2026-06-03 18:03:47,331 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestQos-60291285-47129343 passed
2026-06-03 18:03:47,406 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-03 18:03:47,406 - DEBUG - Waiting to SSH to VM ctest-TestQos-60291285-47129343, IP 29.54.168.131, Port 22
2026-06-03 18:03:47,477 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2026-06-03 18:03:47,591 - DEBUG - VM ctest-TestQos-60291285-47129343 is NOT ready for SSH connections, VM status: ACTIVE
2026-06-03 18:03:52,591 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-03 18:03:52,592 - DEBUG - Waiting to SSH to VM ctest-TestQos-60291285-47129343, IP 29.54.168.131, Port 22
2026-06-03 18:03:52,650 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2026-06-03 18:03:52,746 - DEBUG - VM ctest-TestQos-60291285-47129343 is NOT ready for SSH connections, VM status: ACTIVE
2026-06-03 18:03:57,747 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-03 18:03:57,747 - DEBUG - Waiting to SSH to VM ctest-TestQos-60291285-47129343, IP 29.54.168.131, Port 22
2026-06-03 18:03:57,816 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2026-06-03 18:03:57,927 - DEBUG - VM ctest-TestQos-60291285-47129343 is NOT ready for SSH connections, VM status: ACTIVE
2026-06-03 18:04:02,928 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-03 18:04:02,928 - DEBUG - Waiting to SSH to VM ctest-TestQos-60291285-47129343, IP 29.54.168.131, Port 22
2026-06-03 18:04:02,995 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2026-06-03 18:04:03,104 - DEBUG - VM ctest-TestQos-60291285-47129343 is NOT ready for SSH connections, VM status: ACTIVE
2026-06-03 18:04:08,105 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-03 18:04:08,105 - DEBUG - Waiting to SSH to VM ctest-TestQos-60291285-47129343, IP 29.54.168.131, Port 22
2026-06-03 18:04:08,165 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2026-06-03 18:04:08,304 - DEBUG - VM ctest-TestQos-60291285-47129343 is NOT ready for SSH connections, VM status: ACTIVE
2026-06-03 18:04:13,304 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-03 18:04:13,305 - DEBUG - Waiting to SSH to VM ctest-TestQos-60291285-47129343, IP 29.54.168.131, Port 22
2026-06-03 18:04:13,460 - DEBUG - VM ctest-TestQos-60291285-47129343 is ready for SSH connections
2026-06-03 18:04:13,461 - INFO - Waiting for VM ctest-TestQos-60291285-39093332 to be up..
2026-06-03 18:04:13,573 - DEBUG - VM is in ACTIVE state now
2026-06-03 18:04:13,573 - INFO - VM name : ctest-TestQos-60291285-39093332
2026-06-03 18:04:13,686 - DEBUG - VM ctest-TestQos-60291285-39093332 ID is 8888f9d5-83ec-4c2b-a901-ede2d4b7742d
2026-06-03 18:04:13,687 - DEBUG - VM ctest-TestQos-60291285-39093332 launched on Node an-jenkins-deploy-platform-ansible-os-5883-2
2026-06-03 18:04:13,811 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/8888f9d5-83ec-4c2b-a901-ede2d4b7742d
2026-06-03 18:04:13,825 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine-interface/02d0125f-6760-4b1a-9dc7-8726eb649a46
2026-06-03 18:04:17,045 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1038ms')
2026-06-03 18:04:17,045 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestQos-60291285-39093332 failed!
2026-06-03 18:04:17,061 - DEBUG - Gateway for vn default-domain:ctest-TestQos-60291285:ctest-vn-21114724 is 29.54.168.129 and allocation pool is NOT set
2026-06-03 18:04:17,062 - DEBUG - Gateway for vn default-domain:ctest-TestQos-60291285:ctest-vn-21114724 is 23d9:db3f:21f0:5bcc:84e0:6aa6:0:1 and allocation pool is NOT set
2026-06-03 18:04:21,138 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1001ms')
2026-06-03 18:04:21,138 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestQos-60291285-39093332 failed!
2026-06-03 18:04:21,156 - DEBUG - Gateway for vn default-domain:ctest-TestQos-60291285:ctest-vn-21114724 is 29.54.168.129 and allocation pool is NOT set
2026-06-03 18:04:21,156 - DEBUG - Gateway for vn default-domain:ctest-TestQos-60291285:ctest-vn-21114724 is 23d9:db3f:21f0:5bcc:84e0:6aa6:0:1 and allocation pool is NOT set
2026-06-03 18:04:25,233 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1018ms')
2026-06-03 18:04:25,233 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestQos-60291285-39093332 failed!
2026-06-03 18:04:25,252 - DEBUG - Gateway for vn default-domain:ctest-TestQos-60291285:ctest-vn-21114724 is 29.54.168.129 and allocation pool is NOT set
2026-06-03 18:04:25,253 - DEBUG - Gateway for vn default-domain:ctest-TestQos-60291285:ctest-vn-21114724 is 23d9:db3f:21f0:5bcc:84e0:6aa6:0:1 and allocation pool is NOT set
2026-06-03 18:04:29,334 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1012ms')
2026-06-03 18:04:29,334 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestQos-60291285-39093332 failed!
2026-06-03 18:04:29,351 - DEBUG - Gateway for vn default-domain:ctest-TestQos-60291285:ctest-vn-21114724 is 29.54.168.129 and allocation pool is NOT set
2026-06-03 18:04:29,351 - DEBUG - Gateway for vn default-domain:ctest-TestQos-60291285:ctest-vn-21114724 is 23d9:db3f:21f0:5bcc:84e0:6aa6:0:1 and allocation pool is NOT set
2026-06-03 18:04:33,431 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1017ms')
2026-06-03 18:04:33,431 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestQos-60291285-39093332 failed!
2026-06-03 18:04:33,448 - DEBUG - Gateway for vn default-domain:ctest-TestQos-60291285:ctest-vn-21114724 is 29.54.168.129 and allocation pool is NOT set
2026-06-03 18:04:33,448 - DEBUG - Gateway for vn default-domain:ctest-TestQos-60291285:ctest-vn-21114724 is 23d9:db3f:21f0:5bcc:84e0:6aa6:0:1 and allocation pool is NOT set
2026-06-03 18:04:37,529 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1010ms')
2026-06-03 18:04:37,529 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestQos-60291285-39093332 failed!
2026-06-03 18:04:37,546 - DEBUG - Gateway for vn default-domain:ctest-TestQos-60291285:ctest-vn-21114724 is 29.54.168.129 and allocation pool is NOT set
2026-06-03 18:04:37,546 - DEBUG - Gateway for vn default-domain:ctest-TestQos-60291285:ctest-vn-21114724 is 23d9:db3f:21f0:5bcc:84e0:6aa6:0:1 and allocation pool is NOT set
2026-06-03 18:04:41,653 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1030ms')
2026-06-03 18:04:41,653 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestQos-60291285-39093332 failed!
2026-06-03 18:04:41,669 - DEBUG - Gateway for vn default-domain:ctest-TestQos-60291285:ctest-vn-21114724 is 29.54.168.129 and allocation pool is NOT set
2026-06-03 18:04:41,669 - DEBUG - Gateway for vn default-domain:ctest-TestQos-60291285:ctest-vn-21114724 is 23d9:db3f:21f0:5bcc:84e0:6aa6:0:1 and allocation pool is NOT set
2026-06-03 18:04:45,750 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1019ms')
2026-06-03 18:04:45,750 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestQos-60291285-39093332 failed!
2026-06-03 18:04:45,766 - DEBUG - Gateway for vn default-domain:ctest-TestQos-60291285:ctest-vn-21114724 is 29.54.168.129 and allocation pool is NOT set
2026-06-03 18:04:45,766 - DEBUG - Gateway for vn default-domain:ctest-TestQos-60291285:ctest-vn-21114724 is 23d9:db3f:21f0:5bcc:84e0:6aa6:0:1 and allocation pool is NOT set
2026-06-03 18:04:49,846 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1014ms')
2026-06-03 18:04:49,846 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestQos-60291285-39093332 failed!
2026-06-03 18:04:49,862 - DEBUG - Gateway for vn default-domain:ctest-TestQos-60291285:ctest-vn-21114724 is 29.54.168.129 and allocation pool is NOT set
2026-06-03 18:04:49,862 - DEBUG - Gateway for vn default-domain:ctest-TestQos-60291285:ctest-vn-21114724 is 23d9:db3f:21f0:5bcc:84e0:6aa6:0:1 and allocation pool is NOT set
2026-06-03 18:04:53,941 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1017ms')
2026-06-03 18:04:53,942 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestQos-60291285-39093332 failed!
2026-06-03 18:04:53,957 - DEBUG - Gateway for vn default-domain:ctest-TestQos-60291285:ctest-vn-21114724 is 29.54.168.129 and allocation pool is NOT set
2026-06-03 18:04:53,957 - DEBUG - Gateway for vn default-domain:ctest-TestQos-60291285:ctest-vn-21114724 is 23d9:db3f:21f0:5bcc:84e0:6aa6:0:1 and allocation pool is NOT set
2026-06-03 18:04:58,038 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1016ms')
2026-06-03 18:04:58,038 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestQos-60291285-39093332 failed!
2026-06-03 18:04:58,056 - DEBUG - Gateway for vn default-domain:ctest-TestQos-60291285:ctest-vn-21114724 is 29.54.168.129 and allocation pool is NOT set
2026-06-03 18:04:58,056 - DEBUG - Gateway for vn default-domain:ctest-TestQos-60291285:ctest-vn-21114724 is 23d9:db3f:21f0:5bcc:84e0:6aa6:0:1 and allocation pool is NOT set
2026-06-03 18:05:02,137 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1012ms')
2026-06-03 18:05:02,137 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestQos-60291285-39093332 failed!
2026-06-03 18:05:02,155 - DEBUG - Gateway for vn default-domain:ctest-TestQos-60291285:ctest-vn-21114724 is 29.54.168.129 and allocation pool is NOT set
2026-06-03 18:05:02,156 - DEBUG - Gateway for vn default-domain:ctest-TestQos-60291285:ctest-vn-21114724 is 23d9:db3f:21f0:5bcc:84e0:6aa6:0:1 and allocation pool is NOT set
2026-06-03 18:05:06,232 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1015ms')
2026-06-03 18:05:06,232 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestQos-60291285-39093332 failed!
2026-06-03 18:05:06,247 - DEBUG - Gateway for vn default-domain:ctest-TestQos-60291285:ctest-vn-21114724 is 29.54.168.129 and allocation pool is NOT set
2026-06-03 18:05:06,247 - DEBUG - Gateway for vn default-domain:ctest-TestQos-60291285:ctest-vn-21114724 is 23d9:db3f:21f0:5bcc:84e0:6aa6:0:1 and allocation pool is NOT set
2026-06-03 18:05:10,331 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1018ms')
2026-06-03 18:05:10,331 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestQos-60291285-39093332 failed!
2026-06-03 18:05:10,347 - DEBUG - Gateway for vn default-domain:ctest-TestQos-60291285:ctest-vn-21114724 is 29.54.168.129 and allocation pool is NOT set
2026-06-03 18:05:10,347 - DEBUG - Gateway for vn default-domain:ctest-TestQos-60291285:ctest-vn-21114724 is 23d9:db3f:21f0:5bcc:84e0:6aa6:0:1 and allocation pool is NOT set
2026-06-03 18:05:14,416 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1007ms')
2026-06-03 18:05:14,416 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestQos-60291285-39093332 failed!
2026-06-03 18:05:14,431 - DEBUG - Gateway for vn default-domain:ctest-TestQos-60291285:ctest-vn-21114724 is 29.54.168.129 and allocation pool is NOT set
2026-06-03 18:05:14,432 - DEBUG - Gateway for vn default-domain:ctest-TestQos-60291285:ctest-vn-21114724 is 23d9:db3f:21f0:5bcc:84e0:6aa6:0:1 and allocation pool is NOT set
2026-06-03 18:05:18,520 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1009ms')
2026-06-03 18:05:18,520 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestQos-60291285-39093332 failed!
2026-06-03 18:05:18,537 - DEBUG - Gateway for vn default-domain:ctest-TestQos-60291285:ctest-vn-21114724 is 29.54.168.129 and allocation pool is NOT set
2026-06-03 18:05:18,537 - DEBUG - Gateway for vn default-domain:ctest-TestQos-60291285:ctest-vn-21114724 is 23d9:db3f:21f0:5bcc:84e0:6aa6:0:1 and allocation pool is NOT set
2026-06-03 18:05:20,620 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n64 bytes from 169.254.0.3: icmp_seq=1 ttl=63 time=22.8 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=11.7 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 11.668/17.212/22.756/5.544 ms')
2026-06-03 18:05:20,620 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestQos-60291285-39093332 passed
2026-06-03 18:05:20,695 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-03 18:05:20,695 - DEBUG - Waiting to SSH to VM ctest-TestQos-60291285-39093332, IP 29.54.168.132, Port 22
2026-06-03 18:05:20,786 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2026-06-03 18:05:20,889 - DEBUG - VM ctest-TestQos-60291285-39093332 is NOT ready for SSH connections, VM status: ACTIVE
2026-06-03 18:05:25,890 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-03 18:05:25,890 - DEBUG - Waiting to SSH to VM ctest-TestQos-60291285-39093332, IP 29.54.168.132, Port 22
2026-06-03 18:05:25,965 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2026-06-03 18:05:26,057 - DEBUG - VM ctest-TestQos-60291285-39093332 is NOT ready for SSH connections, VM status: ACTIVE
2026-06-03 18:05:31,058 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-03 18:05:31,058 - DEBUG - Waiting to SSH to VM ctest-TestQos-60291285-39093332, IP 29.54.168.132, Port 22
2026-06-03 18:05:31,125 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2026-06-03 18:05:31,221 - DEBUG - VM ctest-TestQos-60291285-39093332 is NOT ready for SSH connections, VM status: ACTIVE
2026-06-03 18:05:36,221 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-03 18:05:36,222 - DEBUG - Waiting to SSH to VM ctest-TestQos-60291285-39093332, IP 29.54.168.132, Port 22
2026-06-03 18:05:36,310 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2026-06-03 18:05:36,405 - DEBUG - VM ctest-TestQos-60291285-39093332 is NOT ready for SSH connections, VM status: ACTIVE
2026-06-03 18:05:41,406 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-03 18:05:41,406 - DEBUG - Waiting to SSH to VM ctest-TestQos-60291285-39093332, IP 29.54.168.132, Port 22
2026-06-03 18:05:41,462 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2026-06-03 18:05:41,572 - DEBUG - VM ctest-TestQos-60291285-39093332 is NOT ready for SSH connections, VM status: ACTIVE
2026-06-03 18:05:46,573 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-03 18:05:46,573 - DEBUG - Waiting to SSH to VM ctest-TestQos-60291285-39093332, IP 29.54.168.132, Port 22
2026-06-03 18:05:46,661 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2026-06-03 18:05:46,760 - DEBUG - VM ctest-TestQos-60291285-39093332 is NOT ready for SSH connections, VM status: ACTIVE
2026-06-03 18:05:51,762 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-03 18:05:51,762 - DEBUG - Waiting to SSH to VM ctest-TestQos-60291285-39093332, IP 29.54.168.132, Port 22
2026-06-03 18:05:51,845 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2026-06-03 18:05:51,980 - DEBUG - VM ctest-TestQos-60291285-39093332 is NOT ready for SSH connections, VM status: ACTIVE
2026-06-03 18:05:56,981 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-03 18:05:56,981 - DEBUG - Waiting to SSH to VM ctest-TestQos-60291285-39093332, IP 29.54.168.132, Port 22
2026-06-03 18:05:57,059 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2026-06-03 18:05:57,154 - DEBUG - VM ctest-TestQos-60291285-39093332 is NOT ready for SSH connections, VM status: ACTIVE
2026-06-03 18:06:02,155 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-03 18:06:02,155 - DEBUG - Waiting to SSH to VM ctest-TestQos-60291285-39093332, IP 29.54.168.132, Port 22
2026-06-03 18:06:02,245 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2026-06-03 18:06:02,343 - DEBUG - VM ctest-TestQos-60291285-39093332 is NOT ready for SSH connections, VM status: ACTIVE
2026-06-03 18:06:07,345 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-03 18:06:07,345 - DEBUG - Waiting to SSH to VM ctest-TestQos-60291285-39093332, IP 29.54.168.132, Port 22
2026-06-03 18:06:07,423 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2026-06-03 18:06:07,548 - DEBUG - VM ctest-TestQos-60291285-39093332 is NOT ready for SSH connections, VM status: ACTIVE
2026-06-03 18:06:12,549 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-03 18:06:12,549 - DEBUG - Waiting to SSH to VM ctest-TestQos-60291285-39093332, IP 29.54.168.132, Port 22
2026-06-03 18:06:12,641 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2026-06-03 18:06:12,739 - DEBUG - VM ctest-TestQos-60291285-39093332 is NOT ready for SSH connections, VM status: ACTIVE
2026-06-03 18:06:17,740 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-03 18:06:17,740 - DEBUG - Waiting to SSH to VM ctest-TestQos-60291285-39093332, IP 29.54.168.132, Port 22
2026-06-03 18:06:17,809 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2026-06-03 18:06:17,914 - DEBUG - VM ctest-TestQos-60291285-39093332 is NOT ready for SSH connections, VM status: ACTIVE
2026-06-03 18:06:22,914 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-03 18:06:22,914 - DEBUG - Waiting to SSH to VM ctest-TestQos-60291285-39093332, IP 29.54.168.132, Port 22
2026-06-03 18:06:22,990 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2026-06-03 18:06:23,103 - DEBUG - VM ctest-TestQos-60291285-39093332 is NOT ready for SSH connections, VM status: ACTIVE
2026-06-03 18:06:28,104 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-03 18:06:28,104 - DEBUG - Waiting to SSH to VM ctest-TestQos-60291285-39093332, IP 29.54.168.132, Port 22
2026-06-03 18:06:28,194 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2026-06-03 18:06:28,284 - DEBUG - VM ctest-TestQos-60291285-39093332 is NOT ready for SSH connections, VM status: ACTIVE
2026-06-03 18:06:33,285 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-03 18:06:33,285 - DEBUG - Waiting to SSH to VM ctest-TestQos-60291285-39093332, IP 29.54.168.132, Port 22
2026-06-03 18:06:33,352 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2026-06-03 18:06:33,448 - DEBUG - VM ctest-TestQos-60291285-39093332 is NOT ready for SSH connections, VM status: ACTIVE
2026-06-03 18:06:38,449 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-03 18:06:38,450 - DEBUG - Waiting to SSH to VM ctest-TestQos-60291285-39093332, IP 29.54.168.132, Port 22
2026-06-03 18:06:38,528 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2026-06-03 18:06:38,630 - DEBUG - VM ctest-TestQos-60291285-39093332 is NOT ready for SSH connections, VM status: ACTIVE
2026-06-03 18:06:43,631 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-03 18:06:43,631 - DEBUG - Waiting to SSH to VM ctest-TestQos-60291285-39093332, IP 29.54.168.132, Port 22
2026-06-03 18:06:43,727 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2026-06-03 18:06:43,836 - DEBUG - VM ctest-TestQos-60291285-39093332 is NOT ready for SSH connections, VM status: ACTIVE
2026-06-03 18:06:48,837 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-03 18:06:48,837 - DEBUG - Waiting to SSH to VM ctest-TestQos-60291285-39093332, IP 29.54.168.132, Port 22
2026-06-03 18:06:48,914 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2026-06-03 18:06:49,004 - DEBUG - VM ctest-TestQos-60291285-39093332 is NOT ready for SSH connections, VM status: ACTIVE
2026-06-03 18:06:54,005 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-03 18:06:54,005 - DEBUG - Waiting to SSH to VM ctest-TestQos-60291285-39093332, IP 29.54.168.132, Port 22
2026-06-03 18:06:54,087 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2026-06-03 18:06:54,191 - DEBUG - VM ctest-TestQos-60291285-39093332 is NOT ready for SSH connections, VM status: ACTIVE
2026-06-03 18:06:59,192 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-03 18:06:59,193 - DEBUG - Waiting to SSH to VM ctest-TestQos-60291285-39093332, IP 29.54.168.132, Port 22
2026-06-03 18:06:59,300 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2026-06-03 18:06:59,421 - DEBUG - VM ctest-TestQos-60291285-39093332 is NOT ready for SSH connections, VM status: ACTIVE
2026-06-03 18:07:04,422 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-03 18:07:04,422 - DEBUG - Waiting to SSH to VM ctest-TestQos-60291285-39093332, IP 29.54.168.132, Port 22
2026-06-03 18:07:04,504 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2026-06-03 18:07:04,601 - DEBUG - VM ctest-TestQos-60291285-39093332 is NOT ready for SSH connections, VM status: ACTIVE
2026-06-03 18:07:09,601 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-03 18:07:09,601 - DEBUG - Waiting to SSH to VM ctest-TestQos-60291285-39093332, IP 29.54.168.132, Port 22
2026-06-03 18:07:09,681 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2026-06-03 18:07:09,775 - DEBUG - VM ctest-TestQos-60291285-39093332 is NOT ready for SSH connections, VM status: ACTIVE
2026-06-03 18:07:14,776 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-03 18:07:14,776 - DEBUG - Waiting to SSH to VM ctest-TestQos-60291285-39093332, IP 29.54.168.132, Port 22
2026-06-03 18:07:14,876 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2026-06-03 18:07:14,969 - DEBUG - VM ctest-TestQos-60291285-39093332 is NOT ready for SSH connections, VM status: ACTIVE
2026-06-03 18:07:19,970 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-03 18:07:19,970 - DEBUG - Waiting to SSH to VM ctest-TestQos-60291285-39093332, IP 29.54.168.132, Port 22
2026-06-03 18:07:20,038 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2026-06-03 18:07:20,127 - DEBUG - VM ctest-TestQos-60291285-39093332 is NOT ready for SSH connections, VM status: ACTIVE
2026-06-03 18:07:25,128 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-03 18:07:25,128 - DEBUG - Waiting to SSH to VM ctest-TestQos-60291285-39093332, IP 29.54.168.132, Port 22
2026-06-03 18:07:25,206 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2026-06-03 18:07:25,309 - DEBUG - VM ctest-TestQos-60291285-39093332 is NOT ready for SSH connections, VM status: ACTIVE
2026-06-03 18:07:30,310 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-03 18:07:30,310 - DEBUG - Waiting to SSH to VM ctest-TestQos-60291285-39093332, IP 29.54.168.132, Port 22
2026-06-03 18:07:30,378 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2026-06-03 18:07:30,468 - DEBUG - VM ctest-TestQos-60291285-39093332 is NOT ready for SSH connections, VM status: ACTIVE
2026-06-03 18:07:35,469 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-03 18:07:35,469 - DEBUG - Waiting to SSH to VM ctest-TestQos-60291285-39093332, IP 29.54.168.132, Port 22
2026-06-03 18:07:35,537 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2026-06-03 18:07:35,651 - DEBUG - VM ctest-TestQos-60291285-39093332 is NOT ready for SSH connections, VM status: ACTIVE
2026-06-03 18:07:40,652 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-03 18:07:40,652 - DEBUG - Waiting to SSH to VM ctest-TestQos-60291285-39093332, IP 29.54.168.132, Port 22
2026-06-03 18:07:40,824 - DEBUG - VM ctest-TestQos-60291285-39093332 is ready for SSH connections
2026-06-03 18:07:40,824 - INFO - Waiting for VM ctest-TestQos-60291285-14131773 to be up..
2026-06-03 18:07:40,924 - DEBUG - VM is in ACTIVE state now
2026-06-03 18:07:40,924 - INFO - VM name : ctest-TestQos-60291285-14131773
2026-06-03 18:07:41,026 - DEBUG - VM ctest-TestQos-60291285-14131773 ID is bca82926-ecde-4850-9669-eb12a9330d6f
2026-06-03 18:07:41,027 - DEBUG - VM ctest-TestQos-60291285-14131773 launched on Node an-jenkins-deploy-platform-ansible-os-5883-2
2026-06-03 18:07:41,129 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/bca82926-ecde-4850-9669-eb12a9330d6f
2026-06-03 18:07:41,141 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine-interface/2caffefe-1cb8-4e19-bf48-7f38856ca66e
2026-06-03 18:07:42,312 - 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=3.13 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.358 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 0.358/1.742/3.127/1.384 ms')
2026-06-03 18:07:42,312 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-TestQos-60291285-14131773 passed
2026-06-03 18:07:42,382 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-03 18:07:42,382 - DEBUG - Waiting to SSH to VM ctest-TestQos-60291285-14131773, IP 46.199.229.3, Port 22
2026-06-03 18:07:42,576 - DEBUG - VM ctest-TestQos-60291285-14131773 is ready for SSH connections
2026-06-03 18:07:42,580 - INFO - ================================================================================
2026-06-03 18:07:42,580 - INFO - STARTING TEST : test_qos_remark_dscp_on_vmi
2026-06-03 18:07:42,580 - INFO - TEST DESCRIPTION : Create a qos config for remarking DSCP 1 to 10
Have VMs A, B
Apply the qos config to VM A
Validate that packets from A to B have DSCP marked correctly
2026-06-03 18:07:43,901 - DEBUG - Nothing to compare xmpp stats {'10.0.0.22': {'10.20.0.25': '0', '10.20.0.17': '0'}, '10.0.0.19': {'10.20.0.25': '0', '10.20.0.17': '0'}} with
2026-06-03 18:07:43,901 - INFO - Initial checks done. Running the testcase now
2026-06-03 18:07:43,901 - INFO -
2026-06-03 18:07:43,908 - DEBUG - FC Dict is {'fc_id': 0, 'dscp': 10, 'dot1p': 1, 'exp': 1, 'connections': }
2026-06-03 18:07:44,177 - INFO - Created FC ['default-global-system-config', 'default-global-qos-config', 'ctest-fc-31008741'], UUID feb64e53-1084-4ef6-8277-7fcc4300299a
2026-06-03 18:07:44,601 - INFO - Created QosConfig ['default-domain', 'ctest-TestQos-60291285', 'ctest-qos_config-64199770'], UUID: 3cce7257-7646-4cc9-98fe-22c01838aa5f
2026-06-03 18:07:44,653 - INFO - Applying qos-config on VM cc882ef5-6d86-429f-98dc-6647ed6b4138
2026-06-03 18:07:44,924 - DEBUG - VM is in ACTIVE state now
2026-06-03 18:07:45,197 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/886d7b11-5e3a-4c7c-b622-47fb40e087dd
2026-06-03 18:07:45,207 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine-interface/cc882ef5-6d86-429f-98dc-6647ed6b4138
2026-06-03 18:07:45,219 - DEBUG - Requesting: http://10.0.0.249:8082/instance-ip/17845d35-7548-4362-9275-51f5afdef889
2026-06-03 18:07:45,231 - DEBUG - Requesting: http://10.0.0.249:8082/instance-ip/30d21679-088d-4073-91b9-dee98a55d8b5
2026-06-03 18:07:45,472 - DEBUG - VM is in ACTIVE state now
2026-06-03 18:07:45,775 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/8888f9d5-83ec-4c2b-a901-ede2d4b7742d
2026-06-03 18:07:45,784 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine-interface/02d0125f-6760-4b1a-9dc7-8726eb649a46
2026-06-03 18:07:45,798 - DEBUG - Requesting: http://10.0.0.249:8082/instance-ip/3856a09e-1ab2-4331-91d7-7fb537326b46
2026-06-03 18:07:45,812 - DEBUG - Requesting: http://10.0.0.249:8082/instance-ip/ba5b5e97-6ec9-4887-856e-f3a77f180096
2026-06-03 18:07:45,849 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-03 18:07:45,849 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-03 18:07:45,849 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-03 18:07:45,849 - INFO - Starting hping3 on ctest-TestQos-60291285-47129343, args: --destport 20000 --baseport 10000 --count 20000 --interval 1 --udp --tos 4 --keep --numeric
2026-06-03 18:07:45,849 - DEBUG - Hping3 cmd : hping3 --destport 20000 --baseport 10000 --count 20000 --interval 1 --udp --tos 4 --keep --numeric 29.54.168.132 1>/tmp/hping_ctest-random-98840238.log 2>/tmp/hping_ctest-random-98840238.result
2026-06-03 18:07:45,849 - DEBUG - Running remote_cmd, Cmd : hping3 --destport 20000 --baseport 10000 --count 20000 --interval 1 --udp --tos 4 --keep --numeric 29.54.168.132 1>/tmp/hping_ctest-random-98840238.log 2>/tmp/hping_ctest-random-98840238.result, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.22, gateway password: c0ntrail123
2026-06-03 18:07:45,850 - DEBUG - nohup hping3 --destport 20000 --baseport 10000 --count 20000 --interval 1 --udp --tos 4 --keep --numeric 29.54.168.132 1>/tmp/hping_ctest-random-98840238.log 2>/tmp/hping_ctest-random-98840238.result & echo $! > /tmp/hping_ctest-random-98840238.pid
2026-06-03 18:08:29,074 - DEBUG - None
2026-06-03 18:08:34,282 - DEBUG - Forward flow: {'index': '499840', 'rflow': '430228', 'sip': '29.54.168.131', 'sport': '10000', 'dip': '29.54.168.132', 'dport': '20000', 'proto': '17', 'vrf_id': '2', 'action': 'FORWARD', 'flags': ' ACTIVE | RFLOW_VALID ', 'd_vrf_id': '0', 'bytes': '252', 'pkts': '6', 'insight': '0', 'nhid': '31', 'underlay_udp_sport': '55952', 'ttl': '0', 'qos_id': '0', 'gen_id': '1', 'tcp_seq': '0', 'oflow_bytes': '0', 'oflow_packets': '0', 'underlay_gw_index': '-1'}
2026-06-03 18:08:34,285 - DEBUG - Reverse flow: {'index': '430228', 'rflow': '499840', 'sip': '29.54.168.132', 'sport': '20000', 'dip': '29.54.168.131', 'dport': '10000', 'proto': '17', 'vrf_id': '2', 'action': 'FORWARD', 'flags': ' ACTIVE | RFLOW_VALID ', 'd_vrf_id': '0', 'bytes': '420', 'pkts': '6', 'insight': '0', 'nhid': '31', 'underlay_udp_sport': '52732', 'ttl': '0', 'qos_id': '0', 'gen_id': '1', 'tcp_seq': '0', 'oflow_bytes': '0', 'oflow_packets': '0', 'underlay_gw_index': '-1'}
2026-06-03 18:08:34,285 - DEBUG - The filter pattern is ['udp', 'and', 'src port 55952']
2026-06-03 18:08:34,285 - DEBUG - The filter string is '(udp and src port 55952)'
2026-06-03 18:08:34,408 - DEBUG - Executing command: sudo tcpdump -nni ens6 -U -vvxx '(udp and src port 55952)' -w /tmp/ens6_ctest-random-09162687.pcap
2026-06-03 18:08:39,496 - DEBUG - Executing command: sudo kill $(ps -ef|grep tcpdump | grep pcap| awk '{print $2}')
2026-06-03 18:08:41,500 - DEBUG - Ensuring hping3 instance with result file /tmp/hping_ctest-random-98840238.result on ctest-TestQos-60291285-47129343 is stopped
2026-06-03 18:08:41,500 - DEBUG - Running remote_cmd, Cmd : cat /tmp/hping_ctest-random-98840238.pid | xargs kill , host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.22, gateway password: c0ntrail123
2026-06-03 18:08:41,500 - DEBUG - cat /tmp/hping_ctest-random-98840238.pid | xargs kill
2026-06-03 18:08:42,311 - DEBUG - None
2026-06-03 18:08:42,312 - DEBUG - Running remote_cmd, Cmd : cat /tmp/hping_ctest-random-98840238.result, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.22, gateway password: c0ntrail123
2026-06-03 18:08:42,312 - DEBUG - cat /tmp/hping_ctest-random-98840238.result
2026-06-03 18:08:42,650 - DEBUG - --- 29.54.168.132 hping statistic ---
14 packets transmitted, 14 packets received, 0% packet loss
round-trip min/avg/max = 18.1/6510.8/13013.2 ms
2026-06-03 18:08:42,651 - DEBUG - Running remote_cmd, Cmd : cat /tmp/hping_ctest-random-98840238.log, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.22, gateway password: c0ntrail123
2026-06-03 18:08:42,651 - DEBUG - cat /tmp/hping_ctest-random-98840238.log
2026-06-03 18:08:43,025 - DEBUG - HPING 29.54.168.132 (eth0 29.54.168.132): udp mode set, 28 headers + 0 data bytes
ICMP Port Unreachable from ip=29.54.168.132
status=0 port=10000 seq=0
ICMP Port Unreachable from ip=29.54.168.132
status=1 port=10000 seq=0
ICMP Port Unreachable from ip=29.54.168.132
status=1 port=10000 seq=0
ICMP Port Unreachable from ip=29.54.168.132
status=1 port=10000 seq=0
ICMP Port Unreachable from ip=29.54.168.132
status=1 port=10000 seq=0
ICMP Port Unreachable from ip=29.54.168.132
status=1 port=10000 seq=0
ICMP Port Unreachable from ip=29.54.168.132
status=1 port=10000 seq=0
ICMP Port Unreachable from ip=29.54.168.132
status=1 port=10000 seq=0
ICMP Port Unreachable from ip=29.54.168.132
status=1 port=10000 seq=0
ICMP Port Unreachable from ip=29.54.168.132
status=1 port=10000 seq=0
ICMP Port Unreachable from ip=29.54.168.132
status=1 port=10000 seq=0
ICMP Port Unreachable from ip=29.54.168.132
status=1 port=10000 seq=0
ICMP Port Unreachable from ip=29.54.168.132
status=1 port=10000 seq=0
ICMP Port Unreachable from ip=29.54.168.132
status=1 port=10000 seq=0
2026-06-03 18:08:43,026 - DEBUG - Hping3 stats: {'sent': '14', 'received': '14', 'loss': '0', 'rtt_min': '18.1', 'rtt_avg': '6510.8', 'rtt_max': '13013.2'}
2026-06-03 18:08:43,026 - DEBUG - Executing command: sudo tcpdump -nnr /tmp/ens6_ctest-random-09162687.pcap | wc -l
2026-06-03 18:08:43,044 - DEBUG - STDOUT: 16
2026-06-03 18:08:43,044 - DEBUG - STDERR: reading from file /tmp/ens6_ctest-random-09162687.pcap, link-type EN10MB (Ethernet), snapshot length 262144
2026-06-03 18:08:43,044 - DEBUG - 16 packets are found in tcpdump output file /tmp/ens6_ctest-random-09162687.pcap but expected 1, which is fine
2026-06-03 18:08:43,044 - INFO - 16 packets are found in tcpdump output as expected
2026-06-03 18:08:43,045 - DEBUG - Executing command: sudo kill $(ps -ef|grep tcpdump | grep pcap| awk '{print $2}')
2026-06-03 18:08:45,108 - DEBUG - ['/contrail-test/ens6_ctest-random-09162687.pcap']
2026-06-03 18:08:45,108 - DEBUG - Verifying for packet number 0
2026-06-03 18:08:45,108 - DEBUG - Validated DSCP marking of 10
2026-06-03 18:08:45,108 - DEBUG - Verifying for packet number 1
2026-06-03 18:08:45,108 - DEBUG - Validated DSCP marking of 10
2026-06-03 18:08:45,108 - DEBUG - Verifying for packet number 2
2026-06-03 18:08:45,108 - DEBUG - Validated DSCP marking of 10
2026-06-03 18:08:45,109 - DEBUG - Verifying for packet number 3
2026-06-03 18:08:45,109 - DEBUG - Validated DSCP marking of 10
2026-06-03 18:08:45,109 - INFO - Packet QoS marking validation passed
2026-06-03 18:08:45,109 - DEBUG - Executing command: sudo tcpdump -nnr /tmp/ens6_ctest-random-09162687.pcap | wc -l
2026-06-03 18:08:45,123 - DEBUG - STDOUT: 16
2026-06-03 18:08:45,123 - DEBUG - STDERR: reading from file /tmp/ens6_ctest-random-09162687.pcap, link-type EN10MB (Ethernet), snapshot length 262144
2026-06-03 18:08:45,123 - DEBUG - 16 packets are found in tcpdump output file /tmp/ens6_ctest-random-09162687.pcap but expected 1, which is fine
2026-06-03 18:08:45,123 - INFO - 16 packets are found in tcpdump output as expected
2026-06-03 18:08:45,123 - DEBUG - Executing command: sudo kill $(ps -ef|grep tcpdump | grep pcap| awk '{print $2}')
2026-06-03 18:08:47,179 - DEBUG - ['/contrail-test/ens6_ctest-random-09162687.pcap']
2026-06-03 18:08:47,180 - DEBUG - Verifying for packet number 0
2026-06-03 18:08:47,180 - DEBUG - Interface ens6 does not seem to be a tagged intf. Skipping dot1p check
2026-06-03 18:08:47,180 - DEBUG - Verifying for packet number 1
2026-06-03 18:08:47,180 - DEBUG - Interface ens6 does not seem to be a tagged intf. Skipping dot1p check
2026-06-03 18:08:47,180 - DEBUG - Verifying for packet number 2
2026-06-03 18:08:47,180 - DEBUG - Interface ens6 does not seem to be a tagged intf. Skipping dot1p check
2026-06-03 18:08:47,180 - DEBUG - Verifying for packet number 3
2026-06-03 18:08:47,180 - DEBUG - Interface ens6 does not seem to be a tagged intf. Skipping dot1p check
2026-06-03 18:08:47,180 - INFO - Packet QoS marking validation passed
2026-06-03 18:08:47,306 - INFO - Removing qos-config on VM cc882ef5-6d86-429f-98dc-6647ed6b4138
2026-06-03 18:08:47,409 - INFO - Deleting Qos config ['default-domain', 'ctest-TestQos-60291285', 'ctest-qos_config-64199770'], UUID: 3cce7257-7646-4cc9-98fe-22c01838aa5f
2026-06-03 18:08:47,517 - INFO - Deleting FC ['default-global-system-config', 'default-global-qos-config', 'ctest-fc-31008741'], UUID: feb64e53-1084-4ef6-8277-7fcc4300299a
2026-06-03 18:08:48,875 - DEBUG - No XMPP flaps were noticed during the test
2026-06-03 18:08:48,876 - INFO - --------------------------------------------------------------------------------
2026-06-03 18:08:48,882 - INFO - Deleting VM ctest-TestQos-60291285-14131773
2026-06-03 18:08:49,042 - INFO - Deleting VM ctest-TestQos-60291285-39093332
2026-06-03 18:08:49,161 - INFO - Deleting VM ctest-TestQos-60291285-47129343
2026-06-03 18:08:49,264 - INFO - Deleting VN ctest-vn-35809154
2026-06-03 18:08:49,429 - DEBUG - VN ef1f9761-1462-486f-aec0-2276f022098c still in use: Unable to complete operation on network ef1f9761-1462-486f-aec0-2276f022098c. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-f02d6142-ce49-49c5-ad76-8e6b93134d44']
2026-06-03 18:08:49,429 - WARNING - Deleting VN ctest-vn-35809154 failed..Will retry
2026-06-03 18:08:51,778 - DEBUG - Response for deleting network ()
2026-06-03 18:08:51,778 - INFO - Deleting VN ctest-vn-21114724
2026-06-03 18:08:51,989 - DEBUG - Response for deleting network ()
2026-06-03 18:08:52,810 - INFO - Deleted project: ctest-TestQos-60291285, ID : 1b180239-b2f7-4dcc-8de1-fdbbcbac9da0