2025-10-27 01:06:38,742 - INFO - Domain Default found not creating 2025-10-27 01:06:38,899 - INFO - Project ctest-TestRouters-97884813 not found, creating it 2025-10-27 01:06:39,382 - INFO - Created Project:ctest-TestRouters-97884813, ID : 715de626-8707-43b9-8f95-4001782fa65b 2025-10-27 01:06:41,294 - INFO - ================================================================================ 2025-10-27 01:06:41,294 - INFO - STARTING TEST : test_basic_router_behavior 2025-10-27 01:06:41,294 - INFO - TEST DESCRIPTION : Validate a router is able to route packets between two VNs Create a router Create 2 VNs, and a VM in each Add router port from each VN Ping between VMs 2025-10-27 01:06:41,557 - DEBUG - Nothing to compare xmpp stats {'10.0.0.242': {'10.20.0.17': '0'}} with 2025-10-27 01:06:41,557 - INFO - Initial checks done. Running the testcase now 2025-10-27 01:06:41,557 - INFO - 2025-10-27 01:06:42,869 - DEBUG - Response for create_network : {'network': {'id': 'c5eb385e-7bab-4378-9e6a-46aa5af472a7', 'name': 'ctest-vn1-99709342', 'tenant_id': '715de626870743b98f954001782fa65b', 'project_id': '715de626870743b98f954001782fa65b', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRouters-97884813', 'ctest-vn1-99709342'], 'port_security_enabled': True, 'description': ''}} 2025-10-27 01:06:43,102 - DEBUG - Response for create_subnet : {'subnet': {'id': 'fb8cbc7c-7954-45ad-a589-ae917d0a6b19', 'name': '', 'tenant_id': '715de626870743b98f954001782fa65b', 'network_id': 'c5eb385e-7bab-4378-9e6a-46aa5af472a7', 'ip_version': 4, 'cidr': '63.213.97.0/26', 'allocation_pools': [{'start': '63.213.97.2', 'end': '63.213.97.62'}], 'gateway_ip': '63.213.97.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '63.213.97.2', 'tags': [], 'project_id': '715de626870743b98f954001782fa65b'}} 2025-10-27 01:06:43,123 - INFO - Created VN ctest-vn1-99709342 2025-10-27 01:06:43,179 - DEBUG - VN ctest-vn1-99709342 UUID is c5eb385e-7bab-4378-9e6a-46aa5af472a7 2025-10-27 01:06:43,509 - DEBUG - Response for create_network : {'network': {'id': '1dfd85c4-cbd4-4193-9760-b1cd7a78b5b6', 'name': 'ctest-vn2-25523256', 'tenant_id': '715de626870743b98f954001782fa65b', 'project_id': '715de626870743b98f954001782fa65b', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRouters-97884813', 'ctest-vn2-25523256'], 'port_security_enabled': True, 'description': ''}} 2025-10-27 01:06:43,724 - DEBUG - Response for create_subnet : {'subnet': {'id': '1166fc98-84e6-421d-8c9f-63dbc5cda078', 'name': '', 'tenant_id': '715de626870743b98f954001782fa65b', 'network_id': '1dfd85c4-cbd4-4193-9760-b1cd7a78b5b6', 'ip_version': 4, 'cidr': '157.246.125.64/26', 'allocation_pools': [{'start': '157.246.125.66', 'end': '157.246.125.126'}], 'gateway_ip': '157.246.125.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '157.246.125.66', 'tags': [], 'project_id': '715de626870743b98f954001782fa65b'}} 2025-10-27 01:06:43,744 - INFO - Created VN ctest-vn2-25523256 2025-10-27 01:06:43,795 - DEBUG - VN ctest-vn2-25523256 UUID is 1dfd85c4-cbd4-4193-9760-b1cd7a78b5b6 2025-10-27 01:06:43,971 - DEBUG - Services list from nova: [, , ] 2025-10-27 01:06:45,084 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-4485-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-4485-1) 2025-10-27 01:06:46,488 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-4485-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-4485-1) 2025-10-27 01:06:46,559 - INFO - Adding interface with subnet_id fb8cbc7c-7954-45ad-a589-ae917d0a6b19, port_id None to router d9ec711c-3420-4ebe-8d8b-a9aaef5ea53e 2025-10-27 01:06:46,871 - INFO - Waiting for VM ctest-vn1-vm1-45451865 to be up.. 2025-10-27 01:06:46,986 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-10-27 01:06:52,076 - DEBUG - VM is in ACTIVE state now 2025-10-27 01:06:52,077 - INFO - VM name : ctest-vn1-vm1-45451865 2025-10-27 01:06:52,170 - DEBUG - VM ctest-vn1-vm1-45451865 ID is a7c2b18a-e352-4d1b-9479-09bea7608ccf 2025-10-27 01:06:52,191 - DEBUG - VM ctest-vn1-vm1-45451865 launched on Node cn-jenkins-deploy-platform-ansible-os-4485-1 2025-10-27 01:06:52,272 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-machine/a7c2b18a-e352-4d1b-9479-09bea7608ccf 2025-10-27 01:06:52,613 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-machine/a7c2b18a-e352-4d1b-9479-09bea7608ccf 2025-10-27 01:06:52,644 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-machine-interface/4027200a-2114-46af-877a-9acda83e7cfc 2025-10-27 01:06:55,907 - 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') 2025-10-27 01:06:55,908 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1-vm1-45451865 failed! 2025-10-27 01:06:55,963 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn1-99709342 is 63.213.97.1 and allocation pool is NOT set 2025-10-27 01:06:58,023 - 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=3.38 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.586 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 = 0.586/1.984/3.383/1.398 ms') 2025-10-27 01:06:58,023 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1-vm1-45451865 passed 2025-10-27 01:06:58,179 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-27 01:06:58,179 - DEBUG - Waiting to SSH to VM ctest-vn1-vm1-45451865, IP 63.213.97.3, Port 22 2025-10-27 01:06:58,339 - DEBUG - VM ctest-vn1-vm1-45451865 is ready for SSH connections 2025-10-27 01:06:58,339 - INFO - Waiting for VM ctest-vn2-vm1-38016559 to be up.. 2025-10-27 01:06:58,421 - DEBUG - VM is in ACTIVE state now 2025-10-27 01:06:58,421 - INFO - VM name : ctest-vn2-vm1-38016559 2025-10-27 01:06:58,498 - DEBUG - VM ctest-vn2-vm1-38016559 ID is 72ecf097-e6d0-4c79-a0eb-221d5cbe31bb 2025-10-27 01:06:58,498 - DEBUG - VM ctest-vn2-vm1-38016559 launched on Node cn-jenkins-deploy-platform-ansible-os-4485-1 2025-10-27 01:06:58,582 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-machine/72ecf097-e6d0-4c79-a0eb-221d5cbe31bb 2025-10-27 01:06:58,591 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-machine-interface/67bc9dbf-0437-4352-a583-d1c16de12823 2025-10-27 01:07:01,861 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1026ms') 2025-10-27 01:07:01,861 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:07:01,915 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:07:05,985 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1010ms') 2025-10-27 01:07:05,985 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:07:06,043 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:07:10,112 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1010ms') 2025-10-27 01:07:10,112 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:07:10,167 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:07:14,242 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1011ms') 2025-10-27 01:07:14,242 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:07:14,295 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:07:18,376 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1014ms') 2025-10-27 01:07:18,376 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:07:18,435 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:07:22,496 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1001ms') 2025-10-27 01:07:22,496 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:07:22,551 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:07:26,621 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1014ms') 2025-10-27 01:07:26,621 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:07:26,675 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:07:30,757 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1018ms') 2025-10-27 01:07:30,757 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:07:30,811 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:07:34,883 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1010ms') 2025-10-27 01:07:34,883 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:07:34,943 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:07:39,009 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1005ms') 2025-10-27 01:07:39,009 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:07:39,063 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:07:43,132 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1010ms') 2025-10-27 01:07:43,133 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:07:43,191 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:07:47,260 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1015ms') 2025-10-27 01:07:47,260 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:07:47,315 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:07:51,397 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1019ms') 2025-10-27 01:07:51,397 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:07:51,451 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:07:55,525 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1006ms') 2025-10-27 01:07:55,525 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:07:55,583 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:07:59,645 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1001ms') 2025-10-27 01:07:59,645 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:07:59,699 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:08:03,777 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1018ms') 2025-10-27 01:08:03,777 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:08:03,831 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:08:07,906 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1010ms') 2025-10-27 01:08:07,906 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:08:07,963 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:08:12,029 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1005ms') 2025-10-27 01:08:12,029 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:08:12,083 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:08:16,157 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1018ms') 2025-10-27 01:08:16,157 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:08:16,211 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:08:20,293 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1014ms') 2025-10-27 01:08:20,293 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:08:20,351 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:08:24,420 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1006ms') 2025-10-27 01:08:24,420 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:08:24,479 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:08:28,544 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1006ms') 2025-10-27 01:08:28,544 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:08:28,599 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:08:32,674 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1017ms') 2025-10-27 01:08:32,674 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:08:32,727 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:08:36,796 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1014ms') 2025-10-27 01:08:36,796 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:08:36,851 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:08:40,928 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1018ms') 2025-10-27 01:08:40,928 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:08:40,983 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:08:45,058 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1009ms') 2025-10-27 01:08:45,058 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:08:45,115 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:08:49,181 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1005ms') 2025-10-27 01:08:49,181 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:08:49,235 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:08:53,312 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1015ms') 2025-10-27 01:08:53,312 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:08:53,367 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:08:57,438 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1014ms') 2025-10-27 01:08:57,438 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:08:57,499 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:09:01,572 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1014ms') 2025-10-27 01:09:01,572 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:09:01,627 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:09:05,698 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1014ms') 2025-10-27 01:09:05,698 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:09:05,751 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:09:09,829 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1014ms') 2025-10-27 01:09:09,829 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:09:09,883 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:09:13,951 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1009ms') 2025-10-27 01:09:13,951 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:09:14,007 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:09:18,076 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1010ms') 2025-10-27 01:09:18,076 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:09:18,131 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:09:22,206 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1019ms') 2025-10-27 01:09:22,206 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:09:22,259 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:09:26,341 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1018ms') 2025-10-27 01:09:26,341 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:09:26,399 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:09:30,463 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1006ms') 2025-10-27 01:09:30,463 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:09:30,515 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:09:34,599 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1022ms') 2025-10-27 01:09:34,599 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:09:34,655 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:09:38,717 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1006ms') 2025-10-27 01:09:38,718 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:09:38,775 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:09:42,845 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1014ms') 2025-10-27 01:09:42,845 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:09:42,899 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:09:46,980 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1017ms') 2025-10-27 01:09:46,980 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:09:47,035 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:09:51,105 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1010ms') 2025-10-27 01:09:51,105 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:09:51,159 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:09:55,235 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1015ms') 2025-10-27 01:09:55,235 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:09:55,291 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:09:59,363 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1009ms') 2025-10-27 01:09:59,363 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:09:59,419 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:10:03,489 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1014ms') 2025-10-27 01:10:03,489 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:10:03,543 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:10:07,619 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1010ms') 2025-10-27 01:10:07,620 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:10:07,675 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:10:11,749 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1010ms') 2025-10-27 01:10:11,749 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:10:11,807 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:10:15,874 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1003ms') 2025-10-27 01:10:15,874 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:10:15,927 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:10:19,996 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1011ms') 2025-10-27 01:10:19,996 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:10:20,051 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:10:24,132 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1018ms') 2025-10-27 01:10:24,132 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:10:24,187 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:10:28,255 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1010ms') 2025-10-27 01:10:28,255 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:10:28,315 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:10:31,620 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1034ms') 2025-10-27 01:10:31,620 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:10:31,675 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:10:35,745 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1011ms') 2025-10-27 01:10:35,745 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:10:35,799 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:10:39,877 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1014ms') 2025-10-27 01:10:39,877 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:10:39,931 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:10:44,006 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1006ms') 2025-10-27 01:10:44,006 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:10:44,059 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:10:48,131 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1002ms') 2025-10-27 01:10:48,132 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:10:48,191 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:10:52,256 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1007ms') 2025-10-27 01:10:52,256 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:10:52,311 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:10:56,381 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1010ms') 2025-10-27 01:10:56,381 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:10:56,443 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:11:00,516 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1014ms') 2025-10-27 01:11:00,516 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:11:00,571 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:11:04,636 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1010ms') 2025-10-27 01:11:04,636 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:11:04,691 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:11:08,770 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1014ms') 2025-10-27 01:11:08,770 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:11:08,827 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:11:12,901 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1013ms') 2025-10-27 01:11:12,902 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:11:12,955 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:11:17,024 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1011ms') 2025-10-27 01:11:17,024 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:11:17,083 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:11:21,155 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1010ms') 2025-10-27 01:11:21,155 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:11:21,215 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:11:25,277 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1006ms') 2025-10-27 01:11:25,278 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:11:25,335 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:11:29,407 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1013ms') 2025-10-27 01:11:29,407 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:11:29,463 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:11:33,535 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1014ms') 2025-10-27 01:11:33,535 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:11:33,591 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:11:37,667 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1014ms') 2025-10-27 01:11:37,667 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:11:37,727 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:11:41,791 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1005ms') 2025-10-27 01:11:41,791 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:11:41,851 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:11:45,923 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1010ms') 2025-10-27 01:11:45,923 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:11:45,999 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:11:50,080 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1021ms') 2025-10-27 01:11:50,080 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:11:50,139 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:11:54,208 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1010ms') 2025-10-27 01:11:54,208 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:11:54,263 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:11:58,336 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1014ms') 2025-10-27 01:11:58,336 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:11:58,391 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:12:02,466 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1015ms') 2025-10-27 01:12:02,466 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:12:02,523 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:12:06,591 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1010ms') 2025-10-27 01:12:06,591 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:12:06,647 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:12:10,723 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1014ms') 2025-10-27 01:12:10,723 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:12:10,779 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:12:14,849 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1009ms') 2025-10-27 01:12:14,849 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:12:14,903 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:12:18,976 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1014ms') 2025-10-27 01:12:18,976 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:12:19,031 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:12:23,102 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1014ms') 2025-10-27 01:12:23,102 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:12:23,159 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:12:27,230 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1014ms') 2025-10-27 01:12:27,230 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:12:27,283 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:12:31,366 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1018ms') 2025-10-27 01:12:31,366 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:12:31,423 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:12:35,486 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1006ms') 2025-10-27 01:12:35,486 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:12:35,543 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:12:39,613 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1014ms') 2025-10-27 01:12:39,613 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:12:39,667 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:12:43,742 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1018ms') 2025-10-27 01:12:43,742 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:12:43,795 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:12:47,877 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1014ms') 2025-10-27 01:12:47,877 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:12:47,931 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:12:51,999 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1008ms') 2025-10-27 01:12:51,999 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:12:52,055 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:12:56,125 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1014ms') 2025-10-27 01:12:56,125 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:12:56,183 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:13:00,252 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1011ms') 2025-10-27 01:13:00,252 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:13:00,307 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:13:04,390 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1018ms') 2025-10-27 01:13:04,390 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:13:04,443 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:13:08,510 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1004ms') 2025-10-27 01:13:08,510 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:13:08,563 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:13:12,643 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1018ms') 2025-10-27 01:13:12,643 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:13:12,699 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:13:16,764 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1011ms') 2025-10-27 01:13:16,764 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:13:16,823 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:13:20,901 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1014ms') 2025-10-27 01:13:20,901 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:13:20,955 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:13:25,028 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1010ms') 2025-10-27 01:13:25,028 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:13:25,083 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:13:29,149 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1010ms') 2025-10-27 01:13:29,149 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:13:29,207 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:13:33,285 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1018ms') 2025-10-27 01:13:33,285 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:13:33,343 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:13:37,410 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1006ms') 2025-10-27 01:13:37,410 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:13:37,467 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:13:41,537 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1009ms') 2025-10-27 01:13:41,537 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:13:41,591 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:13:45,669 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1018ms') 2025-10-27 01:13:45,669 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:13:45,723 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:13:49,797 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1010ms') 2025-10-27 01:13:49,797 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:13:49,851 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:13:53,925 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1010ms') 2025-10-27 01:13:53,925 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:13:53,979 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:13:58,052 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1010ms') 2025-10-27 01:13:58,052 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-38016559 failed! 2025-10-27 01:13:58,107 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97884813:ctest-vn2-25523256 is 157.246.125.65 and allocation pool is NOT set 2025-10-27 01:13:58,107 - WARNING - VM metadata IP is not 169.254.x.x 2025-10-27 01:13:58,303 - DEBUG - VM ctest-vn2-vm1-38016559 does not seem to be fully up 2025-10-27 01:13:58,788 - DEBUG - Console output: [ 0.000000] Initializing cgroup subsys cpuset [ 0.000000] Initializing cgroup subsys cpu [ 0.000000] Linux version 3.0.0-12-virtual (buildd@crested) (gcc version 4.6.1 (Ubuntu/Linaro 4.6.1-9ubuntu3) ) #20-Ubuntu SMP Fri Oct 7 18:19:02 UTC 2011 (Ubuntu 3.0.0-12.20-virtual 3.0.4) [ 0.000000] Command line: LABEL=cirros-rootfs ro console=tty0 console=ttyS0 console=hvc0 [ 0.000000] KERNEL supported cpus: [ 0.000000] Intel GenuineIntel [ 0.000000] AMD AuthenticAMD [ 0.000000] Centaur CentaurHauls [ 0.000000] BIOS-provided physical RAM map: [ 0.000000] BIOS-e820: 0000000000000000 - 000000000009fc00 (usable) [ 0.000000] BIOS-e820: 000000000009fc00 - 00000000000a0000 (reserved) [ 0.000000] BIOS-e820: 00000000000f0000 - 0000000000100000 (reserved) [ 0.000000] BIOS-e820: 0000000000100000 - 000000003ffdb000 (usable) [ 0.000000] BIOS-e820: 000000003ffdb000 - 0000000040000000 (reserved) [ 0.000000] BIOS-e820: 00000000fffc0000 - 0000000100000000 (reserved) [ 0.000000] NX (Execute Disable) protection: active [ 0.000000] DMI 2.8 present. [ 0.000000] No AGP bridge found [ 0.000000] last_pfn = 0x3ffdb max_arch_pfn = 0x400000000 [ 0.000000] x86 PAT enabled: cpu 0, old 0x7040600070406, new 0x7010600070106 [ 0.000000] found SMP MP-table at [ffff8800000f5b30] f5b30 [ 0.000000] Using GB pages for direct mapping [ 0.000000] init_memory_mapping: 0000000000000000-000000003ffdb000 [ 0.000000] RAMDISK: 37dfc000 - 37ff0000 [ 0.000000] ACPI: RSDP 00000000000f5900 00014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 000000003ffe17da 00030 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 000000003ffe16c6 00074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 000000003ffe0040 01686 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 000000003ffe0000 00040 [ 0.000000] ACPI: APIC 000000003ffe173a 00078 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 000000003ffe17b2 00028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at 0000000000000000-000000003ffdb000 [ 0.000000] Initmem setup node 0 0000000000000000-000000003ffdb000 [ 0.000000] NODE_DATA [000000003ffd3000 - 000000003ffd7fff] [ 0.000000] Zone PFN ranges: [ 0.000000] DMA 0x00000010 -> 0x00001000 [ 0.000000] DMA32 0x00001000 -> 0x00100000 [ 0.000000] Normal empty [ 0.000000] Movable zone start PFN for each node [ 0.000000] early_node_map[2] active PFN ranges [ 0.000000] 0: 0x00000010 -> 0x0000009f [ 0.000000] 0: 0x00000100 -> 0x0003ffdb [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC (acpi_id[0x00] lapic_id[0x00] enabled) [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] ACPI: IOAPIC (id[0x00] address[0xfec00000] gsi_base[0]) [ 0.000000] IOAPIC[0]: apic_id 0, version 32, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] SMP: Allowing 1 CPUs, 0 hotplug CPUs [ 0.000000] PM: Registered nosave memory: 000000000009f000 - 00000000000a0000 [ 0.000000] PM: Registered nosave memory: 00000000000a0000 - 00000000000f0000 [ 0.000000] PM: Registered nosave memory: 00000000000f0000 - 0000000000100000 [ 0.000000] Allocating PCI resources starting at 40000000 (gap: 40000000:bffc0000) [ 0.000000] Booting paravirtualized kernel on bare hardware [ 0.000000] setup_percpu: NR_CPUS:64 nr_cpumask_bits:64 nr_cpu_ids:1 nr_node_ids:1 [ 0.000000] PERCPU: Embedded 27 pages/cpu @ffff88003fc00000 s79296 r8192 d23104 u2097152 [ 0.000000] Built 1 zonelists in Node order, mobility grouping on. Total pages: 258405 [ 0.000000] Policy zone: DMA32 [ 0.000000] Kernel command line: LABEL=cirros-rootfs ro console=tty0 console=ttyS0 console=hvc0 [ 0.000000] PID hash table entries: 4096 (order: 3, 32768 bytes) [ 0.000000] xsave/xrstor: enabled xstate_bv 0x3, cntxt size 0x240 [ 0.000000] Checking aperture... [ 0.000000] No AGP bridge found [ 0.000000] Memory: 1015852k/1048428k available (6206k kernel code, 452k absent, 32124k reserved, 6907k data, 900k init) [ 0.000000] SLUB: Genslabs=15, HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 [ 0.000000] Hierarchical RCU implementation. [ 0.000000] RCU dyntick-idle grace-period acceleration is enabled. [ 0.000000] NR_IRQS:4352 nr_irqs:256 16 [ 0.000000] Console: colour VGA+ 80x25 [ 0.000000] console [tty0] enabled [ 0.000000] console [ttyS0] enabled [ 0.000000] allocated 8388608 bytes of page_cgroup [ 0.000000] please try 'cgroup_disable=memory' option if you don't want memory cgroups [ 0.000000] Fast TSC calibration using PIT [ 0.000000] Detected 1994.836 MHz processor. [ 0.017028] Calibrating delay loop (skipped), value calculated using timer frequency.. 3989.67 BogoMIPS (lpj=7979344) [ 0.018089] pid_max: default: 32768 minimum: 301 [ 0.022778] Security Framework initialized [ 0.028656] AppArmor: AppArmor initialized [ 0.028956] Yama: becoming mindful. [ 0.036578] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes) [ 0.039271] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes) [ 0.040889] Mount-cache hash table entries: 256 [ 0.052774] Initializing cgroup subsys cpuacct [ 0.053269] Initializing cgroup subsys memory [ 0.054325] Initializing cgroup subsys devices [ 0.054659] Initializing cgroup subsys freezer [ 0.054924] Initializing cgroup subsys net_cls [ 0.055183] Initializing cgroup subsys blkio [ 0.055463] Initializing cgroup subsys perf_event [ 0.061167] mce: CPU supports 10 MCE banks [ 0.062964] using AMD E400 aware idle routine [ 0.066504] SMP alternatives: switching to UP code [ 0.314438] Freeing SMP alternatives: 24k freed [ 0.315313] ACPI: Core revision 20110413 [ 0.346677] ftrace: allocating 26075 entries in 103 pages [ 0.378228] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.419133] CPU0: AMD Opteron 23xx (Gen 3 Class Opteron) stepping 03 [ 0.420025] Performance Events: Broken PMU hardware detected, using software events only. [ 0.430004] Brought up 1 CPUs [ 0.430334] Total of 1 processors activated (3989.67 BogoMIPS). [ 0.440904] devtmpfs: initialized [ 0.466759] print_constraints: dummy: [ 0.467755] Time: 1:06:49 Date: 10/27/25 [ 0.469408] NET: Registered protocol family 16 [ 0.476101] Extended Config Space enabled on 0 nodes [ 0.477034] ACPI: bus type pci registered [ 0.479813] PCI: Using configuration type 1 for base access [ 0.480092] PCI: Using configuration type 1 for extended access [ 0.498001] bio: create slab at 0 [ 0.529522] ACPI: Interpreter enabled [ 0.529810] ACPI: (supports S0 S5) [ 0.530733] ACPI: Using IOAPIC for interrupt routing [ 0.567502] ACPI: No dock devices found. [ 0.567808] HEST: Table not found. [ 0.568146] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.571131] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.573683] pci_root PNP0A03:00: host bridge window [io 0x0000-0x0cf7] [ 0.573987] pci_root PNP0A03:00: host bridge window [io 0x0d00-0xffff] [ 0.574307] pci_root PNP0A03:00: host bridge window [mem 0x000a0000-0x000bffff] [ 0.574597] pci_root PNP0A03:00: host bridge window [mem 0x40000000-0xfebfffff] [ 0.574876] pci_root PNP0A03:00: host bridge window [mem 0x100000000-0x17fffffff] [ 0.588799] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.589223] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 1.189662] pci0000:00: Requesting ACPI _OSC control (0x1d) [ 1.190175] pci0000:00: ACPI _OSC request failed (AE_NOT_FOUND), returned control mask: 0x1d [ 1.190513] ACPI _OSC control for PCIe not granted, disabling ASPM [ 1.222838] ACPI: PCI Interrupt Link [LNKA] (IRQs 5 *10 11) [ 1.224018] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 *10 11) [ 1.224772] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 10 *11) [ 1.225544] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 10 *11) [ 1.226082] ACPI: PCI Interrupt Link [LNKS] (IRQs *9) [ 1.232076] vgaarb: device added: PCI:0000:00:02.0,decodes=io+mem,owns=io+mem,locks=none [ 1.232197] vgaarb: loaded [ 1.232368] vgaarb: bridge control possible 0000:00:02.0 [ 1.237050] SCSI subsystem initialized [ 1.239572] usbcore: registered new interface driver usbfs [ 1.240315] usbcore: registered new interface driver hub [ 1.240995] usbcore: registered new device driver usb [ 1.243502] PCI: Using ACPI for IRQ routing [ 1.252554] NetLabel: Initializing [ 1.252772] NetLabel: domain hash size = 128 [ 1.252955] NetLabel: protocols = UNLABELED CIPSOv4 [ 1.254494] NetLabel: unlabeled traffic allowed by default [ 1.321376] AppArmor: AppArmor Filesystem Enabled [ 1.322541] pnp: PnP ACPI init [ 1.322904] ACPI: bus type pnp registered [ 1.331391] pnp: PnP ACPI: found 9 devices [ 1.331668] ACPI: ACPI bus type pnp unregistered [ 1.355793] Switching to clocksource acpi_pm [ 1.358994] NET: Registered protocol family 2 [ 1.360664] Switched to NOHz mode on CPU #0 [ 1.365109] IP route cache hash table entries: 32768 (order: 6, 262144 bytes) [ 1.375109] TCP established hash table entries: 131072 (order: 9, 2097152 bytes) [ 1.380088] TCP bind hash table entries: 65536 (order: 8, 1048576 bytes) [ 1.382898] TCP: Hash tables configured (established 131072 bind 65536) [ 1.383271] TCP reno registered [ 1.383586] UDP hash table entries: 512 (order: 2, 16384 bytes) [ 1.384154] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes) [ 1.386472] NET: Registered protocol family 1 [ 1.387039] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 1.387369] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 1.387859] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 1.401896] Trying to unpack rootfs image as initramfs... [ 1.425171] audit: initializing netlink socket (disabled) [ 1.426328] type=2000 audit(1761527210.420:1): initialized [ 1.553073] HugeTLB registered 2 MB page size, pre-allocated 0 pages [ 1.597481] VFS: Disk quotas dquot_6.5.2 [ 1.598285] Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 1.617415] fuse init (API version 7.16) [ 1.619022] msgmni has been set to 1984 [ 1.641296] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 253) [ 1.648669] io scheduler noop registered [ 1.648956] io scheduler deadline registered (default) [ 1.649629] io scheduler cfq registered [ 1.651712] pci_hotplug: PCI Hot Plug PCI Core version: 0.5 [ 1.653565] pciehp: PCI Express Hot Plug Controller Driver version: 0.4 [ 1.657769] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 1.658865] ACPI: Power Button [PWRF] [ 1.665860] ERST: Table is not found! [ 1.677082] ACPI: PCI Interrupt Link [LNKC] enabled at IRQ 11 [ 1.677623] virtio-pci 0000:00:03.0: PCI INT A -> Link[LNKC] -> GSI 11 (level, high) -> IRQ 11 [ 1.684949] ACPI: PCI Interrupt Link [LNKD] enabled at IRQ 10 [ 1.685287] virtio-pci 0000:00:04.0: PCI INT A -> Link[LNKD] -> GSI 10 (level, high) -> IRQ 10 [ 1.686738] ACPI: PCI Interrupt Link [LNKA] enabled at IRQ 10 [ 1.687010] virtio-pci 0000:00:05.0: PCI INT A -> Link[LNKA] -> GSI 10 (level, high) -> IRQ 10 [ 1.693543] ACPI: PCI Interrupt Link [LNKB] enabled at IRQ 11 [ 1.693802] virtio-pci 0000:00:06.0: PCI INT A -> Link[LNKB] -> GSI 11 (level, high) -> IRQ 11 [ 1.695128] virtio-pci 0000:00:07.0: PCI INT A -> Link[LNKC] -> GSI 11 (level, high) -> IRQ 11 [ 1.697663] Serial: 8250/16550 driver, 32 ports, IRQ sharing enabled [ 1.721315] serial8250: ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A [ 1.757229] 00:01: ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A [ 1.760353] Linux agpgart interface v0.103 [ 1.775584] brd: module loaded [ 1.781678] loop: module loaded [ 1.906928] vda: vda1 [ 1.912832] Freeing initrd memory: 2000k freed [ 1.935898] scsi0 : ata_piix [ 1.937760] scsi1 : ata_piix [ 1.938559] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc100 irq 14 [ 1.938870] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc108 irq 15 [ 1.944500] Fixed MDIO Bus: probed [ 1.945288] PPP generic driver version 2.4.2 [ 1.946340] tun: Universal TUN/TAP device driver, 1.6 [ 1.946572] tun: (C) 1999-2004 Max Krasnyansky [ 1.954634] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver [ 1.955781] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver [ 1.956310] uhci_hcd: USB Universal Host Controller Interface driver [ 1.956842] uhci_hcd 0000:00:01.2: PCI INT D -> Link[LNKD] -> GSI 10 (level, high) -> IRQ 10 [ 1.957563] uhci_hcd 0000:00:01.2: UHCI Host Controller [ 1.958806] uhci_hcd 0000:00:01.2: new USB bus registered, assigned bus number 1 [ 1.960153] uhci_hcd 0000:00:01.2: irq 10, io base 0x0000c080 [ 1.969819] hub 1-0:1.0: USB hub found [ 1.970573] hub 1-0:1.0: 2 ports detected [ 1.974506] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 1.977827] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 1.978279] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 1.979526] mousedev: PS/2 mouse device common for all mice [ 1.980998] rtc_cmos 00:05: RTC can wake from S4 [ 1.983632] rtc_cmos 00:05: rtc core: registered rtc_cmos as rtc0 [ 1.984449] rtc0: alarms up to one day, y3k, 242 bytes nvram [ 1.985615] device-mapper: uevent: version 1.0.3 [ 1.986747] device-mapper: ioctl: 4.20.0-ioctl (2011-02-02) initialised: dm-devel@redhat.com [ 1.987888] cpuidle: using governor ladder [ 1.988265] cpuidle: using governor menu [ 1.988549] EFI Variables Facility v0.08 2004-May-17 [ 1.991233] TCP cubic registered [ 1.992700] NET: Registered protocol family 10 [ 2.003297] NET: Registered protocol family 17 [ 2.003999] Registering the dns_resolver key type [ 2.007051] registered taskstats version 1 [ 2.016238] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 2.207205] Magic number: 13:222:105 [ 2.207694] usbmon usbmon1: hash matches [ 2.208838] rtc_cmos 00:05: setting system clock to 2025-10-27 01:06:51 UTC (1761527211) [ 2.211327] BIOS EDD facility v0.16 2004-Jun-25, 0 devices found [ 2.211632] EDD information not available. [ 2.226050] Freeing unused kernel memory: 900k freed [ 2.267388] Write protecting the kernel read-only data: 12288k [ 2.306936] Freeing unused kernel memory: 1968k freed [ 2.337191] Freeing unused kernel memory: 1368k freed [ 2.398692] usb 1-1: new full speed USB device number 2 using uhci_hcd info: initramfs: up at 2.46 GROWROOT: CHANGED: partition=1 start=16065 old: size=64260 end=80325 new: size=2072385,end=2088450 info: initramfs loading root from /dev/vda1 info: /etc/init.d/rc.sysinit: up at 4.14 [ 4.177389] EXT3-fs (vda1): warning: checktime reached, running e2fsck is recommended Starting logging: OK Initializing random number generator... done. Starting network... udhcpc (v1.18.5) started Sending discover... Sending select for 157.246.125.67... Lease of 157.246.125.67 obtained, lease time 268435455 deleting routers route: SIOCDELRT: No such process adding dns 157.246.125.66 cloud-setup: checking http://169.254.169.254/2009-04-04/meta-data/instance-id cloud-setup: successful after 1/30 tries: up 6.50. iid=i-00000007 Starting dropbear sshd: generating rsa key... generating dsa key... OK ===== cloud-final: system completely up in 7.96 seconds ==== 2025-10-27 01:13:58,788 - ERROR - VM ctest-vn2-vm1-38016559 does not seem to be fully up. Check logs 2025-10-27 01:13:58,913 - DEBUG - [ 0.000000] Initializing cgroup subsys cpuset [ 0.000000] Initializing cgroup subsys cpu [ 0.000000] Linux version 3.0.0-12-virtual (buildd@crested) (gcc version 4.6.1 (Ubuntu/Linaro 4.6.1-9ubuntu3) ) #20-Ubuntu SMP Fri Oct 7 18:19:02 UTC 2011 (Ubuntu 3.0.0-12.20-virtual 3.0.4) [ 0.000000] Command line: LABEL=cirros-rootfs ro console=tty0 console=ttyS0 console=hvc0 [ 0.000000] KERNEL supported cpus: [ 0.000000] Intel GenuineIntel [ 0.000000] AMD AuthenticAMD [ 0.000000] Centaur CentaurHauls [ 0.000000] BIOS-provided physical RAM map: [ 0.000000] BIOS-e820: 0000000000000000 - 000000000009fc00 (usable) [ 0.000000] BIOS-e820: 000000000009fc00 - 00000000000a0000 (reserved) [ 0.000000] BIOS-e820: 00000000000f0000 - 0000000000100000 (reserved) [ 0.000000] BIOS-e820: 0000000000100000 - 000000003ffdb000 (usable) [ 0.000000] BIOS-e820: 000000003ffdb000 - 0000000040000000 (reserved) [ 0.000000] BIOS-e820: 00000000fffc0000 - 0000000100000000 (reserved) [ 0.000000] NX (Execute Disable) protection: active [ 0.000000] DMI 2.8 present. [ 0.000000] No AGP bridge found [ 0.000000] last_pfn = 0x3ffdb max_arch_pfn = 0x400000000 [ 0.000000] x86 PAT enabled: cpu 0, old 0x7040600070406, new 0x7010600070106 [ 0.000000] found SMP MP-table at [ffff8800000f5b30] f5b30 [ 0.000000] Using GB pages for direct mapping [ 0.000000] init_memory_mapping: 0000000000000000-000000003ffdb000 [ 0.000000] RAMDISK: 37dfc000 - 37ff0000 [ 0.000000] ACPI: RSDP 00000000000f5900 00014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 000000003ffe17da 00030 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 000000003ffe16c6 00074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 000000003ffe0040 01686 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 000000003ffe0000 00040 [ 0.000000] ACPI: APIC 000000003ffe173a 00078 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 000000003ffe17b2 00028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at 0000000000000000-000000003ffdb000 [ 0.000000] Initmem setup node 0 0000000000000000-000000003ffdb000 [ 0.000000] NODE_DATA [000000003ffd3000 - 000000003ffd7fff] [ 0.000000] Zone PFN ranges: [ 0.000000] DMA 0x00000010 -> 0x00001000 [ 0.000000] DMA32 0x00001000 -> 0x00100000 [ 0.000000] Normal empty [ 0.000000] Movable zone start PFN for each node [ 0.000000] early_node_map[2] active PFN ranges [ 0.000000] 0: 0x00000010 -> 0x0000009f [ 0.000000] 0: 0x00000100 -> 0x0003ffdb [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC (acpi_id[0x00] lapic_id[0x00] enabled) [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] ACPI: IOAPIC (id[0x00] address[0xfec00000] gsi_base[0]) [ 0.000000] IOAPIC[0]: apic_id 0, version 32, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] SMP: Allowing 1 CPUs, 0 hotplug CPUs [ 0.000000] PM: Registered nosave memory: 000000000009f000 - 00000000000a0000 [ 0.000000] PM: Registered nosave memory: 00000000000a0000 - 00000000000f0000 [ 0.000000] PM: Registered nosave memory: 00000000000f0000 - 0000000000100000 [ 0.000000] Allocating PCI resources starting at 40000000 (gap: 40000000:bffc0000) [ 0.000000] Booting paravirtualized kernel on bare hardware [ 0.000000] setup_percpu: NR_CPUS:64 nr_cpumask_bits:64 nr_cpu_ids:1 nr_node_ids:1 [ 0.000000] PERCPU: Embedded 27 pages/cpu @ffff88003fc00000 s79296 r8192 d23104 u2097152 [ 0.000000] Built 1 zonelists in Node order, mobility grouping on. Total pages: 258405 [ 0.000000] Policy zone: DMA32 [ 0.000000] Kernel command line: LABEL=cirros-rootfs ro console=tty0 console=ttyS0 console=hvc0 [ 0.000000] PID hash table entries: 4096 (order: 3, 32768 bytes) [ 0.000000] xsave/xrstor: enabled xstate_bv 0x3, cntxt size 0x240 [ 0.000000] Checking aperture... [ 0.000000] No AGP bridge found [ 0.000000] Memory: 1015852k/1048428k available (6206k kernel code, 452k absent, 32124k reserved, 6907k data, 900k init) [ 0.000000] SLUB: Genslabs=15, HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 [ 0.000000] Hierarchical RCU implementation. [ 0.000000] RCU dyntick-idle grace-period acceleration is enabled. [ 0.000000] NR_IRQS:4352 nr_irqs:256 16 [ 0.000000] Console: colour VGA+ 80x25 [ 0.000000] console [tty0] enabled [ 0.000000] console [ttyS0] enabled [ 0.000000] allocated 8388608 bytes of page_cgroup [ 0.000000] please try 'cgroup_disable=memory' option if you don't want memory cgroups [ 0.000000] Fast TSC calibration using PIT [ 0.000000] Detected 1994.836 MHz processor. [ 0.017028] Calibrating delay loop (skipped), value calculated using timer frequency.. 3989.67 BogoMIPS (lpj=7979344) [ 0.018089] pid_max: default: 32768 minimum: 301 [ 0.022778] Security Framework initialized [ 0.028656] AppArmor: AppArmor initialized [ 0.028956] Yama: becoming mindful. [ 0.036578] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes) [ 0.039271] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes) [ 0.040889] Mount-cache hash table entries: 256 [ 0.052774] Initializing cgroup subsys cpuacct [ 0.053269] Initializing cgroup subsys memory [ 0.054325] Initializing cgroup subsys devices [ 0.054659] Initializing cgroup subsys freezer [ 0.054924] Initializing cgroup subsys net_cls [ 0.055183] Initializing cgroup subsys blkio [ 0.055463] Initializing cgroup subsys perf_event [ 0.061167] mce: CPU supports 10 MCE banks [ 0.062964] using AMD E400 aware idle routine [ 0.066504] SMP alternatives: switching to UP code [ 0.314438] Freeing SMP alternatives: 24k freed [ 0.315313] ACPI: Core revision 20110413 [ 0.346677] ftrace: allocating 26075 entries in 103 pages [ 0.378228] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.419133] CPU0: AMD Opteron 23xx (Gen 3 Class Opteron) stepping 03 [ 0.420025] Performance Events: Broken PMU hardware detected, using software events only. [ 0.430004] Brought up 1 CPUs [ 0.430334] Total of 1 processors activated (3989.67 BogoMIPS). [ 0.440904] devtmpfs: initialized [ 0.466759] print_constraints: dummy: [ 0.467755] Time: 1:06:49 Date: 10/27/25 [ 0.469408] NET: Registered protocol family 16 [ 0.476101] Extended Config Space enabled on 0 nodes [ 0.477034] ACPI: bus type pci registered [ 0.479813] PCI: Using configuration type 1 for base access [ 0.480092] PCI: Using configuration type 1 for extended access [ 0.498001] bio: create slab at 0 [ 0.529522] ACPI: Interpreter enabled [ 0.529810] ACPI: (supports S0 S5) [ 0.530733] ACPI: Using IOAPIC for interrupt routing [ 0.567502] ACPI: No dock devices found. [ 0.567808] HEST: Table not found. [ 0.568146] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.571131] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.573683] pci_root PNP0A03:00: host bridge window [io 0x0000-0x0cf7] [ 0.573987] pci_root PNP0A03:00: host bridge window [io 0x0d00-0xffff] [ 0.574307] pci_root PNP0A03:00: host bridge window [mem 0x000a0000-0x000bffff] [ 0.574597] pci_root PNP0A03:00: host bridge window [mem 0x40000000-0xfebfffff] [ 0.574876] pci_root PNP0A03:00: host bridge window [mem 0x100000000-0x17fffffff] [ 0.588799] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.589223] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 1.189662] pci0000:00: Requesting ACPI _OSC control (0x1d) [ 1.190175] pci0000:00: ACPI _OSC request failed (AE_NOT_FOUND), returned control mask: 0x1d [ 1.190513] ACPI _OSC control for PCIe not granted, disabling ASPM [ 1.222838] ACPI: PCI Interrupt Link [LNKA] (IRQs 5 *10 11) [ 1.224018] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 *10 11) [ 1.224772] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 10 *11) [ 1.225544] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 10 *11) [ 1.226082] ACPI: PCI Interrupt Link [LNKS] (IRQs *9) [ 1.232076] vgaarb: device added: PCI:0000:00:02.0,decodes=io+mem,owns=io+mem,locks=none [ 1.232197] vgaarb: loaded [ 1.232368] vgaarb: bridge control possible 0000:00:02.0 [ 1.237050] SCSI subsystem initialized [ 1.239572] usbcore: registered new interface driver usbfs [ 1.240315] usbcore: registered new interface driver hub [ 1.240995] usbcore: registered new device driver usb [ 1.243502] PCI: Using ACPI for IRQ routing [ 1.252554] NetLabel: Initializing [ 1.252772] NetLabel: domain hash size = 128 [ 1.252955] NetLabel: protocols = UNLABELED CIPSOv4 [ 1.254494] NetLabel: unlabeled traffic allowed by default [ 1.321376] AppArmor: AppArmor Filesystem Enabled [ 1.322541] pnp: PnP ACPI init [ 1.322904] ACPI: bus type pnp registered [ 1.331391] pnp: PnP ACPI: found 9 devices [ 1.331668] ACPI: ACPI bus type pnp unregistered [ 1.355793] Switching to clocksource acpi_pm [ 1.358994] NET: Registered protocol family 2 [ 1.360664] Switched to NOHz mode on CPU #0 [ 1.365109] IP route cache hash table entries: 32768 (order: 6, 262144 bytes) [ 1.375109] TCP established hash table entries: 131072 (order: 9, 2097152 bytes) [ 1.380088] TCP bind hash table entries: 65536 (order: 8, 1048576 bytes) [ 1.382898] TCP: Hash tables configured (established 131072 bind 65536) [ 1.383271] TCP reno registered [ 1.383586] UDP hash table entries: 512 (order: 2, 16384 bytes) [ 1.384154] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes) [ 1.386472] NET: Registered protocol family 1 [ 1.387039] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 1.387369] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 1.387859] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 1.401896] Trying to unpack rootfs image as initramfs... [ 1.425171] audit: initializing netlink socket (disabled) [ 1.426328] type=2000 audit(1761527210.420:1): initialized [ 1.553073] HugeTLB registered 2 MB page size, pre-allocated 0 pages [ 1.597481] VFS: Disk quotas dquot_6.5.2 [ 1.598285] Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 1.617415] fuse init (API version 7.16) [ 1.619022] msgmni has been set to 1984 [ 1.641296] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 253) [ 1.648669] io scheduler noop registered [ 1.648956] io scheduler deadline registered (default) [ 1.649629] io scheduler cfq registered [ 1.651712] pci_hotplug: PCI Hot Plug PCI Core version: 0.5 [ 1.653565] pciehp: PCI Express Hot Plug Controller Driver version: 0.4 [ 1.657769] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 1.658865] ACPI: Power Button [PWRF] [ 1.665860] ERST: Table is not found! [ 1.677082] ACPI: PCI Interrupt Link [LNKC] enabled at IRQ 11 [ 1.677623] virtio-pci 0000:00:03.0: PCI INT A -> Link[LNKC] -> GSI 11 (level, high) -> IRQ 11 [ 1.684949] ACPI: PCI Interrupt Link [LNKD] enabled at IRQ 10 [ 1.685287] virtio-pci 0000:00:04.0: PCI INT A -> Link[LNKD] -> GSI 10 (level, high) -> IRQ 10 [ 1.686738] ACPI: PCI Interrupt Link [LNKA] enabled at IRQ 10 [ 1.687010] virtio-pci 0000:00:05.0: PCI INT A -> Link[LNKA] -> GSI 10 (level, high) -> IRQ 10 [ 1.693543] ACPI: PCI Interrupt Link [LNKB] enabled at IRQ 11 [ 1.693802] virtio-pci 0000:00:06.0: PCI INT A -> Link[LNKB] -> GSI 11 (level, high) -> IRQ 11 [ 1.695128] virtio-pci 0000:00:07.0: PCI INT A -> Link[LNKC] -> GSI 11 (level, high) -> IRQ 11 [ 1.697663] Serial: 8250/16550 driver, 32 ports, IRQ sharing enabled [ 1.721315] serial8250: ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A [ 1.757229] 00:01: ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A [ 1.760353] Linux agpgart interface v0.103 [ 1.775584] brd: module loaded [ 1.781678] loop: module loaded [ 1.906928] vda: vda1 [ 1.912832] Freeing initrd memory: 2000k freed [ 1.935898] scsi0 : ata_piix [ 1.937760] scsi1 : ata_piix [ 1.938559] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc100 irq 14 [ 1.938870] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc108 irq 15 [ 1.944500] Fixed MDIO Bus: probed [ 1.945288] PPP generic driver version 2.4.2 [ 1.946340] tun: Universal TUN/TAP device driver, 1.6 [ 1.946572] tun: (C) 1999-2004 Max Krasnyansky [ 1.954634] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver [ 1.955781] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver [ 1.956310] uhci_hcd: USB Universal Host Controller Interface driver [ 1.956842] uhci_hcd 0000:00:01.2: PCI INT D -> Link[LNKD] -> GSI 10 (level, high) -> IRQ 10 [ 1.957563] uhci_hcd 0000:00:01.2: UHCI Host Controller [ 1.958806] uhci_hcd 0000:00:01.2: new USB bus registered, assigned bus number 1 [ 1.960153] uhci_hcd 0000:00:01.2: irq 10, io base 0x0000c080 [ 1.969819] hub 1-0:1.0: USB hub found [ 1.970573] hub 1-0:1.0: 2 ports detected [ 1.974506] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 1.977827] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 1.978279] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 1.979526] mousedev: PS/2 mouse device common for all mice [ 1.980998] rtc_cmos 00:05: RTC can wake from S4 [ 1.983632] rtc_cmos 00:05: rtc core: registered rtc_cmos as rtc0 [ 1.984449] rtc0: alarms up to one day, y3k, 242 bytes nvram [ 1.985615] device-mapper: uevent: version 1.0.3 [ 1.986747] device-mapper: ioctl: 4.20.0-ioctl (2011-02-02) initialised: dm-devel@redhat.com [ 1.987888] cpuidle: using governor ladder [ 1.988265] cpuidle: using governor menu [ 1.988549] EFI Variables Facility v0.08 2004-May-17 [ 1.991233] TCP cubic registered [ 1.992700] NET: Registered protocol family 10 [ 2.003297] NET: Registered protocol family 17 [ 2.003999] Registering the dns_resolver key type [ 2.007051] registered taskstats version 1 [ 2.016238] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 2.207205] Magic number: 13:222:105 [ 2.207694] usbmon usbmon1: hash matches [ 2.208838] rtc_cmos 00:05: setting system clock to 2025-10-27 01:06:51 UTC (1761527211) [ 2.211327] BIOS EDD facility v0.16 2004-Jun-25, 0 devices found [ 2.211632] EDD information not available. [ 2.226050] Freeing unused kernel memory: 900k freed [ 2.267388] Write protecting the kernel read-only data: 12288k [ 2.306936] Freeing unused kernel memory: 1968k freed [ 2.337191] Freeing unused kernel memory: 1368k freed [ 2.398692] usb 1-1: new full speed USB device number 2 using uhci_hcd info: initramfs: up at 2.46 GROWROOT: CHANGED: partition=1 start=16065 old: size=64260 end=80325 new: size=2072385,end=2088450 info: initramfs loading root from /dev/vda1 info: /etc/init.d/rc.sysinit: up at 4.14 [ 4.177389] EXT3-fs (vda1): warning: checktime reached, running e2fsck is recommended Starting logging: OK Initializing random number generator... done. Starting network... udhcpc (v1.18.5) started Sending discover... Sending select for 157.246.125.67... Lease of 157.246.125.67 obtained, lease time 268435455 deleting routers route: SIOCDELRT: No such process adding dns 157.246.125.66 cloud-setup: checking http://169.254.169.254/2009-04-04/meta-data/instance-id cloud-setup: successful after 1/30 tries: up 6.50. iid=i-00000007 Starting dropbear sshd: generating rsa key... generating dsa key... OK ===== cloud-final: system completely up in 7.96 seconds ==== 2025-10-27 01:13:58,917 - INFO - Deleting interface with subnet_id fb8cbc7c-7954-45ad-a589-ae917d0a6b19, port_id None from router d9ec711c-3420-4ebe-8d8b-a9aaef5ea53e 2025-10-27 01:13:59,103 - INFO - Deleting VM ctest-vn2-vm1-38016559 2025-10-27 01:13:59,175 - INFO - Deleting VM ctest-vn1-vm1-45451865 2025-10-27 01:13:59,250 - INFO - Deleting VN ctest-vn2-25523256 2025-10-27 01:13:59,296 - DEBUG - VN 1dfd85c4-cbd4-4193-9760-b1cd7a78b5b6 still in use: Unable to complete operation on network 1dfd85c4-cbd4-4193-9760-b1cd7a78b5b6. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-e798657b-43e0-4c7b-b5c8-d5603060b1ba'] 2025-10-27 01:13:59,296 - WARNING - Deleting VN ctest-vn2-25523256 failed..Will retry 2025-10-27 01:14:01,467 - DEBUG - Response for deleting network () 2025-10-27 01:14:01,468 - INFO - Deleting VN ctest-vn1-99709342 2025-10-27 01:14:01,594 - DEBUG - Response for deleting network () 2025-10-27 01:14:01,863 - ERROR - AssertionError Python 3.9.21: /usr/bin/python3 Mon Oct 27 01:13:58 2025 A problem occurred in a Python script. Here is the sequence of function calls leading up to the error, in the order they occurred. /contrail-test/tcutils/wrappers.py in wrapper(self=, *args=(), **kwargs={}) 78 log.info('Initial checks done. Running the testcase now') 79 log.info('') 80 result = function(self, *args, **kwargs) 81 if self.inputs.upgrade: 82 pid = os.getpid() result = None function = self = args = () kwargs = {} /contrail-test/scripts/neutron/test_routers.py in test_basic_router_behavior(self=) 56 self.add_vn_to_router(router_dict['id'], vn1_fixture) 57 assert vn1_vm1_fixture.wait_till_vm_is_up() 58 assert vn2_vm1_fixture.wait_till_vm_is_up() 59 assert vn1_vm1_fixture.ping_with_certainty(vn2_vm1_fixture.vm_ip, 60 expectation=False) vn2_vm1_fixture = vn2_vm1_fixture.wait_till_vm_is_up = > AssertionError: __cause__ = None __class__ = __context__ = None __delattr__ = __dict__ = {} __dir__ = __doc__ = 'Assertion failed.' __eq__ = __format__ = __ge__ = __getattribute__ = __gt__ = __hash__ = __init__ = __init_subclass__ = __le__ = __lt__ = __ne__ = __new__ = __reduce__ = __reduce_ex__ = __repr__ = __setattr__ = __setstate__ = __sizeof__ = __str__ = __subclasshook__ = __suppress_context__ = False __traceback__ = args = () with_traceback = The above is a description of an error in a Python program. Here is the original traceback: Traceback (most recent call last): File "/contrail-test/tcutils/wrappers.py", line 80, in wrapper result = function(self, *args, **kwargs) File "/contrail-test/scripts/neutron/test_routers.py", line 58, in test_basic_router_behavior assert vn2_vm1_fixture.wait_till_vm_is_up() AssertionError 2025-10-27 01:14:01,869 - DEBUG - No XMPP flaps were noticed during the test 2025-10-27 01:14:01,869 - INFO -

2025-10-27 01:14:01,869 - INFO - END TEST : test_basic_router_behavior : FAILED[0:07:20]

2025-10-27 01:14:01,869 - INFO - -------------------------------------------------------------------------------- 2025-10-27 01:14:02,647 - INFO - Deleted project: ctest-TestRouters-97884813, ID : 715de626-8707-43b9-8f95-4001782fa65b