2026-06-05 17:43:10,127 - INFO - Domain Default found not creating 2026-06-05 17:43:10,626 - INFO - Project ctest-TestBasicVMVN-69169209 not found, creating it 2026-06-05 17:43:11,231 - INFO - Created Project:ctest-TestBasicVMVN-69169209, ID : dfe8ccc4-8291-40a4-b457-695dfeb6c2d3 2026-06-05 17:43:12,794 - INFO - ================================================================================ 2026-06-05 17:43:12,794 - INFO - STARTING TEST : test_generic_link_local_service 2026-06-05 17:43:12,794 - INFO - TEST DESCRIPTION : Description: Test to validate generic linklocal service - running nova list from vm. 1.Create generic link local service to be able to wget to jenkins 2.Create a vm 3.Try wget to jenkins - passes if successful else fails Maintainer: sandipd@juniper.net 2026-06-05 17:43:14,070 - DEBUG - Nothing to compare xmpp stats {'10.0.0.27': {'10.20.0.14': '0', '10.20.0.129': '0'}, '10.0.0.19': {'10.20.0.18': '0', '10.20.0.14': '0'}} with 2026-06-05 17:43:14,070 - INFO - Initial checks done. Running the testcase now 2026-06-05 17:43:14,070 - INFO - 2026-06-05 17:43:14,766 - DEBUG - Response for create_network : {'network': {'id': '5c904b57-4612-4b70-9024-2b6058334b06', 'name': 'ctest-vn2_metadata-95065489', 'tenant_id': 'dfe8ccc4829140a4b457695dfeb6c2d3', 'project_id': 'dfe8ccc4829140a4b457695dfeb6c2d3', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicVMVN-69169209', 'ctest-vn2_metadata-95065489'], 'port_security_enabled': True, 'description': ''}} 2026-06-05 17:43:15,118 - DEBUG - Response for create_subnet : {'subnet': {'id': '997c2142-bb76-439e-b35a-07d8260f6727', 'name': '', 'tenant_id': 'dfe8ccc4829140a4b457695dfeb6c2d3', 'network_id': '5c904b57-4612-4b70-9024-2b6058334b06', 'ip_version': 4, 'cidr': '11.1.1.0/24', 'allocation_pools': [{'start': '11.1.1.2', 'end': '11.1.1.254'}], 'gateway_ip': '11.1.1.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '11.1.1.2', 'tags': [], 'project_id': 'dfe8ccc4829140a4b457695dfeb6c2d3'}} 2026-06-05 17:43:15,141 - INFO - Created VN ctest-vn2_metadata-95065489 2026-06-05 17:43:15,152 - DEBUG - VN ctest-vn2_metadata-95065489 UUID is 5c904b57-4612-4b70-9024-2b6058334b06 2026-06-05 17:43:15,299 - DEBUG - Services list from nova: [, , , ] 2026-06-05 17:43:16,424 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5896-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5896-1) 2026-06-05 17:43:16,507 - DEBUG - Link local service introspect added 2026-06-05 17:43:16,561 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-06-05 17:43:21,657 - DEBUG - VM is in ACTIVE state now 2026-06-05 17:43:21,833 - INFO - Waiting for VM ctest-TestBasicVMVN-69169209-35001339 to be up.. 2026-06-05 17:43:21,833 - INFO - VM name : ctest-TestBasicVMVN-69169209-35001339 2026-06-05 17:43:21,929 - DEBUG - VM ctest-TestBasicVMVN-69169209-35001339 ID is 7be1ae4b-906c-4b82-97f7-25ff0eecf3e5 2026-06-05 17:43:21,929 - DEBUG - VM ctest-TestBasicVMVN-69169209-35001339 launched on Node an-jenkins-deploy-platform-ansible-os-5896-1 2026-06-05 17:43:22,022 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/7be1ae4b-906c-4b82-97f7-25ff0eecf3e5 2026-06-05 17:43:22,368 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/7be1ae4b-906c-4b82-97f7-25ff0eecf3e5 2026-06-05 17:43:22,407 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/d2ed4242-2d75-440e-9cc5-ee1bfc05c497 2026-06-05 17:43:25,589 - 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 1025ms') 2026-06-05 17:43:25,590 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicVMVN-69169209-35001339 failed! 2026-06-05 17:43:25,606 - DEBUG - Gateway for vn default-domain:ctest-TestBasicVMVN-69169209:ctest-vn2_metadata-95065489 is 11.1.1.1 and allocation pool is NOT set 2026-06-05 17:43:27,675 - 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=14.9 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=4.42 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1000ms\r\nrtt min/avg/max/mdev = 4.416/9.677/14.938/5.261 ms') 2026-06-05 17:43:27,675 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicVMVN-69169209-35001339 passed 2026-06-05 17:43:27,744 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-06-05 17:43:27,744 - DEBUG - Waiting to SSH to VM ctest-TestBasicVMVN-69169209-35001339, IP 11.1.1.3, Port 22 2026-06-05 17:43:27,811 - DEBUG - Error on ssh to cirros@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-05 17:43:27,894 - DEBUG - VM ctest-TestBasicVMVN-69169209-35001339 is NOT ready for SSH connections, VM status: ACTIVE 2026-06-05 17:43:32,895 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-06-05 17:43:32,895 - DEBUG - Waiting to SSH to VM ctest-TestBasicVMVN-69169209-35001339, IP 11.1.1.3, Port 22 2026-06-05 17:43:33,066 - DEBUG - VM ctest-TestBasicVMVN-69169209-35001339 is ready for SSH connections 2026-06-05 17:43:33,067 - INFO - Retry 0 2026-06-05 17:43:33,067 - DEBUG - Running remote_cmd, Cmd : wget http://169.254.1.2:8083 --spider && echo "Successful", host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.27, gateway password: c0ntrail123 2026-06-05 17:43:33,067 - DEBUG - wget http://169.254.1.2:8083 --spider && echo "Successful" 2026-06-05 17:43:34,735 - DEBUG - Connecting to 169.254.1.2:8083 (169.254.1.2:8083) Successful 2026-06-05 17:43:34,735 - INFO - Generic metadata worked 2026-06-05 17:43:34,947 - DEBUG - Link local service introspect removed 2026-06-05 17:43:34,947 - INFO - Deleting VM ctest-TestBasicVMVN-69169209-35001339 2026-06-05 17:43:35,056 - INFO - Deleting VN ctest-vn2_metadata-95065489 2026-06-05 17:43:35,110 - DEBUG - VN 5c904b57-4612-4b70-9024-2b6058334b06 still in use: Unable to complete operation on network 5c904b57-4612-4b70-9024-2b6058334b06. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-03fe4a78-7e13-4a73-8f76-ee3f305cf2f5'] 2026-06-05 17:43:35,110 - WARNING - Deleting VN ctest-vn2_metadata-95065489 failed..Will retry 2026-06-05 17:43:37,282 - DEBUG - Response for deleting network () 2026-06-05 17:43:38,555 - DEBUG - No XMPP flaps were noticed during the test 2026-06-05 17:43:38,555 - INFO - -------------------------------------------------------------------------------- 2026-06-05 17:43:38,557 - INFO - ================================================================================ 2026-06-05 17:43:38,558 - INFO - STARTING TEST : test_metadata_service 2026-06-05 17:43:38,558 - INFO - TEST DESCRIPTION : Description: Test to validate metadata service on VM creation. 1.Verify from global-vrouter-config if metadata configures or not - fails otherwise 2.Create a shell script which writes 'hello world ' in a file in /tmp and save the script on the nova api node 3.Create a vm with userdata pointing to that script - script should get executed during vm boot up 4.Go to the vm and verify if the file with 'hello world ' written saved in /tmp of the vm - fails otherwise Maintainer: sandipd@juniper.net 2026-06-05 17:43:39,831 - DEBUG - Nothing to compare xmpp stats {'10.0.0.27': {'10.20.0.14': '0', '10.20.0.129': '0'}, '10.0.0.19': {'10.20.0.18': '0', '10.20.0.14': '0'}} with 2026-06-05 17:43:39,831 - INFO - Initial checks done. Running the testcase now 2026-06-05 17:43:39,831 - INFO - 2026-06-05 17:43:39,831 - DEBUG - Requesting: http://10.0.0.33:8082/global-vrouter-configs 2026-06-05 17:43:40,165 - DEBUG - Requesting: http://10.0.0.33:8082/global-vrouter-configs 2026-06-05 17:43:40,197 - DEBUG - Requesting: http://10.0.0.33:8082/global-vrouter-config/febd749d-96cc-4848-8ec5-c0f66fea7702 2026-06-05 17:43:40,502 - DEBUG - Response for create_network : {'network': {'id': '9f39df23-5b6e-4517-a1e4-221096e6e55d', 'name': 'ctest-vn-06577630', 'tenant_id': 'dfe8ccc4829140a4b457695dfeb6c2d3', 'project_id': 'dfe8ccc4829140a4b457695dfeb6c2d3', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicVMVN-69169209', 'ctest-vn-06577630'], 'port_security_enabled': True, 'description': ''}} 2026-06-05 17:43:40,690 - DEBUG - Response for create_subnet : {'subnet': {'id': '507c5d48-2457-42e3-94e6-80fca889afc0', 'name': '', 'tenant_id': 'dfe8ccc4829140a4b457695dfeb6c2d3', 'network_id': '9f39df23-5b6e-4517-a1e4-221096e6e55d', 'ip_version': 4, 'cidr': '46.51.216.0/26', 'allocation_pools': [{'start': '46.51.216.2', 'end': '46.51.216.62'}], 'gateway_ip': '46.51.216.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '46.51.216.2', 'tags': [], 'project_id': 'dfe8ccc4829140a4b457695dfeb6c2d3'}} 2026-06-05 17:43:40,713 - INFO - Created VN ctest-vn-06577630 2026-06-05 17:43:40,728 - DEBUG - VN ctest-vn-06577630 UUID is 9f39df23-5b6e-4517-a1e4-221096e6e55d 2026-06-05 17:43:41,971 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5896-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5896-2) 2026-06-05 17:43:41,971 - INFO - Waiting for VM ctest-TestBasicVMVN-69169209-54452389 to be up.. 2026-06-05 17:43:42,018 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-06-05 17:43:47,109 - DEBUG - VM is in ACTIVE state now 2026-06-05 17:43:47,109 - INFO - VM name : ctest-TestBasicVMVN-69169209-54452389 2026-06-05 17:43:47,214 - DEBUG - VM ctest-TestBasicVMVN-69169209-54452389 ID is c65d9d2e-c546-4853-a07f-eecae3e3bfaa 2026-06-05 17:43:47,214 - DEBUG - VM ctest-TestBasicVMVN-69169209-54452389 launched on Node an-jenkins-deploy-platform-ansible-os-5896-2 2026-06-05 17:43:47,307 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/c65d9d2e-c546-4853-a07f-eecae3e3bfaa 2026-06-05 17:43:47,319 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/97d88f7d-82f4-4fc3-a415-a2de8d61dcbf 2026-06-05 17:43:50,521 - 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 1037ms') 2026-06-05 17:43:50,521 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicVMVN-69169209-54452389 failed! 2026-06-05 17:43:50,536 - DEBUG - Gateway for vn default-domain:ctest-TestBasicVMVN-69169209:ctest-vn-06577630 is 46.51.216.1 and allocation pool is NOT set 2026-06-05 17:43:54,643 - 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 1041ms') 2026-06-05 17:43:54,643 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicVMVN-69169209-54452389 failed! 2026-06-05 17:43:54,657 - DEBUG - Gateway for vn default-domain:ctest-TestBasicVMVN-69169209:ctest-vn-06577630 is 46.51.216.1 and allocation pool is NOT set 2026-06-05 17:43:56,726 - 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=4.63 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.717 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.717/2.673/4.629/1.956 ms') 2026-06-05 17:43:56,727 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicVMVN-69169209-54452389 passed 2026-06-05 17:43:56,799 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-06-05 17:43:56,800 - DEBUG - Waiting to SSH to VM ctest-TestBasicVMVN-69169209-54452389, IP 46.51.216.3, Port 22 2026-06-05 17:43:56,973 - DEBUG - VM ctest-TestBasicVMVN-69169209-54452389 is ready for SSH connections 2026-06-05 17:43:56,973 - DEBUG - Running remote_cmd, Cmd : cat /tmp/output.txt, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.19, gateway password: c0ntrail123 2026-06-05 17:43:56,973 - DEBUG - cat /tmp/output.txt 2026-06-05 17:43:58,986 - DEBUG - Hello World. The time is now Fri, 05 Jun 2026 11:43:54 -0600! 2026-06-05 17:43:58,986 - DEBUG - ret : {'cat /tmp/output.txt': 'Hello World. The time is now Fri, 05 Jun 2026 11:43:54 -0600!'} 2026-06-05 17:43:58,986 - INFO - metadata_script.txt got executed in the vm 2026-06-05 17:43:58,986 - INFO - Deleting VM ctest-TestBasicVMVN-69169209-54452389 2026-06-05 17:43:59,063 - INFO - Deleting VN ctest-vn-06577630 2026-06-05 17:43:59,107 - DEBUG - VN 9f39df23-5b6e-4517-a1e4-221096e6e55d still in use: Unable to complete operation on network 9f39df23-5b6e-4517-a1e4-221096e6e55d. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-20dc0e6a-a66d-4216-a1f3-17a784fdf5c0'] 2026-06-05 17:43:59,107 - WARNING - Deleting VN ctest-vn-06577630 failed..Will retry 2026-06-05 17:44:01,316 - DEBUG - Response for deleting network () 2026-06-05 17:44:02,623 - DEBUG - No XMPP flaps were noticed during the test
2026-06-05 17:44:02,623 - INFO - END TEST : test_metadata_service : PASSED[0:00:24]
2026-06-05 17:44:02,623 - INFO - -------------------------------------------------------------------------------- 2026-06-05 17:44:02,625 - INFO - ================================================================================ 2026-06-05 17:44:02,625 - INFO - STARTING TEST : test_ping_within_vn_two_vms_two_different_subnets 2026-06-05 17:44:02,626 - INFO - TEST DESCRIPTION : Description: Validate Ping between 2 VMs in the same VN, 2 VMs in different VN subnets. Test steps: 1. Create 1 IPAM's. 2. Create 1 VN with 2 subnets and launch 2 VMs in them. 3. Ping between the VMs in the same VN should go thru fine. 4. Ping to the subnet broadcast and all-broadcast address. Pass criteria: VM in the same subnet will respond to both the pings, while the VM in a different VN should respond only to the all-broadcast address. Maintainer : ganeshahv@juniper.net 2026-06-05 17:44:03,915 - DEBUG - Nothing to compare xmpp stats {'10.0.0.27': {'10.20.0.14': '0', '10.20.0.129': '0'}, '10.0.0.19': {'10.20.0.18': '0', '10.20.0.14': '0'}} with 2026-06-05 17:44:03,915 - INFO - Initial checks done. Running the testcase now 2026-06-05 17:44:03,915 - INFO - 2026-06-05 17:44:04,538 - DEBUG - Response for create_network : {'network': {'id': 'b226ee53-55fe-4552-af6a-8ff946438621', 'name': 'ctest-vn-85614248', 'tenant_id': 'dfe8ccc4829140a4b457695dfeb6c2d3', 'project_id': 'dfe8ccc4829140a4b457695dfeb6c2d3', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicVMVN-69169209', 'ctest-vn-85614248'], 'port_security_enabled': True, 'description': ''}} 2026-06-05 17:44:04,742 - DEBUG - Response for create_subnet : {'subnet': {'id': '5b61b067-7ebd-4c91-a13d-4b9f913904c4', 'name': '', 'tenant_id': 'dfe8ccc4829140a4b457695dfeb6c2d3', 'network_id': 'b226ee53-55fe-4552-af6a-8ff946438621', 'ip_version': 4, 'cidr': '31.1.1.0/29', 'allocation_pools': [{'start': '31.1.1.2', 'end': '31.1.1.6'}], 'gateway_ip': '31.1.1.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '31.1.1.2', 'tags': [], 'project_id': 'dfe8ccc4829140a4b457695dfeb6c2d3'}} 2026-06-05 17:44:04,945 - DEBUG - Response for create_subnet : {'subnet': {'id': '459c858f-ca77-433b-88de-9c7edf47e281', 'name': '', 'tenant_id': 'dfe8ccc4829140a4b457695dfeb6c2d3', 'network_id': 'b226ee53-55fe-4552-af6a-8ff946438621', 'ip_version': 4, 'cidr': '31.1.2.0/29', 'allocation_pools': [{'start': '31.1.2.2', 'end': '31.1.2.6'}], 'gateway_ip': '31.1.2.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '31.1.2.2', 'tags': [], 'project_id': 'dfe8ccc4829140a4b457695dfeb6c2d3'}} 2026-06-05 17:44:04,969 - INFO - Created VN ctest-vn-85614248 2026-06-05 17:44:04,982 - DEBUG - VN ctest-vn-85614248 UUID is b226ee53-55fe-4552-af6a-8ff946438621 2026-06-05 17:44:05,459 - DEBUG - Response for create_port : {'port': {'name': 'f24bfff3-eced-4005-9a53-e0206b1f1918', 'id': 'f24bfff3-eced-4005-9a53-e0206b1f1918', 'tenant_id': 'dfe8ccc4829140a4b457695dfeb6c2d3', 'network_id': 'b226ee53-55fe-4552-af6a-8ff946438621', 'mac_address': '02:f2:4b:ff:f3:ec', 'binding:vnic_type': 'normal', 'binding:vif_details': {'port_filter': True}, 'binding:vif_type': 'unbound', 'binding:host_id': None, 'allowed_address_pairs': [], 'fixed_ips': [{'ip_address': '31.1.1.4', 'subnet_id': '5b61b067-7ebd-4c91-a13d-4b9f913904c4'}], 'security_groups': ['551feb74-78e2-4839-a3dd-8273046a0286'], 'admin_state_up': True, 'device_id': '', 'device_owner': '', 'status': 'DOWN', 'port_security_enabled': True, 'description': '', 'project_id': 'dfe8ccc4829140a4b457695dfeb6c2d3'}} 2026-06-05 17:44:05,798 - DEBUG - Response for create_port : {'port': {'name': '4b0567ef-acb5-4cf8-a691-d287a5837f7b', 'id': '4b0567ef-acb5-4cf8-a691-d287a5837f7b', 'tenant_id': 'dfe8ccc4829140a4b457695dfeb6c2d3', 'network_id': 'b226ee53-55fe-4552-af6a-8ff946438621', 'mac_address': '02:4b:05:67:ef:ac', 'binding:vnic_type': 'normal', 'binding:vif_details': {'port_filter': True}, 'binding:vif_type': 'unbound', 'binding:host_id': None, 'allowed_address_pairs': [], 'fixed_ips': [{'ip_address': '31.1.2.4', 'subnet_id': '459c858f-ca77-433b-88de-9c7edf47e281'}], 'security_groups': ['551feb74-78e2-4839-a3dd-8273046a0286'], 'admin_state_up': True, 'device_id': '', 'device_owner': '', 'status': 'DOWN', 'port_security_enabled': True, 'description': '', 'project_id': 'dfe8ccc4829140a4b457695dfeb6c2d3'}} 2026-06-05 17:44:06,204 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5896-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5896-1) 2026-06-05 17:44:06,770 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5896-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5896-2) 2026-06-05 17:44:08,350 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5896-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5896-1) 2026-06-05 17:44:08,350 - DEBUG - Requesting: http://10.0.0.33:8082/domains 2026-06-05 17:44:08,359 - DEBUG - Requesting: http://10.0.0.33:8082/domain/2afd8c56-3066-40b1-96ed-768ec91ed6e8 2026-06-05 17:44:08,377 - DEBUG - Requesting: http://10.0.0.33:8082/project/dfe8ccc4-8291-40a4-b457-695dfeb6c2d3 2026-06-05 17:44:08,480 - DEBUG - Requesting: http://10.0.0.33:8082/network-ipam/e132d6ab-e3f9-4864-ad82-08e4fb0d693f 2026-06-05 17:44:08,493 - INFO - Verifications in API Server for IPAM: ctest-ipam-78470215 passed 2026-06-05 17:44:08,498 - DEBUG - Control-node 10.0.0.33 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestBasicVMVN-69169209:ctest-ipam-78470215', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicVMVN-69169209', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '16227268441121835108', 'uuid-lslong': '12502565295091444031'}, 'enable': 'true', 'created': '2026-06-05T17:44:03', 'last-modified': '2026-06-05T17:44:04', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:04.314836'} 2026-06-05 17:44:08,504 - DEBUG - Control-node 10.0.0.130 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestBasicVMVN-69169209:ctest-ipam-78470215', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicVMVN-69169209', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '16227268441121835108', 'uuid-lslong': '12502565295091444031'}, 'enable': 'true', 'created': '2026-06-05T17:44:03', 'last-modified': '2026-06-05T17:44:04', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:04.319228'} 2026-06-05 17:44:08,510 - DEBUG - Control-node 10.0.0.38 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestBasicVMVN-69169209:ctest-ipam-78470215', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicVMVN-69169209', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '16227268441121835108', 'uuid-lslong': '12502565295091444031'}, 'enable': 'true', 'created': '2026-06-05T17:44:03', 'last-modified': '2026-06-05T17:44:04', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:04.320719'} 2026-06-05 17:44:08,510 - INFO - Verifications in Control node for IPAM: ctest-ipam-78470215 passed 2026-06-05 17:44:08,510 - DEBUG - Requesting: http://10.0.0.33:8082/domains 2026-06-05 17:44:08,517 - DEBUG - Requesting: http://10.0.0.33:8082/domain/2afd8c56-3066-40b1-96ed-768ec91ed6e8 2026-06-05 17:44:08,529 - DEBUG - Requesting: http://10.0.0.33:8082/project/dfe8ccc4-8291-40a4-b457-695dfeb6c2d3 2026-06-05 17:44:08,629 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/b226ee53-55fe-4552-af6a-8ff946438621 2026-06-05 17:44:08,647 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/b226ee53-55fe-4552-af6a-8ff946438621 2026-06-05 17:44:08,662 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/0cccc091-1549-4058-bd22-8f840b23b417 2026-06-05 17:44:08,673 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/0cccc091-1549-4058-bd22-8f840b23b417 2026-06-05 17:44:08,685 - DEBUG - Requesting: http://10.0.0.33:8082/route-target/de49a957-deab-4c62-ad21-82ce634d3a60 2026-06-05 17:44:08,692 - DEBUG - Route Targets: ['target:64512:8000004'] 2026-06-05 17:44:08,693 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/b226ee53-55fe-4552-af6a-8ff946438621 2026-06-05 17:44:08,703 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/0cccc091-1549-4058-bd22-8f840b23b417 2026-06-05 17:44:08,720 - INFO - Verified VN network id 14 for VN b226ee53-55fe-4552-af6a-8ff946438621 2026-06-05 17:44:08,720 - INFO - Verifications in API Server for VN ctest-vn-85614248 passed 2026-06-05 17:44:08,720 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/b226ee53-55fe-4552-af6a-8ff946438621 2026-06-05 17:44:08,735 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/0cccc091-1549-4058-bd22-8f840b23b417 2026-06-05 17:44:08,744 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/0cccc091-1549-4058-bd22-8f840b23b417 2026-06-05 17:44:08,752 - DEBUG - Requesting: http://10.0.0.33:8082/route-target/de49a957-deab-4c62-ad21-82ce634d3a60 2026-06-05 17:44:08,765 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicVMVN-69169209:ctest-vn-85614248', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicVMVN-69169209', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12837209829558601042', 'uuid-lslong': '12640073604957177377'}, 'enable': 'true', 'created': '2026-06-05T17:44:04', 'last-modified': '2026-06-05T17:44:04', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:03.878197'} 2026-06-05 17:44:08,774 - DEBUG - Route Targets: ['target:64512:8000004'] 2026-06-05 17:44:08,782 - DEBUG - Control-node 10.0.0.130 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicVMVN-69169209:ctest-vn-85614248', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicVMVN-69169209', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12837209829558601042', 'uuid-lslong': '12640073604957177377'}, 'enable': 'true', 'created': '2026-06-05T17:44:04', 'last-modified': '2026-06-05T17:44:04', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:03.893085'} 2026-06-05 17:44:08,792 - DEBUG - Route Targets: ['target:64512:8000004'] 2026-06-05 17:44:08,797 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicVMVN-69169209:ctest-vn-85614248', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicVMVN-69169209', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12837209829558601042', 'uuid-lslong': '12640073604957177377'}, 'enable': 'true', 'created': '2026-06-05T17:44:04', 'last-modified': '2026-06-05T17:44:04', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:03.910938'} 2026-06-05 17:44:08,804 - DEBUG - Route Targets: ['target:64512:8000004'] 2026-06-05 17:44:08,804 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn-85614248 passed 2026-06-05 17:44:08,805 - DEBUG - ====Verifying policy data for ctest-vn-85614248 in API_Server ====== 2026-06-05 17:44:08,805 - DEBUG - Requesting: http://10.0.0.33:8082/domains 2026-06-05 17:44:08,835 - DEBUG - Requesting: http://10.0.0.33:8082/domain/2afd8c56-3066-40b1-96ed-768ec91ed6e8 2026-06-05 17:44:08,888 - DEBUG - Requesting: http://10.0.0.33:8082/project/dfe8ccc4-8291-40a4-b457-695dfeb6c2d3 2026-06-05 17:44:09,010 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/b226ee53-55fe-4552-af6a-8ff946438621 2026-06-05 17:44:09,027 - DEBUG - =>VN ctest-vn-85614248 has no policy to be verified 2026-06-05 17:44:09,027 - DEBUG - Verifying the vn in opserver 2026-06-05 17:44:09,027 - DEBUG - Verifying the default-domain:ctest-TestBasicVMVN-69169209:ctest-vn-85614248 virtual network link through opserver 10.0.0.33 2026-06-05 17:44:09,027 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks 2026-06-05 17:44:09,434 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks 2026-06-05 17:44:09,509 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicVMVN-69169209:ctest-vn-85614248', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicVMVN-69169209:ctest-vn-85614248?flat'} 2026-06-05 17:44:09,509 - DEBUG - Verifying the default-domain:ctest-TestBasicVMVN-69169209:ctest-vn-85614248 virtual network link through opserver 10.0.0.130 2026-06-05 17:44:09,509 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-networks 2026-06-05 17:44:09,848 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-networks 2026-06-05 17:44:09,916 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicVMVN-69169209:ctest-vn-85614248', 'href': 'http://10.0.0.130:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicVMVN-69169209:ctest-vn-85614248?flat'} 2026-06-05 17:44:09,917 - DEBUG - Verifying the default-domain:ctest-TestBasicVMVN-69169209:ctest-vn-85614248 virtual network link through opserver 10.0.0.38 2026-06-05 17:44:09,917 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks 2026-06-05 17:44:10,253 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks 2026-06-05 17:44:10,315 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicVMVN-69169209:ctest-vn-85614248', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicVMVN-69169209:ctest-vn-85614248?flat'} 2026-06-05 17:44:10,315 - INFO - Validated that VN default-domain:ctest-TestBasicVMVN-69169209:ctest-vn-85614248 is found in opserver 2026-06-05 17:44:10,326 - DEBUG - VRF ids for VN ctest-vn-85614248: {'10.0.0.27': '2', '10.0.0.19': '2'} 2026-06-05 17:44:10,326 - INFO - Waiting for VM ctest-TestBasicVMVN-69169209-19912858 to be up.. 2026-06-05 17:44:10,412 - DEBUG - VM is in ACTIVE state now 2026-06-05 17:44:10,412 - INFO - VM name : ctest-TestBasicVMVN-69169209-19912858 2026-06-05 17:44:10,493 - DEBUG - VM ctest-TestBasicVMVN-69169209-19912858 ID is de0faa2f-2a2c-441c-8f5f-aa38a6673fc4 2026-06-05 17:44:10,493 - DEBUG - VM ctest-TestBasicVMVN-69169209-19912858 launched on Node an-jenkins-deploy-platform-ansible-os-5896-1 2026-06-05 17:44:10,578 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/de0faa2f-2a2c-441c-8f5f-aa38a6673fc4 2026-06-05 17:44:10,588 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/f24bfff3-eced-4005-9a53-e0206b1f1918 2026-06-05 17:44:13,792 - 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 1020ms') 2026-06-05 17:44:13,792 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicVMVN-69169209-19912858 failed! 2026-06-05 17:44:13,809 - DEBUG - Gateway for vn default-domain:ctest-TestBasicVMVN-69169209:ctest-vn-85614248 is 31.1.1.1 and allocation pool is NOT set 2026-06-05 17:44:13,810 - DEBUG - Gateway for vn default-domain:ctest-TestBasicVMVN-69169209:ctest-vn-85614248 is 31.1.2.1 and allocation pool is NOT set 2026-06-05 17:44:17,882 - 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-05 17:44:17,882 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicVMVN-69169209-19912858 failed! 2026-06-05 17:44:17,896 - DEBUG - Gateway for vn default-domain:ctest-TestBasicVMVN-69169209:ctest-vn-85614248 is 31.1.1.1 and allocation pool is NOT set 2026-06-05 17:44:17,896 - DEBUG - Gateway for vn default-domain:ctest-TestBasicVMVN-69169209:ctest-vn-85614248 is 31.1.2.1 and allocation pool is NOT set 2026-06-05 17:44:19,961 - 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=4.71 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.334 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.334/2.523/4.712/2.189 ms') 2026-06-05 17:44:19,961 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicVMVN-69169209-19912858 passed 2026-06-05 17:44:20,032 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-06-05 17:44:20,032 - DEBUG - Waiting to SSH to VM ctest-TestBasicVMVN-69169209-19912858, IP 31.1.1.4, Port 22 2026-06-05 17:44:20,206 - DEBUG - VM ctest-TestBasicVMVN-69169209-19912858 is ready for SSH connections 2026-06-05 17:44:20,206 - INFO - Waiting for VM ctest-TestBasicVMVN-69169209-10318342 to be up.. 2026-06-05 17:44:20,294 - DEBUG - VM is in ACTIVE state now 2026-06-05 17:44:20,294 - INFO - VM name : ctest-TestBasicVMVN-69169209-10318342 2026-06-05 17:44:20,377 - DEBUG - VM ctest-TestBasicVMVN-69169209-10318342 ID is 00406f70-1f42-46de-b001-a6005edf27c3 2026-06-05 17:44:20,377 - DEBUG - VM ctest-TestBasicVMVN-69169209-10318342 launched on Node an-jenkins-deploy-platform-ansible-os-5896-2 2026-06-05 17:44:20,459 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/00406f70-1f42-46de-b001-a6005edf27c3 2026-06-05 17:44:20,470 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/4b0567ef-acb5-4cf8-a691-d287a5837f7b 2026-06-05 17:44:21,642 - 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=4.81 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.546 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.546/2.676/4.807/2.130 ms') 2026-06-05 17:44:21,642 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicVMVN-69169209-10318342 passed 2026-06-05 17:44:21,718 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-06-05 17:44:21,718 - DEBUG - Waiting to SSH to VM ctest-TestBasicVMVN-69169209-10318342, IP 31.1.2.4, Port 22 2026-06-05 17:44:21,874 - DEBUG - VM ctest-TestBasicVMVN-69169209-10318342 is ready for SSH connections 2026-06-05 17:44:21,875 - INFO - Waiting for VM ctest-TestBasicVMVN-69169209-38324694 to be up.. 2026-06-05 17:44:21,968 - DEBUG - VM is in ACTIVE state now 2026-06-05 17:44:21,968 - INFO - VM name : ctest-TestBasicVMVN-69169209-38324694 2026-06-05 17:44:22,056 - DEBUG - VM ctest-TestBasicVMVN-69169209-38324694 ID is 21ab6fd0-4066-4405-9a46-62e9ddf32e76 2026-06-05 17:44:22,056 - DEBUG - VM ctest-TestBasicVMVN-69169209-38324694 launched on Node an-jenkins-deploy-platform-ansible-os-5896-1 2026-06-05 17:44:22,141 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/21ab6fd0-4066-4405-9a46-62e9ddf32e76 2026-06-05 17:44:22,153 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/2b47a5ee-b187-4466-88a9-c92a8bbc68da 2026-06-05 17:44:23,310 - 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=2.90 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.438 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1000ms\r\nrtt min/avg/max/mdev = 0.438/1.668/2.898/1.230 ms') 2026-06-05 17:44:23,310 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-TestBasicVMVN-69169209-38324694 passed 2026-06-05 17:44:23,381 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-06-05 17:44:23,381 - DEBUG - Waiting to SSH to VM ctest-TestBasicVMVN-69169209-38324694, IP 31.1.1.3, Port 22 2026-06-05 17:44:23,550 - DEBUG - VM ctest-TestBasicVMVN-69169209-38324694 is ready for SSH connections 2026-06-05 17:44:23,550 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-06-05 17:44:23,550 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 31.1.2.4, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.27, gateway password: c0ntrail123 2026-06-05 17:44:23,550 - DEBUG - ping -s 56 -c 3 -W 1 31.1.2.4 2026-06-05 17:44:27,613 - DEBUG - PING 31.1.2.4 (31.1.2.4): 56 data bytes 64 bytes from 31.1.2.4: seq=0 ttl=63 time=10.047 ms 64 bytes from 31.1.2.4: seq=1 ttl=63 time=1.188 ms 64 bytes from 31.1.2.4: seq=2 ttl=63 time=1.466 ms --- 31.1.2.4 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 1.188/4.233/10.047 ms 2026-06-05 17:44:27,613 - INFO - Ping to IP 31.1.2.4 from VM ctest-TestBasicVMVN-69169209-19912858 passed 2026-06-05 17:44:27,613 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-06-05 17:44:27,613 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 31.1.1.4, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.19, gateway password: c0ntrail123 2026-06-05 17:44:27,613 - DEBUG - ping -s 56 -c 3 -W 1 31.1.1.4 2026-06-05 17:44:31,384 - DEBUG - PING 31.1.1.4 (31.1.1.4): 56 data bytes 64 bytes from 31.1.1.4: seq=0 ttl=63 time=3.544 ms 64 bytes from 31.1.1.4: seq=1 ttl=63 time=1.471 ms 64 bytes from 31.1.1.4: seq=2 ttl=63 time=2.129 ms --- 31.1.1.4 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 1.471/2.381/3.544 ms 2026-06-05 17:44:31,384 - INFO - Ping to IP 31.1.1.4 from VM ctest-TestBasicVMVN-69169209-10318342 passed 2026-06-05 17:44:31,384 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-06-05 17:44:31,384 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-06-05 17:44:31,384 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-06-05 17:44:31,384 - DEBUG - Running remote_cmd, Cmd : echo 0 > /proc/sys/net/ipv4/icmp_echo_ignore_broadcasts, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.27, gateway password: c0ntrail123 2026-06-05 17:44:31,384 - DEBUG - echo 0 > /proc/sys/net/ipv4/icmp_echo_ignore_broadcasts 2026-06-05 17:44:31,570 - DEBUG - None 2026-06-05 17:44:31,570 - DEBUG - Running remote_cmd, Cmd : echo 0 > /proc/sys/net/ipv4/icmp_echo_ignore_broadcasts, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.19, gateway password: c0ntrail123 2026-06-05 17:44:31,570 - DEBUG - echo 0 > /proc/sys/net/ipv4/icmp_echo_ignore_broadcasts 2026-06-05 17:44:31,798 - DEBUG - None 2026-06-05 17:44:31,798 - DEBUG - Running remote_cmd, Cmd : echo 0 > /proc/sys/net/ipv4/icmp_echo_ignore_broadcasts, host_string: cirros@169.254.0.4, password: cubswin:)gateway: ubuntu@10.0.0.27, gateway password: c0ntrail123 2026-06-05 17:44:31,798 - DEBUG - echo 0 > /proc/sys/net/ipv4/icmp_echo_ignore_broadcasts 2026-06-05 17:44:32,616 - DEBUG - None 2026-06-05 17:44:32,616 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 31.1.1.7, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.27, gateway password: c0ntrail123 2026-06-05 17:44:32,616 - DEBUG - ping -s 56 -c 3 -W 1 31.1.1.7 2026-06-05 17:44:34,844 - DEBUG - PING 31.1.1.7 (31.1.1.7): 56 data bytes 64 bytes from 31.1.1.4: seq=0 ttl=64 time=1.965 ms 64 bytes from 31.1.1.3: seq=0 ttl=64 time=5.197 ms (DUP!) 64 bytes from 31.1.1.4: seq=1 ttl=64 time=1.234 ms 64 bytes from 31.1.1.3: seq=1 ttl=64 time=2.563 ms (DUP!) 64 bytes from 31.1.1.4: seq=2 ttl=64 time=0.403 ms --- 31.1.1.7 ping statistics --- 3 packets transmitted, 3 packets received, 2 duplicates, 0% packet loss round-trip min/avg/max = 0.403/2.272/5.197 ms 2026-06-05 17:44:34,844 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 224.0.0.1, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.27, gateway password: c0ntrail123 2026-06-05 17:44:34,844 - DEBUG - ping -s 56 -c 3 -W 1 224.0.0.1 2026-06-05 17:44:37,117 - DEBUG - PING 224.0.0.1 (224.0.0.1): 56 data bytes 64 bytes from 31.1.1.4: seq=0 ttl=64 time=2.013 ms 64 bytes from 31.1.1.3: seq=0 ttl=64 time=4.172 ms (DUP!) 64 bytes from 31.1.2.4: seq=0 ttl=63 time=6.735 ms (DUP!) 64 bytes from 31.1.1.4: seq=1 ttl=64 time=0.424 ms 64 bytes from 31.1.1.3: seq=1 ttl=64 time=3.810 ms (DUP!) 64 bytes from 31.1.2.4: seq=1 ttl=63 time=4.899 ms (DUP!) 64 bytes from 31.1.1.4: seq=2 ttl=64 time=0.499 ms --- 224.0.0.1 ping statistics --- 3 packets transmitted, 3 packets received, 4 duplicates, 0% packet loss round-trip min/avg/max = 0.424/3.221/6.735 ms 2026-06-05 17:44:37,118 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 255.255.255.255, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.27, gateway password: c0ntrail123 2026-06-05 17:44:37,118 - DEBUG - ping -s 56 -c 3 -W 1 255.255.255.255 2026-06-05 17:44:39,377 - DEBUG - PING 255.255.255.255 (255.255.255.255): 56 data bytes 64 bytes from 31.1.1.4: seq=0 ttl=64 time=1.246 ms 64 bytes from 31.1.1.3: seq=0 ttl=64 time=3.917 ms (DUP!) 64 bytes from 31.1.2.4: seq=0 ttl=63 time=5.004 ms (DUP!) 64 bytes from 31.1.1.4: seq=1 ttl=64 time=1.104 ms 64 bytes from 31.1.1.3: seq=1 ttl=64 time=2.513 ms (DUP!) 64 bytes from 31.1.2.4: seq=1 ttl=63 time=3.414 ms (DUP!) 64 bytes from 31.1.1.4: seq=2 ttl=64 time=0.414 ms --- 255.255.255.255 ping statistics --- 3 packets transmitted, 3 packets received, 4 duplicates, 0% packet loss round-trip min/avg/max = 0.414/2.516/5.004 ms 2026-06-05 17:44:39,377 - INFO - Deleting VM ctest-TestBasicVMVN-69169209-38324694 2026-06-05 17:44:39,451 - INFO - Detaching port 4b0567ef-acb5-4cf8-a691-d287a5837f7b from VM ctest-TestBasicVMVN-69169209-10318342 2026-06-05 17:44:39,489 - INFO - Deleting VM ctest-TestBasicVMVN-69169209-10318342 2026-06-05 17:44:39,554 - INFO - Detaching port f24bfff3-eced-4005-9a53-e0206b1f1918 from VM ctest-TestBasicVMVN-69169209-19912858 2026-06-05 17:44:39,582 - INFO - Deleting VM ctest-TestBasicVMVN-69169209-19912858 2026-06-05 17:44:39,652 - INFO - Deleting VN ctest-vn-85614248 2026-06-05 17:44:39,898 - DEBUG - Response for delete_port : () 2026-06-05 17:44:40,212 - DEBUG - Response for delete_port : () 2026-06-05 17:44:40,275 - DEBUG - VN b226ee53-55fe-4552-af6a-8ff946438621 still in use: Unable to complete operation on network b226ee53-55fe-4552-af6a-8ff946438621. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-e462afbe-f4ec-494c-883e-bae283b8050f'] 2026-06-05 17:44:40,275 - WARNING - Deleting VN ctest-vn-85614248 failed..Will retry 2026-06-05 17:44:42,528 - DEBUG - Response for deleting network () 2026-06-05 17:44:42,528 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/0cccc091-1549-4058-bd22-8f840b23b417 2026-06-05 17:44:42,537 - DEBUG - Response Code: 404 2026-06-05 17:44:42,537 - DEBUG - Requesting: http://10.0.0.33:8082/domains 2026-06-05 17:44:42,555 - DEBUG - Requesting: http://10.0.0.33:8082/domain/2afd8c56-3066-40b1-96ed-768ec91ed6e8 2026-06-05 17:44:42,573 - DEBUG - Requesting: http://10.0.0.33:8082/project/dfe8ccc4-8291-40a4-b457-695dfeb6c2d3 2026-06-05 17:44:42,664 - INFO - Validated that VN ctest-vn-85614248 is not found in API Server 2026-06-05 17:44:42,675 - DEBUG - VN ctest-vn-85614248 is not present in Agent 10.0.0.27 2026-06-05 17:44:42,685 - DEBUG - VN ctest-vn-85614248 is not present in Agent 10.0.0.19 2026-06-05 17:44:42,685 - INFO - Validated that VN ctest-vn-85614248 is not in any agent 2026-06-05 17:44:42,690 - DEBUG - VRF 2 is not seen in agent 10.0.0.27 2026-06-05 17:44:42,696 - DEBUG - Vrouter 10.0.0.27 does not have vrf 2 for VN ctest-vn-85614248 2026-06-05 17:44:42,701 - DEBUG - VRF 2 is not seen in agent 10.0.0.19 2026-06-05 17:44:42,705 - DEBUG - Vrouter 10.0.0.19 does not have vrf 2 for VN ctest-vn-85614248 2026-06-05 17:44:42,705 - INFO - Validated that all vrouters do not have the route table for VN default-domain:ctest-TestBasicVMVN-69169209:ctest-vn-85614248 2026-06-05 17:44:42,727 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn-85614248 info 2026-06-05 17:44:42,827 - INFO - IPAM: ctest-ipam-78470215 is not found in API Server 2026-06-05 17:44:42,851 - INFO - IPAM:ctest-ipam-78470215 is not found in control node 2026-06-05 17:44:44,152 - DEBUG - No XMPP flaps were noticed during the test
2026-06-05 17:44:44,152 - INFO - END TEST : test_ping_within_vn_two_vms_two_different_subnets : PASSED[0:00:42]
2026-06-05 17:44:44,152 - INFO - -------------------------------------------------------------------------------- 2026-06-05 17:44:44,970 - INFO - Deleted project: ctest-TestBasicVMVN-69169209, ID : dfe8ccc4-8291-40a4-b457-695dfeb6c2d3