2026-02-11 13:48:20,635 - INFO - Domain Default found not creating 2026-02-11 13:48:20,812 - INFO - Project ctest-TestBasicVMVN-18962391 not found, creating it 2026-02-11 13:48:21,294 - INFO - Created Project:ctest-TestBasicVMVN-18962391, ID : 47aa51d0-3881-4ab0-b009-e4ca928de2d2 2026-02-11 13:48:22,867 - INFO - ================================================================================ 2026-02-11 13:48:22,867 - INFO - STARTING TEST : test_generic_link_local_service 2026-02-11 13:48:22,867 - 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-02-11 13:48:23,122 - DEBUG - Nothing to compare xmpp stats {'10.0.0.141': {'10.20.0.193': '0'}} with 2026-02-11 13:48:23,123 - INFO - Initial checks done. Running the testcase now 2026-02-11 13:48:23,123 - INFO - 2026-02-11 13:48:23,716 - DEBUG - Response for create_network : {'network': {'id': 'b6af1716-1882-496a-bf63-41ea7dc55a46', 'name': 'ctest-vn2_metadata-99993505', 'tenant_id': '47aa51d038814ab0b009e4ca928de2d2', 'project_id': '47aa51d038814ab0b009e4ca928de2d2', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicVMVN-18962391', 'ctest-vn2_metadata-99993505'], 'port_security_enabled': True, 'description': ''}} 2026-02-11 13:48:23,916 - DEBUG - Response for create_subnet : {'subnet': {'id': 'a03dacff-38f3-48a6-96ca-cb5afff0fc9f', 'name': '', 'tenant_id': '47aa51d038814ab0b009e4ca928de2d2', 'network_id': 'b6af1716-1882-496a-bf63-41ea7dc55a46', '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': '47aa51d038814ab0b009e4ca928de2d2'}} 2026-02-11 13:48:23,938 - INFO - Created VN ctest-vn2_metadata-99993505 2026-02-11 13:48:23,994 - DEBUG - VN ctest-vn2_metadata-99993505 UUID is b6af1716-1882-496a-bf63-41ea7dc55a46 2026-02-11 13:48:24,188 - DEBUG - Services list from nova: [, , ] 2026-02-11 13:48:25,328 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-5293-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-5293-1) 2026-02-11 13:48:25,437 - DEBUG - Link local service introspect added 2026-02-11 13:48:25,484 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-02-11 13:48:30,563 - DEBUG - VM is in ACTIVE state now 2026-02-11 13:48:30,698 - INFO - Waiting for VM ctest-TestBasicVMVN-18962391-84056985 to be up.. 2026-02-11 13:48:30,698 - INFO - VM name : ctest-TestBasicVMVN-18962391-84056985 2026-02-11 13:48:30,771 - DEBUG - VM ctest-TestBasicVMVN-18962391-84056985 ID is 40d46e47-407a-4f28-8477-2c08b59a5db0 2026-02-11 13:48:30,771 - DEBUG - VM ctest-TestBasicVMVN-18962391-84056985 launched on Node cn-jenkins-deploy-platform-ansible-os-5293-1 2026-02-11 13:48:30,847 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine/40d46e47-407a-4f28-8477-2c08b59a5db0 2026-02-11 13:48:31,154 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine/40d46e47-407a-4f28-8477-2c08b59a5db0 2026-02-11 13:48:31,193 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine-interface/128539ca-0a9b-44f1-86bf-b12942103d8a 2026-02-11 13:48:34,435 - 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 1003ms') 2026-02-11 13:48:34,435 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicVMVN-18962391-84056985 failed! 2026-02-11 13:48:34,489 - DEBUG - Gateway for vn default-domain:ctest-TestBasicVMVN-18962391:ctest-vn2_metadata-99993505 is 11.1.1.1 and allocation pool is NOT set 2026-02-11 13:48:36,549 - 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.4 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=2.30 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 = 2.299/8.373/14.448/6.074 ms') 2026-02-11 13:48:36,549 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicVMVN-18962391-84056985 passed 2026-02-11 13:48:36,706 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-02-11 13:48:36,706 - DEBUG - Waiting to SSH to VM ctest-TestBasicVMVN-18962391-84056985, IP 11.1.1.3, Port 22 2026-02-11 13:48:36,761 - 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-02-11 13:48:36,839 - DEBUG - VM ctest-TestBasicVMVN-18962391-84056985 is NOT ready for SSH connections, VM status: ACTIVE 2026-02-11 13:48:41,839 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-02-11 13:48:41,839 - DEBUG - Waiting to SSH to VM ctest-TestBasicVMVN-18962391-84056985, IP 11.1.1.3, Port 22 2026-02-11 13:48:41,997 - DEBUG - VM ctest-TestBasicVMVN-18962391-84056985 is ready for SSH connections 2026-02-11 13:48:41,997 - INFO - Retry 0 2026-02-11 13:48:41,997 - 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.141, gateway password: c0ntrail123 2026-02-11 13:48:41,998 - DEBUG - wget http://169.254.1.2:8083 --spider && echo "Successful" 2026-02-11 13:48:43,637 - DEBUG - Connecting to 169.254.1.2:8083 (169.254.1.2:8083) Successful 2026-02-11 13:48:43,637 - INFO - Generic metadata worked 2026-02-11 13:48:43,897 - DEBUG - Link local service introspect removed 2026-02-11 13:48:43,897 - INFO - Deleting VM ctest-TestBasicVMVN-18962391-84056985 2026-02-11 13:48:43,986 - INFO - Deleting VN ctest-vn2_metadata-99993505 2026-02-11 13:48:44,025 - DEBUG - VN b6af1716-1882-496a-bf63-41ea7dc55a46 still in use: Unable to complete operation on network b6af1716-1882-496a-bf63-41ea7dc55a46. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-67ecf313-4ed4-488b-9c13-293503ab415e'] 2026-02-11 13:48:44,025 - WARNING - Deleting VN ctest-vn2_metadata-99993505 failed..Will retry 2026-02-11 13:48:46,121 - DEBUG - Response for deleting network () 2026-02-11 13:48:46,379 - DEBUG - No XMPP flaps were noticed during the test 2026-02-11 13:48:46,380 - INFO - -------------------------------------------------------------------------------- 2026-02-11 13:48:46,383 - INFO - ================================================================================ 2026-02-11 13:48:46,383 - INFO - STARTING TEST : test_metadata_service 2026-02-11 13:48:46,383 - 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-02-11 13:48:46,670 - DEBUG - Nothing to compare xmpp stats {'10.0.0.141': {'10.20.0.193': '0'}} with 2026-02-11 13:48:46,670 - INFO - Initial checks done. Running the testcase now 2026-02-11 13:48:46,670 - INFO - 2026-02-11 13:48:46,670 - DEBUG - Requesting: http://10.0.0.141:8082/global-vrouter-configs 2026-02-11 13:48:46,676 - DEBUG - Requesting: http://10.0.0.141:8082/global-vrouter-config/d7f6d96c-8647-4cb2-81f7-8b5223440c5e 2026-02-11 13:48:46,858 - DEBUG - Response for create_network : {'network': {'id': '16fec7b6-14e1-4775-9933-0c85e99a2313', 'name': 'ctest-vn-98390568', 'tenant_id': '47aa51d038814ab0b009e4ca928de2d2', 'project_id': '47aa51d038814ab0b009e4ca928de2d2', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicVMVN-18962391', 'ctest-vn-98390568'], 'port_security_enabled': True, 'description': ''}} 2026-02-11 13:48:47,121 - DEBUG - Response for create_subnet : {'subnet': {'id': 'de805cc6-5539-4113-b4e0-9ebd017f4e31', 'name': '', 'tenant_id': '47aa51d038814ab0b009e4ca928de2d2', 'network_id': '16fec7b6-14e1-4775-9933-0c85e99a2313', 'ip_version': 4, 'cidr': '138.240.77.128/26', 'allocation_pools': [{'start': '138.240.77.130', 'end': '138.240.77.190'}], 'gateway_ip': '138.240.77.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '138.240.77.130', 'tags': [], 'project_id': '47aa51d038814ab0b009e4ca928de2d2'}} 2026-02-11 13:48:47,137 - INFO - Created VN ctest-vn-98390568 2026-02-11 13:48:47,189 - DEBUG - VN ctest-vn-98390568 UUID is 16fec7b6-14e1-4775-9933-0c85e99a2313 2026-02-11 13:48:48,396 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-5293-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-5293-1) 2026-02-11 13:48:48,396 - INFO - Waiting for VM ctest-TestBasicVMVN-18962391-19924252 to be up.. 2026-02-11 13:48:48,437 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-02-11 13:48:53,517 - DEBUG - VM is in ACTIVE state now 2026-02-11 13:48:53,517 - INFO - VM name : ctest-TestBasicVMVN-18962391-19924252 2026-02-11 13:48:53,610 - DEBUG - VM ctest-TestBasicVMVN-18962391-19924252 ID is d319c4b3-55c9-4ae7-84ca-11b0350eac80 2026-02-11 13:48:53,612 - DEBUG - VM ctest-TestBasicVMVN-18962391-19924252 launched on Node cn-jenkins-deploy-platform-ansible-os-5293-1 2026-02-11 13:48:53,689 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine/d319c4b3-55c9-4ae7-84ca-11b0350eac80 2026-02-11 13:48:53,697 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine-interface/9ac529a4-e8e6-48a8-87b0-29fbd7b0ab80 2026-02-11 13:48:56,968 - 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 1031ms') 2026-02-11 13:48:56,968 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicVMVN-18962391-19924252 failed! 2026-02-11 13:48:57,025 - DEBUG - Gateway for vn default-domain:ctest-TestBasicVMVN-18962391:ctest-vn-98390568 is 138.240.77.129 and allocation pool is NOT set 2026-02-11 13:48:59,086 - 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=8.13 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.742 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 = 0.742/4.435/8.129/3.693 ms') 2026-02-11 13:48:59,086 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicVMVN-18962391-19924252 passed 2026-02-11 13:48:59,233 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-02-11 13:48:59,234 - DEBUG - Waiting to SSH to VM ctest-TestBasicVMVN-18962391-19924252, IP 138.240.77.131, Port 22 2026-02-11 13:48:59,289 - 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-02-11 13:48:59,369 - DEBUG - VM ctest-TestBasicVMVN-18962391-19924252 is NOT ready for SSH connections, VM status: ACTIVE 2026-02-11 13:49:04,370 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-02-11 13:49:04,370 - DEBUG - Waiting to SSH to VM ctest-TestBasicVMVN-18962391-19924252, IP 138.240.77.131, Port 22 2026-02-11 13:49:04,529 - DEBUG - VM ctest-TestBasicVMVN-18962391-19924252 is ready for SSH connections 2026-02-11 13:49:04,529 - DEBUG - Running remote_cmd, Cmd : cat /tmp/output.txt, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.141, gateway password: c0ntrail123 2026-02-11 13:49:04,530 - DEBUG - cat /tmp/output.txt 2026-02-11 13:49:06,214 - DEBUG - Hello World. The time is now Wed, 11 Feb 2026 06:48:59 -0700! 2026-02-11 13:49:06,214 - DEBUG - ret : {'cat /tmp/output.txt': 'Hello World. The time is now Wed, 11 Feb 2026 06:48:59 -0700!'} 2026-02-11 13:49:06,214 - INFO - metadata_script.txt got executed in the vm 2026-02-11 13:49:06,214 - INFO - Deleting VM ctest-TestBasicVMVN-18962391-19924252 2026-02-11 13:49:06,292 - INFO - Deleting VN ctest-vn-98390568 2026-02-11 13:49:06,328 - DEBUG - VN 16fec7b6-14e1-4775-9933-0c85e99a2313 still in use: Unable to complete operation on network 16fec7b6-14e1-4775-9933-0c85e99a2313. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-9ac778ce-fdd7-461c-8190-9585e93bec1f'] 2026-02-11 13:49:06,328 - WARNING - Deleting VN ctest-vn-98390568 failed..Will retry 2026-02-11 13:49:08,428 - DEBUG - Response for deleting network () 2026-02-11 13:49:08,698 - DEBUG - No XMPP flaps were noticed during the test
2026-02-11 13:49:08,698 - INFO - END TEST : test_metadata_service : PASSED[0:00:22]
2026-02-11 13:49:08,698 - INFO - -------------------------------------------------------------------------------- 2026-02-11 13:49:08,701 - INFO - ================================================================================ 2026-02-11 13:49:08,702 - INFO - STARTING TEST : test_ping_within_vn_two_vms_two_different_subnets 2026-02-11 13:49:08,702 - 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-02-11 13:49:08,987 - DEBUG - Nothing to compare xmpp stats {'10.0.0.141': {'10.20.0.193': '0'}} with 2026-02-11 13:49:08,987 - INFO - Initial checks done. Running the testcase now 2026-02-11 13:49:08,987 - INFO - 2026-02-11 13:49:09,490 - DEBUG - Response for create_network : {'network': {'id': 'ac276d43-15b7-4e7e-8b27-ebee167989ac', 'name': 'ctest-vn-35094535', 'tenant_id': '47aa51d038814ab0b009e4ca928de2d2', 'project_id': '47aa51d038814ab0b009e4ca928de2d2', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicVMVN-18962391', 'ctest-vn-35094535'], 'port_security_enabled': True, 'description': ''}} 2026-02-11 13:49:09,683 - DEBUG - Response for create_subnet : {'subnet': {'id': 'c66de32c-96c6-41fc-a599-9e358a1c5801', 'name': '', 'tenant_id': '47aa51d038814ab0b009e4ca928de2d2', 'network_id': 'ac276d43-15b7-4e7e-8b27-ebee167989ac', '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': '47aa51d038814ab0b009e4ca928de2d2'}} 2026-02-11 13:49:09,886 - DEBUG - Response for create_subnet : {'subnet': {'id': '51cc3d60-944e-4d8a-a2f5-2bfe08173f9c', 'name': '', 'tenant_id': '47aa51d038814ab0b009e4ca928de2d2', 'network_id': 'ac276d43-15b7-4e7e-8b27-ebee167989ac', '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': '47aa51d038814ab0b009e4ca928de2d2'}} 2026-02-11 13:49:09,906 - INFO - Created VN ctest-vn-35094535 2026-02-11 13:49:09,957 - DEBUG - VN ctest-vn-35094535 UUID is ac276d43-15b7-4e7e-8b27-ebee167989ac 2026-02-11 13:49:10,326 - DEBUG - Response for create_port : {'port': {'name': 'a9905189-7094-431c-8857-4cc692a91f30', 'id': 'a9905189-7094-431c-8857-4cc692a91f30', 'tenant_id': '47aa51d038814ab0b009e4ca928de2d2', 'network_id': 'ac276d43-15b7-4e7e-8b27-ebee167989ac', 'mac_address': '02:a9:90:51:89:70', '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': 'c66de32c-96c6-41fc-a599-9e358a1c5801'}], 'security_groups': ['cd160d95-5198-4fdc-ade6-47f01b68d3a6'], 'admin_state_up': True, 'device_id': '', 'device_owner': '', 'status': 'DOWN', 'port_security_enabled': True, 'description': '', 'project_id': '47aa51d038814ab0b009e4ca928de2d2'}} 2026-02-11 13:49:10,493 - DEBUG - Response for create_port : {'port': {'name': 'd0cc0abe-df2c-4748-9962-dc3122649b70', 'id': 'd0cc0abe-df2c-4748-9962-dc3122649b70', 'tenant_id': '47aa51d038814ab0b009e4ca928de2d2', 'network_id': 'ac276d43-15b7-4e7e-8b27-ebee167989ac', 'mac_address': '02:d0:cc:0a:be:df', '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': '51cc3d60-944e-4d8a-a2f5-2bfe08173f9c'}], 'security_groups': ['cd160d95-5198-4fdc-ade6-47f01b68d3a6'], 'admin_state_up': True, 'device_id': '', 'device_owner': '', 'status': 'DOWN', 'port_security_enabled': True, 'description': '', 'project_id': '47aa51d038814ab0b009e4ca928de2d2'}} 2026-02-11 13:49:10,919 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-5293-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-5293-1) 2026-02-11 13:49:11,382 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-5293-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-5293-1) 2026-02-11 13:49:12,739 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-5293-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-5293-1) 2026-02-11 13:49:12,740 - DEBUG - Requesting: http://10.0.0.141:8082/domains 2026-02-11 13:49:12,746 - DEBUG - Requesting: http://10.0.0.141:8082/domain/afb4e00c-2e10-4bb2-8b9e-2cd58d43f13c 2026-02-11 13:49:12,761 - DEBUG - Requesting: http://10.0.0.141:8082/project/47aa51d0-3881-4ab0-b009-e4ca928de2d2 2026-02-11 13:49:12,839 - DEBUG - Requesting: http://10.0.0.141:8082/network-ipam/00d25a1b-bcff-4731-9783-3139e69aff6e 2026-02-11 13:49:12,861 - INFO - Verifications in API Server for IPAM: ctest-ipam-73275768 passed 2026-02-11 13:49:12,868 - DEBUG - Control-node 10.0.0.141 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestBasicVMVN-18962391:ctest-ipam-73275768', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicVMVN-18962391', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '59208820290701105', 'uuid-lslong': '10917624046428028782'}, 'enable': 'true', 'created': '2026-02-11T13:49:09', 'last-modified': '2026-02-11T13:49:09', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:03.651002'} 2026-02-11 13:49:12,868 - INFO - Verifications in Control node for IPAM: ctest-ipam-73275768 passed 2026-02-11 13:49:12,868 - DEBUG - Requesting: http://10.0.0.141:8082/domains 2026-02-11 13:49:12,874 - DEBUG - Requesting: http://10.0.0.141:8082/domain/afb4e00c-2e10-4bb2-8b9e-2cd58d43f13c 2026-02-11 13:49:12,886 - DEBUG - Requesting: http://10.0.0.141:8082/project/47aa51d0-3881-4ab0-b009-e4ca928de2d2 2026-02-11 13:49:12,967 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-network/ac276d43-15b7-4e7e-8b27-ebee167989ac 2026-02-11 13:49:12,986 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-network/ac276d43-15b7-4e7e-8b27-ebee167989ac 2026-02-11 13:49:12,999 - DEBUG - Requesting: http://10.0.0.141:8082/routing-instance/34546782-2f91-4f81-a629-c4ccc65d32f0 2026-02-11 13:49:13,009 - DEBUG - Requesting: http://10.0.0.141:8082/routing-instance/34546782-2f91-4f81-a629-c4ccc65d32f0 2026-02-11 13:49:13,018 - DEBUG - Requesting: http://10.0.0.141:8082/route-target/4740ce50-3656-4695-95ae-f8ea3693b399 2026-02-11 13:49:13,029 - DEBUG - Route Targets: ['target:64512:8000004'] 2026-02-11 13:49:13,029 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-network/ac276d43-15b7-4e7e-8b27-ebee167989ac 2026-02-11 13:49:13,050 - DEBUG - Requesting: http://10.0.0.141:8082/routing-instance/34546782-2f91-4f81-a629-c4ccc65d32f0 2026-02-11 13:49:13,070 - INFO - Verified VN network id 11 for VN ac276d43-15b7-4e7e-8b27-ebee167989ac 2026-02-11 13:49:13,070 - INFO - Verifications in API Server for VN ctest-vn-35094535 passed 2026-02-11 13:49:13,071 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-network/ac276d43-15b7-4e7e-8b27-ebee167989ac 2026-02-11 13:49:13,084 - DEBUG - Requesting: http://10.0.0.141:8082/routing-instance/34546782-2f91-4f81-a629-c4ccc65d32f0 2026-02-11 13:49:13,097 - DEBUG - Requesting: http://10.0.0.141:8082/routing-instance/34546782-2f91-4f81-a629-c4ccc65d32f0 2026-02-11 13:49:13,109 - DEBUG - Requesting: http://10.0.0.141:8082/route-target/4740ce50-3656-4695-95ae-f8ea3693b399 2026-02-11 13:49:13,130 - DEBUG - Control-node 10.0.0.141 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicVMVN-18962391:ctest-vn-35094535', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicVMVN-18962391', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12405003833509891710', 'uuid-lslong': '10027242503175506348'}, 'enable': 'true', 'created': '2026-02-11T13:49:09', 'last-modified': '2026-02-11T13:49:09', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:03.271983'} 2026-02-11 13:49:13,139 - DEBUG - Route Targets: ['target:64512:8000004'] 2026-02-11 13:49:13,139 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn-35094535 passed 2026-02-11 13:49:13,140 - DEBUG - ====Verifying policy data for ctest-vn-35094535 in API_Server ====== 2026-02-11 13:49:13,140 - DEBUG - Requesting: http://10.0.0.141:8082/domains 2026-02-11 13:49:13,150 - DEBUG - Requesting: http://10.0.0.141:8082/domain/afb4e00c-2e10-4bb2-8b9e-2cd58d43f13c 2026-02-11 13:49:13,169 - DEBUG - Requesting: http://10.0.0.141:8082/project/47aa51d0-3881-4ab0-b009-e4ca928de2d2 2026-02-11 13:49:13,257 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-network/ac276d43-15b7-4e7e-8b27-ebee167989ac 2026-02-11 13:49:13,276 - DEBUG - =>VN ctest-vn-35094535 has no policy to be verified 2026-02-11 13:49:13,276 - DEBUG - Verifying the vn in opserver 2026-02-11 13:49:13,276 - DEBUG - Verifying the default-domain:ctest-TestBasicVMVN-18962391:ctest-vn-35094535 virtual network link through opserver 10.0.0.141 2026-02-11 13:49:13,276 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/virtual-networks 2026-02-11 13:49:13,669 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/virtual-networks 2026-02-11 13:49:13,761 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicVMVN-18962391:ctest-vn-35094535', 'href': 'http://10.0.0.141:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicVMVN-18962391:ctest-vn-35094535?flat'} 2026-02-11 13:49:13,761 - INFO - Validated that VN default-domain:ctest-TestBasicVMVN-18962391:ctest-vn-35094535 is found in opserver 2026-02-11 13:49:13,770 - DEBUG - VRF ids for VN ctest-vn-35094535: {'10.0.0.141': '2'} 2026-02-11 13:49:13,770 - INFO - Waiting for VM ctest-TestBasicVMVN-18962391-92419161 to be up.. 2026-02-11 13:49:13,880 - DEBUG - VM is in ACTIVE state now 2026-02-11 13:49:13,880 - INFO - VM name : ctest-TestBasicVMVN-18962391-92419161 2026-02-11 13:49:14,003 - DEBUG - VM ctest-TestBasicVMVN-18962391-92419161 ID is d40a8de8-11c4-4190-9927-9f97089aca6f 2026-02-11 13:49:14,003 - DEBUG - VM ctest-TestBasicVMVN-18962391-92419161 launched on Node cn-jenkins-deploy-platform-ansible-os-5293-1 2026-02-11 13:49:14,105 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine/d40a8de8-11c4-4190-9927-9f97089aca6f 2026-02-11 13:49:14,115 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine-interface/a9905189-7094-431c-8857-4cc692a91f30 2026-02-11 13:49:17,388 - 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 1002ms') 2026-02-11 13:49:17,388 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicVMVN-18962391-92419161 failed! 2026-02-11 13:49:17,441 - DEBUG - Gateway for vn default-domain:ctest-TestBasicVMVN-18962391:ctest-vn-35094535 is 31.1.1.1 and allocation pool is NOT set 2026-02-11 13:49:17,442 - DEBUG - Gateway for vn default-domain:ctest-TestBasicVMVN-18962391:ctest-vn-35094535 is 31.1.2.1 and allocation pool is NOT set 2026-02-11 13:49:21,516 - 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 1004ms') 2026-02-11 13:49:21,516 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicVMVN-18962391-92419161 failed! 2026-02-11 13:49:21,574 - DEBUG - Gateway for vn default-domain:ctest-TestBasicVMVN-18962391:ctest-vn-35094535 is 31.1.1.1 and allocation pool is NOT set 2026-02-11 13:49:21,574 - DEBUG - Gateway for vn default-domain:ctest-TestBasicVMVN-18962391:ctest-vn-35094535 is 31.1.2.1 and allocation pool is NOT set 2026-02-11 13:49:23,641 - 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.86 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=1.15 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 = 1.152/2.506/3.860/1.354 ms') 2026-02-11 13:49:23,641 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicVMVN-18962391-92419161 passed 2026-02-11 13:49:23,793 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-02-11 13:49:23,794 - DEBUG - Waiting to SSH to VM ctest-TestBasicVMVN-18962391-92419161, IP 31.1.1.4, Port 22 2026-02-11 13:49:23,953 - DEBUG - VM ctest-TestBasicVMVN-18962391-92419161 is ready for SSH connections 2026-02-11 13:49:23,953 - INFO - Waiting for VM ctest-TestBasicVMVN-18962391-83635006 to be up.. 2026-02-11 13:49:24,031 - DEBUG - VM is in ACTIVE state now 2026-02-11 13:49:24,031 - INFO - VM name : ctest-TestBasicVMVN-18962391-83635006 2026-02-11 13:49:24,118 - DEBUG - VM ctest-TestBasicVMVN-18962391-83635006 ID is f582beeb-49e6-4bb8-9f6f-d10f931f094f 2026-02-11 13:49:24,119 - DEBUG - VM ctest-TestBasicVMVN-18962391-83635006 launched on Node cn-jenkins-deploy-platform-ansible-os-5293-1 2026-02-11 13:49:24,222 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine/f582beeb-49e6-4bb8-9f6f-d10f931f094f 2026-02-11 13:49:24,231 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine-interface/d0cc0abe-df2c-4748-9962-dc3122649b70 2026-02-11 13:49:25,481 - 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=5.45 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=2.07 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 2.071/3.758/5.446/1.687 ms') 2026-02-11 13:49:25,481 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-TestBasicVMVN-18962391-83635006 passed 2026-02-11 13:49:25,629 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-02-11 13:49:25,630 - DEBUG - Waiting to SSH to VM ctest-TestBasicVMVN-18962391-83635006, IP 31.1.2.4, Port 22 2026-02-11 13:49:25,696 - DEBUG - Error on ssh to cirros@169.254.0.4:22, result: /bin/bash: connect: Connection refused /bin/bash: line 1: /dev/tcp/169.254.0.4/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.4/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.4/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''} 2026-02-11 13:49:25,769 - DEBUG - VM ctest-TestBasicVMVN-18962391-83635006 is NOT ready for SSH connections, VM status: ACTIVE 2026-02-11 13:49:30,769 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-02-11 13:49:30,770 - DEBUG - Waiting to SSH to VM ctest-TestBasicVMVN-18962391-83635006, IP 31.1.2.4, Port 22 2026-02-11 13:49:30,925 - DEBUG - VM ctest-TestBasicVMVN-18962391-83635006 is ready for SSH connections 2026-02-11 13:49:30,925 - INFO - Waiting for VM ctest-TestBasicVMVN-18962391-66483678 to be up.. 2026-02-11 13:49:31,005 - DEBUG - VM is in ACTIVE state now 2026-02-11 13:49:31,005 - INFO - VM name : ctest-TestBasicVMVN-18962391-66483678 2026-02-11 13:49:31,084 - DEBUG - VM ctest-TestBasicVMVN-18962391-66483678 ID is 449e72a2-8420-4345-8671-973f1ac27dbe 2026-02-11 13:49:31,084 - DEBUG - VM ctest-TestBasicVMVN-18962391-66483678 launched on Node cn-jenkins-deploy-platform-ansible-os-5293-1 2026-02-11 13:49:31,161 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine/449e72a2-8420-4345-8671-973f1ac27dbe 2026-02-11 13:49:31,170 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine-interface/0fd1300c-a3a4-441b-bd0a-4fe18e4787ec 2026-02-11 13:49:32,410 - DEBUG - (True, 'PING 169.254.0.5 (169.254.0.5) 56(84) bytes of data.\r\n64 bytes from 169.254.0.5: icmp_seq=1 ttl=63 time=3.47 ms\r\n64 bytes from 169.254.0.5: icmp_seq=2 ttl=63 time=0.387 ms\r\n\r\n--- 169.254.0.5 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 0.387/1.928/3.470/1.541 ms') 2026-02-11 13:49:32,411 - INFO - Ping to Metadata IP 169.254.0.5 of VM ctest-TestBasicVMVN-18962391-66483678 passed 2026-02-11 13:49:32,569 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-02-11 13:49:32,570 - DEBUG - Waiting to SSH to VM ctest-TestBasicVMVN-18962391-66483678, IP 31.1.1.3, Port 22 2026-02-11 13:49:32,725 - DEBUG - VM ctest-TestBasicVMVN-18962391-66483678 is ready for SSH connections 2026-02-11 13:49:32,725 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-02-11 13:49:32,726 - 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.141, gateway password: c0ntrail123 2026-02-11 13:49:32,726 - DEBUG - ping -s 56 -c 3 -W 1 31.1.2.4 2026-02-11 13:49:36,869 - 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=5.215 ms 64 bytes from 31.1.2.4: seq=1 ttl=63 time=1.000 ms 64 bytes from 31.1.2.4: seq=2 ttl=63 time=0.770 ms --- 31.1.2.4 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 0.770/2.328/5.215 ms 2026-02-11 13:49:36,870 - INFO - Ping to IP 31.1.2.4 from VM ctest-TestBasicVMVN-18962391-92419161 passed 2026-02-11 13:49:36,870 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-02-11 13:49:36,870 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 31.1.1.4, host_string: cirros@169.254.0.4, password: cubswin:)gateway: ubuntu@10.0.0.141, gateway password: c0ntrail123 2026-02-11 13:49:36,870 - DEBUG - ping -s 56 -c 3 -W 1 31.1.1.4 2026-02-11 13:49:40,559 - 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.747 ms 64 bytes from 31.1.1.4: seq=1 ttl=63 time=1.194 ms 64 bytes from 31.1.1.4: seq=2 ttl=63 time=0.888 ms --- 31.1.1.4 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 0.888/1.943/3.747 ms 2026-02-11 13:49:40,559 - INFO - Ping to IP 31.1.1.4 from VM ctest-TestBasicVMVN-18962391-83635006 passed 2026-02-11 13:49:40,559 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-02-11 13:49:40,559 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-02-11 13:49:40,559 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-02-11 13:49:40,560 - 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.141, gateway password: c0ntrail123 2026-02-11 13:49:40,560 - DEBUG - echo 0 > /proc/sys/net/ipv4/icmp_echo_ignore_broadcasts 2026-02-11 13:49:40,745 - DEBUG - None 2026-02-11 13:49:40,745 - 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.141, gateway password: c0ntrail123 2026-02-11 13:49:40,745 - DEBUG - echo 0 > /proc/sys/net/ipv4/icmp_echo_ignore_broadcasts 2026-02-11 13:49:40,953 - DEBUG - None 2026-02-11 13:49:40,953 - DEBUG - Running remote_cmd, Cmd : echo 0 > /proc/sys/net/ipv4/icmp_echo_ignore_broadcasts, host_string: cirros@169.254.0.5, password: cubswin:)gateway: ubuntu@10.0.0.141, gateway password: c0ntrail123 2026-02-11 13:49:40,953 - DEBUG - echo 0 > /proc/sys/net/ipv4/icmp_echo_ignore_broadcasts 2026-02-11 13:49:41,580 - DEBUG - None 2026-02-11 13:49:41,581 - 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.141, gateway password: c0ntrail123 2026-02-11 13:49:41,581 - DEBUG - ping -s 56 -c 3 -W 1 31.1.1.7 2026-02-11 13:49:43,801 - 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.750 ms 64 bytes from 31.1.1.3: seq=0 ttl=64 time=4.526 ms (DUP!) 64 bytes from 31.1.1.4: seq=1 ttl=64 time=0.654 ms 64 bytes from 31.1.1.3: seq=1 ttl=64 time=2.660 ms (DUP!) 64 bytes from 31.1.1.4: seq=2 ttl=64 time=0.593 ms --- 31.1.1.7 ping statistics --- 3 packets transmitted, 3 packets received, 2 duplicates, 0% packet loss round-trip min/avg/max = 0.593/2.036/4.526 ms 2026-02-11 13:49:43,801 - 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.141, gateway password: c0ntrail123 2026-02-11 13:49:43,801 - DEBUG - ping -s 56 -c 3 -W 1 224.0.0.1 2026-02-11 13:49:46,114 - 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=1.719 ms 64 bytes from 31.1.1.3: seq=0 ttl=64 time=5.866 ms (DUP!) 64 bytes from 31.1.2.4: seq=0 ttl=63 time=7.773 ms (DUP!) 64 bytes from 31.1.1.4: seq=1 ttl=64 time=0.435 ms 64 bytes from 31.1.2.4: seq=1 ttl=63 time=1.865 ms (DUP!) 64 bytes from 31.1.1.3: seq=1 ttl=64 time=2.825 ms (DUP!) 64 bytes from 31.1.1.4: seq=2 ttl=64 time=0.366 ms --- 224.0.0.1 ping statistics --- 3 packets transmitted, 3 packets received, 4 duplicates, 0% packet loss round-trip min/avg/max = 0.366/2.978/7.773 ms 2026-02-11 13:49:46,115 - 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.141, gateway password: c0ntrail123 2026-02-11 13:49:46,115 - DEBUG - ping -s 56 -c 3 -W 1 255.255.255.255 2026-02-11 13:49:48,366 - 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=0.696 ms 64 bytes from 31.1.1.3: seq=0 ttl=64 time=2.711 ms (DUP!) 64 bytes from 31.1.2.4: seq=0 ttl=63 time=3.695 ms (DUP!) 64 bytes from 31.1.1.4: seq=1 ttl=64 time=0.559 ms 64 bytes from 31.1.1.3: seq=1 ttl=64 time=2.036 ms (DUP!) 64 bytes from 31.1.2.4: seq=1 ttl=63 time=3.005 ms (DUP!) 64 bytes from 31.1.1.4: seq=2 ttl=64 time=0.454 ms --- 255.255.255.255 ping statistics --- 3 packets transmitted, 3 packets received, 4 duplicates, 0% packet loss round-trip min/avg/max = 0.454/1.879/3.695 ms 2026-02-11 13:49:48,366 - INFO - Deleting VM ctest-TestBasicVMVN-18962391-66483678 2026-02-11 13:49:48,436 - INFO - Detaching port d0cc0abe-df2c-4748-9962-dc3122649b70 from VM ctest-TestBasicVMVN-18962391-83635006 2026-02-11 13:49:48,469 - INFO - Deleting VM ctest-TestBasicVMVN-18962391-83635006 2026-02-11 13:49:48,561 - INFO - Detaching port a9905189-7094-431c-8857-4cc692a91f30 from VM ctest-TestBasicVMVN-18962391-92419161 2026-02-11 13:49:48,599 - INFO - Deleting VM ctest-TestBasicVMVN-18962391-92419161 2026-02-11 13:49:48,701 - INFO - Deleting VN ctest-vn-35094535 2026-02-11 13:49:48,884 - DEBUG - Response for delete_port : () 2026-02-11 13:49:49,091 - DEBUG - Response for delete_port : () 2026-02-11 13:49:49,138 - DEBUG - VN ac276d43-15b7-4e7e-8b27-ebee167989ac still in use: Unable to complete operation on network ac276d43-15b7-4e7e-8b27-ebee167989ac. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-67b81e6d-fc3d-4767-a248-646b8c70c2de'] 2026-02-11 13:49:49,138 - WARNING - Deleting VN ctest-vn-35094535 failed..Will retry 2026-02-11 13:49:51,281 - DEBUG - Response for deleting network () 2026-02-11 13:49:51,281 - DEBUG - Requesting: http://10.0.0.141:8082/routing-instance/34546782-2f91-4f81-a629-c4ccc65d32f0 2026-02-11 13:49:51,287 - DEBUG - Response Code: 404 2026-02-11 13:49:51,287 - DEBUG - Requesting: http://10.0.0.141:8082/domains 2026-02-11 13:49:51,293 - DEBUG - Requesting: http://10.0.0.141:8082/domain/afb4e00c-2e10-4bb2-8b9e-2cd58d43f13c 2026-02-11 13:49:51,346 - DEBUG - Requesting: http://10.0.0.141:8082/project/47aa51d0-3881-4ab0-b009-e4ca928de2d2 2026-02-11 13:49:51,415 - INFO - Validated that VN ctest-vn-35094535 is not found in API Server 2026-02-11 13:49:51,426 - DEBUG - VN ctest-vn-35094535 is not present in Agent 10.0.0.141 2026-02-11 13:49:51,426 - INFO - Validated that VN ctest-vn-35094535 is not in any agent 2026-02-11 13:49:51,431 - DEBUG - VRF 2 is not seen in agent 10.0.0.141 2026-02-11 13:49:51,437 - DEBUG - Vrouter 10.0.0.141 does not have vrf 2 for VN ctest-vn-35094535 2026-02-11 13:49:51,437 - INFO - Validated that all vrouters do not have the route table for VN default-domain:ctest-TestBasicVMVN-18962391:ctest-vn-35094535 2026-02-11 13:49:51,446 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn-35094535 info 2026-02-11 13:49:51,521 - INFO - IPAM: ctest-ipam-73275768 is not found in API Server 2026-02-11 13:49:51,533 - INFO - IPAM:ctest-ipam-73275768 is not found in control node 2026-02-11 13:49:51,787 - DEBUG - No XMPP flaps were noticed during the test
2026-02-11 13:49:51,787 - INFO - END TEST : test_ping_within_vn_two_vms_two_different_subnets : PASSED[0:00:43]
2026-02-11 13:49:51,787 - INFO - -------------------------------------------------------------------------------- 2026-02-11 13:49:52,527 - INFO - Deleted project: ctest-TestBasicVMVN-18962391, ID : 47aa51d0-3881-4ab0-b009-e4ca928de2d2