2026-01-23 16:32:59,798 - INFO - Domain Default found not creating 2026-01-23 16:33:00,213 - INFO - Project ctest-TestBasicVMVN-45581161 not found, creating it 2026-01-23 16:33:00,870 - INFO - Created Project:ctest-TestBasicVMVN-45581161, ID : bfbe414c-748a-4287-9118-d9d26f4b435d 2026-01-23 16:33:03,194 - INFO - ================================================================================ 2026-01-23 16:33:03,194 - INFO - STARTING TEST : test_generic_link_local_service 2026-01-23 16:33:03,194 - 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-01-23 16:33:03,509 - DEBUG - Nothing to compare xmpp stats {'10.0.0.22': {'10.20.0.17': '0'}} with 2026-01-23 16:33:03,509 - INFO - Initial checks done. Running the testcase now 2026-01-23 16:33:03,509 - INFO - 2026-01-23 16:33:04,307 - DEBUG - Response for create_network : {'network': {'id': '82033ee3-a306-4d1b-81e7-09a6a6d7a4c4', 'name': 'ctest-vn2_metadata-98199663', 'tenant_id': 'bfbe414c748a42879118d9d26f4b435d', 'project_id': 'bfbe414c748a42879118d9d26f4b435d', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicVMVN-45581161', 'ctest-vn2_metadata-98199663'], 'port_security_enabled': True, 'description': ''}} 2026-01-23 16:33:04,591 - DEBUG - Response for create_subnet : {'subnet': {'id': '5f789456-f847-4061-a606-1b03858f8052', 'name': '', 'tenant_id': 'bfbe414c748a42879118d9d26f4b435d', 'network_id': '82033ee3-a306-4d1b-81e7-09a6a6d7a4c4', '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': 'bfbe414c748a42879118d9d26f4b435d'}} 2026-01-23 16:33:04,635 - INFO - Created VN ctest-vn2_metadata-98199663 2026-01-23 16:33:04,704 - DEBUG - VN ctest-vn2_metadata-98199663 UUID is 82033ee3-a306-4d1b-81e7-09a6a6d7a4c4 2026-01-23 16:33:05,023 - DEBUG - Services list from nova: [, , ] 2026-01-23 16:33:06,622 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-5072-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-5072-1) 2026-01-23 16:33:06,764 - DEBUG - Link local service introspect added 2026-01-23 16:33:06,866 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-01-23 16:33:12,080 - DEBUG - VM is in ACTIVE state now 2026-01-23 16:33:12,321 - INFO - Waiting for VM ctest-TestBasicVMVN-45581161-15297739 to be up.. 2026-01-23 16:33:12,321 - INFO - VM name : ctest-TestBasicVMVN-45581161-15297739 2026-01-23 16:33:12,427 - DEBUG - VM ctest-TestBasicVMVN-45581161-15297739 ID is 6feb8279-e06e-44cc-8145-4c7d46e822b7 2026-01-23 16:33:12,427 - DEBUG - VM ctest-TestBasicVMVN-45581161-15297739 launched on Node cn-jenkins-deploy-platform-ansible-os-5072-1 2026-01-23 16:33:12,526 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-machine/6feb8279-e06e-44cc-8145-4c7d46e822b7 2026-01-23 16:33:12,845 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-machine/6feb8279-e06e-44cc-8145-4c7d46e822b7 2026-01-23 16:33:12,889 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-machine-interface/d29cd466-b76b-4f09-81e9-e549c08a5bc3 2026-01-23 16:33:16,221 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1019ms') 2026-01-23 16:33:16,221 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicVMVN-45581161-15297739 failed! 2026-01-23 16:33:16,296 - DEBUG - Gateway for vn default-domain:ctest-TestBasicVMVN-45581161:ctest-vn2_metadata-98199663 is 11.1.1.1 and allocation pool is NOT set 2026-01-23 16:33:20,383 - 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-01-23 16:33:20,383 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicVMVN-45581161-15297739 failed! 2026-01-23 16:33:20,452 - DEBUG - Gateway for vn default-domain:ctest-TestBasicVMVN-45581161:ctest-vn2_metadata-98199663 is 11.1.1.1 and allocation pool is NOT set 2026-01-23 16:33:22,525 - 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.72 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=1.05 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.051/2.384/3.717/1.333 ms') 2026-01-23 16:33:22,526 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicVMVN-45581161-15297739 passed 2026-01-23 16:33:22,705 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-23 16:33:22,705 - DEBUG - Waiting to SSH to VM ctest-TestBasicVMVN-45581161-15297739, IP 11.1.1.3, Port 22 2026-01-23 16:33:22,905 - DEBUG - VM ctest-TestBasicVMVN-45581161-15297739 is ready for SSH connections 2026-01-23 16:33:22,906 - INFO - Retry 0 2026-01-23 16:33:22,906 - 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.22, gateway password: c0ntrail123 2026-01-23 16:33:22,906 - DEBUG - wget http://169.254.1.2:8083 --spider && echo "Successful" 2026-01-23 16:33:25,016 - DEBUG - Connecting to 169.254.1.2:8083 (169.254.1.2:8083) Successful 2026-01-23 16:33:25,016 - INFO - Generic metadata worked 2026-01-23 16:33:25,403 - DEBUG - Link local service introspect removed 2026-01-23 16:33:25,404 - INFO - Deleting VM ctest-TestBasicVMVN-45581161-15297739 2026-01-23 16:33:25,515 - INFO - Deleting VN ctest-vn2_metadata-98199663 2026-01-23 16:33:25,612 - DEBUG - VN 82033ee3-a306-4d1b-81e7-09a6a6d7a4c4 still in use: Unable to complete operation on network 82033ee3-a306-4d1b-81e7-09a6a6d7a4c4. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-b2f8b2e0-005c-4a29-905c-54e157728c5f'] 2026-01-23 16:33:25,612 - WARNING - Deleting VN ctest-vn2_metadata-98199663 failed..Will retry 2026-01-23 16:33:27,828 - DEBUG - Response for deleting network () 2026-01-23 16:33:28,159 - DEBUG - No XMPP flaps were noticed during the test 2026-01-23 16:33:28,160 - INFO - -------------------------------------------------------------------------------- 2026-01-23 16:33:28,168 - INFO - ================================================================================ 2026-01-23 16:33:28,168 - INFO - STARTING TEST : test_metadata_service 2026-01-23 16:33:28,168 - 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-01-23 16:33:28,510 - DEBUG - Nothing to compare xmpp stats {'10.0.0.22': {'10.20.0.17': '0'}} with 2026-01-23 16:33:28,510 - INFO - Initial checks done. Running the testcase now 2026-01-23 16:33:28,510 - INFO - 2026-01-23 16:33:28,511 - DEBUG - Requesting: http://10.0.0.22:8082/global-vrouter-configs 2026-01-23 16:33:28,525 - DEBUG - Requesting: http://10.0.0.22:8082/global-vrouter-config/a80abe97-6295-4282-b960-ba7046c86a04 2026-01-23 16:33:28,873 - DEBUG - Response for create_network : {'network': {'id': 'ecc65edd-2d75-40bc-a7ec-315688a05191', 'name': 'ctest-vn-33388135', 'tenant_id': 'bfbe414c748a42879118d9d26f4b435d', 'project_id': 'bfbe414c748a42879118d9d26f4b435d', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicVMVN-45581161', 'ctest-vn-33388135'], 'port_security_enabled': True, 'description': ''}} 2026-01-23 16:33:29,122 - DEBUG - Response for create_subnet : {'subnet': {'id': '3f598a07-1b92-410e-9b7e-2e8cdfa04818', 'name': '', 'tenant_id': 'bfbe414c748a42879118d9d26f4b435d', 'network_id': 'ecc65edd-2d75-40bc-a7ec-315688a05191', 'ip_version': 4, 'cidr': '68.64.187.192/26', 'allocation_pools': [{'start': '68.64.187.194', 'end': '68.64.187.254'}], 'gateway_ip': '68.64.187.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '68.64.187.194', 'tags': [], 'project_id': 'bfbe414c748a42879118d9d26f4b435d'}} 2026-01-23 16:33:29,147 - INFO - Created VN ctest-vn-33388135 2026-01-23 16:33:29,208 - DEBUG - VN ctest-vn-33388135 UUID is ecc65edd-2d75-40bc-a7ec-315688a05191 2026-01-23 16:33:30,994 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-5072-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-5072-1) 2026-01-23 16:33:30,995 - INFO - Waiting for VM ctest-TestBasicVMVN-45581161-54361752 to be up.. 2026-01-23 16:33:31,066 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-01-23 16:33:36,267 - DEBUG - VM is in ACTIVE state now 2026-01-23 16:33:36,268 - INFO - VM name : ctest-TestBasicVMVN-45581161-54361752 2026-01-23 16:33:36,464 - DEBUG - VM ctest-TestBasicVMVN-45581161-54361752 ID is 8ce5aab6-b44c-420e-a87e-ea798e8bb92e 2026-01-23 16:33:36,464 - DEBUG - VM ctest-TestBasicVMVN-45581161-54361752 launched on Node cn-jenkins-deploy-platform-ansible-os-5072-1 2026-01-23 16:33:36,650 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-machine/8ce5aab6-b44c-420e-a87e-ea798e8bb92e 2026-01-23 16:33:36,668 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-machine-interface/902a15d9-9752-4b19-baa6-4a41294d5d7b 2026-01-23 16:33:39,999 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1018ms') 2026-01-23 16:33:39,999 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicVMVN-45581161-54361752 failed! 2026-01-23 16:33:40,075 - DEBUG - Gateway for vn default-domain:ctest-TestBasicVMVN-45581161:ctest-vn-33388135 is 68.64.187.193 and allocation pool is NOT set 2026-01-23 16:33:44,152 - 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=2 ttl=63 time=15.0 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 1 received, 50% packet loss, time 1009ms\r\nrtt min/avg/max/mdev = 14.956/14.956/14.956/0.000 ms') 2026-01-23 16:33:44,152 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicVMVN-45581161-54361752 passed 2026-01-23 16:33:44,311 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-23 16:33:44,312 - DEBUG - Waiting to SSH to VM ctest-TestBasicVMVN-45581161-54361752, IP 68.64.187.195, Port 22 2026-01-23 16:33:44,387 - 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-01-23 16:33:44,470 - DEBUG - VM ctest-TestBasicVMVN-45581161-54361752 is NOT ready for SSH connections, VM status: ACTIVE 2026-01-23 16:33:49,470 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-23 16:33:49,471 - DEBUG - Waiting to SSH to VM ctest-TestBasicVMVN-45581161-54361752, IP 68.64.187.195, Port 22 2026-01-23 16:33:49,687 - DEBUG - VM ctest-TestBasicVMVN-45581161-54361752 is ready for SSH connections 2026-01-23 16:33:49,688 - DEBUG - Running remote_cmd, Cmd : cat /tmp/output.txt, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.22, gateway password: c0ntrail123 2026-01-23 16:33:49,688 - DEBUG - cat /tmp/output.txt 2026-01-23 16:33:51,667 - DEBUG - Hello World. The time is now Fri, 23 Jan 2026 09:33:44 -0700! 2026-01-23 16:33:51,667 - DEBUG - ret : {'cat /tmp/output.txt': 'Hello World. The time is now Fri, 23 Jan 2026 09:33:44 -0700!'} 2026-01-23 16:33:51,667 - INFO - metadata_script.txt got executed in the vm 2026-01-23 16:33:51,668 - INFO - Deleting VM ctest-TestBasicVMVN-45581161-54361752 2026-01-23 16:33:51,828 - INFO - Deleting VN ctest-vn-33388135 2026-01-23 16:33:51,884 - DEBUG - VN ecc65edd-2d75-40bc-a7ec-315688a05191 still in use: Unable to complete operation on network ecc65edd-2d75-40bc-a7ec-315688a05191. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-4525ec4d-4865-4224-b7d3-1edd881bbef2'] 2026-01-23 16:33:51,884 - WARNING - Deleting VN ctest-vn-33388135 failed..Will retry 2026-01-23 16:33:54,093 - DEBUG - Response for deleting network () 2026-01-23 16:33:54,416 - DEBUG - No XMPP flaps were noticed during the test
2026-01-23 16:33:54,416 - INFO - END TEST : test_metadata_service : PASSED[0:00:26]
2026-01-23 16:33:54,416 - INFO - -------------------------------------------------------------------------------- 2026-01-23 16:33:54,426 - INFO - ================================================================================ 2026-01-23 16:33:54,426 - INFO - STARTING TEST : test_ping_within_vn_two_vms_two_different_subnets 2026-01-23 16:33:54,427 - 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-01-23 16:33:54,759 - DEBUG - Nothing to compare xmpp stats {'10.0.0.22': {'10.20.0.17': '0'}} with 2026-01-23 16:33:54,759 - INFO - Initial checks done. Running the testcase now 2026-01-23 16:33:54,759 - INFO - 2026-01-23 16:33:55,484 - DEBUG - Response for create_network : {'network': {'id': 'f0b3b62a-bb49-4a10-b4f4-f0f567cd7656', 'name': 'ctest-vn-31496887', 'tenant_id': 'bfbe414c748a42879118d9d26f4b435d', 'project_id': 'bfbe414c748a42879118d9d26f4b435d', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicVMVN-45581161', 'ctest-vn-31496887'], 'port_security_enabled': True, 'description': ''}} 2026-01-23 16:33:55,705 - DEBUG - Response for create_subnet : {'subnet': {'id': 'a5c117fa-10eb-46a7-9451-5dcf96e275ef', 'name': '', 'tenant_id': 'bfbe414c748a42879118d9d26f4b435d', 'network_id': 'f0b3b62a-bb49-4a10-b4f4-f0f567cd7656', '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': 'bfbe414c748a42879118d9d26f4b435d'}} 2026-01-23 16:33:55,987 - DEBUG - Response for create_subnet : {'subnet': {'id': 'eae65160-984b-4cd8-808b-11dd3de8058a', 'name': '', 'tenant_id': 'bfbe414c748a42879118d9d26f4b435d', 'network_id': 'f0b3b62a-bb49-4a10-b4f4-f0f567cd7656', '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': 'bfbe414c748a42879118d9d26f4b435d'}} 2026-01-23 16:33:56,027 - INFO - Created VN ctest-vn-31496887 2026-01-23 16:33:56,092 - DEBUG - VN ctest-vn-31496887 UUID is f0b3b62a-bb49-4a10-b4f4-f0f567cd7656 2026-01-23 16:33:56,682 - DEBUG - Response for create_port : {'port': {'name': 'db2b5d13-e407-4c38-ad33-8c5d6070acdb', 'id': 'db2b5d13-e407-4c38-ad33-8c5d6070acdb', 'tenant_id': 'bfbe414c748a42879118d9d26f4b435d', 'network_id': 'f0b3b62a-bb49-4a10-b4f4-f0f567cd7656', 'mac_address': '02:db:2b:5d:13:e4', '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': 'a5c117fa-10eb-46a7-9451-5dcf96e275ef'}], 'security_groups': ['170059ff-e22a-45d2-8f2d-12f500f1d7a2'], 'admin_state_up': True, 'device_id': '', 'device_owner': '', 'status': 'DOWN', 'port_security_enabled': True, 'description': '', 'project_id': 'bfbe414c748a42879118d9d26f4b435d'}} 2026-01-23 16:33:56,928 - DEBUG - Response for create_port : {'port': {'name': '92e95095-8970-4266-ad00-5f922eb11602', 'id': '92e95095-8970-4266-ad00-5f922eb11602', 'tenant_id': 'bfbe414c748a42879118d9d26f4b435d', 'network_id': 'f0b3b62a-bb49-4a10-b4f4-f0f567cd7656', 'mac_address': '02:92:e9:50:95:89', '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': 'eae65160-984b-4cd8-808b-11dd3de8058a'}], 'security_groups': ['170059ff-e22a-45d2-8f2d-12f500f1d7a2'], 'admin_state_up': True, 'device_id': '', 'device_owner': '', 'status': 'DOWN', 'port_security_enabled': True, 'description': '', 'project_id': 'bfbe414c748a42879118d9d26f4b435d'}} 2026-01-23 16:33:57,792 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-5072-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-5072-1) 2026-01-23 16:33:58,710 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-5072-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-5072-1) 2026-01-23 16:34:00,146 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-5072-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-5072-1) 2026-01-23 16:34:00,146 - DEBUG - Requesting: http://10.0.0.22:8082/domains 2026-01-23 16:34:00,153 - DEBUG - Requesting: http://10.0.0.22:8082/domain/de3300cd-e344-4558-8a52-706b9d313c5d 2026-01-23 16:34:00,176 - DEBUG - Requesting: http://10.0.0.22:8082/project/bfbe414c-748a-4287-9118-d9d26f4b435d 2026-01-23 16:34:00,256 - DEBUG - Requesting: http://10.0.0.22:8082/network-ipam/3c42d6f7-3c57-4293-b408-33eab6b15bff 2026-01-23 16:34:00,266 - INFO - Verifications in API Server for IPAM: ctest-ipam-35909218 passed 2026-01-23 16:34:00,274 - DEBUG - Control-node 10.0.0.22 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestBasicVMVN-45581161:ctest-ipam-35909218', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicVMVN-45581161', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4342269348096197267', 'uuid-lslong': '12972675809821154303'}, 'enable': 'true', 'created': '2026-01-23T16:33:54', 'last-modified': '2026-01-23T16:33:55', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:05.229937'} 2026-01-23 16:34:00,274 - INFO - Verifications in Control node for IPAM: ctest-ipam-35909218 passed 2026-01-23 16:34:00,274 - DEBUG - Requesting: http://10.0.0.22:8082/domains 2026-01-23 16:34:00,282 - DEBUG - Requesting: http://10.0.0.22:8082/domain/de3300cd-e344-4558-8a52-706b9d313c5d 2026-01-23 16:34:00,306 - DEBUG - Requesting: http://10.0.0.22:8082/project/bfbe414c-748a-4287-9118-d9d26f4b435d 2026-01-23 16:34:00,402 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/f0b3b62a-bb49-4a10-b4f4-f0f567cd7656 2026-01-23 16:34:00,418 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/f0b3b62a-bb49-4a10-b4f4-f0f567cd7656 2026-01-23 16:34:00,433 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/764ae6b1-ff8d-4ceb-9053-76fcabe1c6bb 2026-01-23 16:34:00,440 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/764ae6b1-ff8d-4ceb-9053-76fcabe1c6bb 2026-01-23 16:34:00,450 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/22e4a2de-51ec-4f32-a2ff-dacbdcbdb4d3 2026-01-23 16:34:00,465 - DEBUG - Route Targets: ['target:64512:8000004'] 2026-01-23 16:34:00,465 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/f0b3b62a-bb49-4a10-b4f4-f0f567cd7656 2026-01-23 16:34:00,477 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/764ae6b1-ff8d-4ceb-9053-76fcabe1c6bb 2026-01-23 16:34:00,496 - INFO - Verified VN network id 11 for VN f0b3b62a-bb49-4a10-b4f4-f0f567cd7656 2026-01-23 16:34:00,497 - INFO - Verifications in API Server for VN ctest-vn-31496887 passed 2026-01-23 16:34:00,497 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/f0b3b62a-bb49-4a10-b4f4-f0f567cd7656 2026-01-23 16:34:00,509 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/764ae6b1-ff8d-4ceb-9053-76fcabe1c6bb 2026-01-23 16:34:00,517 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/764ae6b1-ff8d-4ceb-9053-76fcabe1c6bb 2026-01-23 16:34:00,533 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/22e4a2de-51ec-4f32-a2ff-dacbdcbdb4d3 2026-01-23 16:34:00,552 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicVMVN-45581161:ctest-vn-31496887', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicVMVN-45581161', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17344406884580936208', 'uuid-lslong': '13039311757943600726'}, 'enable': 'true', 'created': '2026-01-23T16:33:55', 'last-modified': '2026-01-23T16:33:55', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:04.640369'} 2026-01-23 16:34:00,562 - DEBUG - Route Targets: ['target:64512:8000004'] 2026-01-23 16:34:00,562 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn-31496887 passed 2026-01-23 16:34:00,563 - DEBUG - ====Verifying policy data for ctest-vn-31496887 in API_Server ====== 2026-01-23 16:34:00,563 - DEBUG - Requesting: http://10.0.0.22:8082/domains 2026-01-23 16:34:00,569 - DEBUG - Requesting: http://10.0.0.22:8082/domain/de3300cd-e344-4558-8a52-706b9d313c5d 2026-01-23 16:34:00,585 - DEBUG - Requesting: http://10.0.0.22:8082/project/bfbe414c-748a-4287-9118-d9d26f4b435d 2026-01-23 16:34:00,630 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/f0b3b62a-bb49-4a10-b4f4-f0f567cd7656 2026-01-23 16:34:00,644 - DEBUG - =>VN ctest-vn-31496887 has no policy to be verified 2026-01-23 16:34:00,644 - DEBUG - Verifying the vn in opserver 2026-01-23 16:34:00,644 - DEBUG - Verifying the default-domain:ctest-TestBasicVMVN-45581161:ctest-vn-31496887 virtual network link through opserver 10.0.0.22 2026-01-23 16:34:00,645 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks 2026-01-23 16:34:01,015 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks 2026-01-23 16:34:01,133 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicVMVN-45581161:ctest-vn-31496887', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicVMVN-45581161:ctest-vn-31496887?flat'} 2026-01-23 16:34:01,133 - INFO - Validated that VN default-domain:ctest-TestBasicVMVN-45581161:ctest-vn-31496887 is found in opserver 2026-01-23 16:34:01,142 - DEBUG - VRF ids for VN ctest-vn-31496887: {'10.0.0.22': '2'} 2026-01-23 16:34:01,142 - INFO - Waiting for VM ctest-TestBasicVMVN-45581161-71818808 to be up.. 2026-01-23 16:34:01,271 - DEBUG - VM is in ACTIVE state now 2026-01-23 16:34:01,271 - INFO - VM name : ctest-TestBasicVMVN-45581161-71818808 2026-01-23 16:34:01,356 - DEBUG - VM ctest-TestBasicVMVN-45581161-71818808 ID is d89f6147-6fd0-4cac-bcb5-95001c7944f0 2026-01-23 16:34:01,356 - DEBUG - VM ctest-TestBasicVMVN-45581161-71818808 launched on Node cn-jenkins-deploy-platform-ansible-os-5072-1 2026-01-23 16:34:01,468 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-machine/d89f6147-6fd0-4cac-bcb5-95001c7944f0 2026-01-23 16:34:01,488 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-machine-interface/db2b5d13-e407-4c38-ad33-8c5d6070acdb 2026-01-23 16:34:04,762 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1006ms') 2026-01-23 16:34:04,762 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicVMVN-45581161-71818808 failed! 2026-01-23 16:34:04,836 - DEBUG - Gateway for vn default-domain:ctest-TestBasicVMVN-45581161:ctest-vn-31496887 is 31.1.1.1 and allocation pool is NOT set 2026-01-23 16:34:04,836 - DEBUG - Gateway for vn default-domain:ctest-TestBasicVMVN-45581161:ctest-vn-31496887 is 31.1.2.1 and allocation pool is NOT set 2026-01-23 16:34:08,925 - 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-01-23 16:34:08,925 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicVMVN-45581161-71818808 failed! 2026-01-23 16:34:08,988 - DEBUG - Gateway for vn default-domain:ctest-TestBasicVMVN-45581161:ctest-vn-31496887 is 31.1.1.1 and allocation pool is NOT set 2026-01-23 16:34:08,988 - DEBUG - Gateway for vn default-domain:ctest-TestBasicVMVN-45581161:ctest-vn-31496887 is 31.1.2.1 and allocation pool is NOT set 2026-01-23 16:34:11,065 - 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=9.22 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.842 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.842/5.030/9.219/4.188 ms') 2026-01-23 16:34:11,065 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicVMVN-45581161-71818808 passed 2026-01-23 16:34:11,244 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-23 16:34:11,244 - DEBUG - Waiting to SSH to VM ctest-TestBasicVMVN-45581161-71818808, IP 31.1.1.4, Port 22 2026-01-23 16:34:11,327 - 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-01-23 16:34:11,496 - DEBUG - VM ctest-TestBasicVMVN-45581161-71818808 is NOT ready for SSH connections, VM status: ACTIVE 2026-01-23 16:34:16,498 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-23 16:34:16,498 - DEBUG - Waiting to SSH to VM ctest-TestBasicVMVN-45581161-71818808, IP 31.1.1.4, Port 22 2026-01-23 16:34:16,690 - DEBUG - VM ctest-TestBasicVMVN-45581161-71818808 is ready for SSH connections 2026-01-23 16:34:16,691 - INFO - Waiting for VM ctest-TestBasicVMVN-45581161-34245844 to be up.. 2026-01-23 16:34:16,883 - DEBUG - VM is in ACTIVE state now 2026-01-23 16:34:16,884 - INFO - VM name : ctest-TestBasicVMVN-45581161-34245844 2026-01-23 16:34:17,048 - DEBUG - VM ctest-TestBasicVMVN-45581161-34245844 ID is dc6055a9-ba8e-4756-ba99-b4ace97b6ec8 2026-01-23 16:34:17,048 - DEBUG - VM ctest-TestBasicVMVN-45581161-34245844 launched on Node cn-jenkins-deploy-platform-ansible-os-5072-1 2026-01-23 16:34:17,191 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-machine/dc6055a9-ba8e-4756-ba99-b4ace97b6ec8 2026-01-23 16:34:17,209 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-machine-interface/92e95095-8970-4266-ad00-5f922eb11602 2026-01-23 16:34:18,527 - 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.09 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.981 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 0.981/3.036/5.091/2.055 ms') 2026-01-23 16:34:18,527 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-TestBasicVMVN-45581161-34245844 passed 2026-01-23 16:34:18,716 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-23 16:34:18,717 - DEBUG - Waiting to SSH to VM ctest-TestBasicVMVN-45581161-34245844, IP 31.1.2.4, Port 22 2026-01-23 16:34:18,915 - DEBUG - VM ctest-TestBasicVMVN-45581161-34245844 is ready for SSH connections 2026-01-23 16:34:18,915 - INFO - Waiting for VM ctest-TestBasicVMVN-45581161-06462052 to be up.. 2026-01-23 16:34:19,113 - DEBUG - VM is in ACTIVE state now 2026-01-23 16:34:19,114 - INFO - VM name : ctest-TestBasicVMVN-45581161-06462052 2026-01-23 16:34:19,320 - DEBUG - VM ctest-TestBasicVMVN-45581161-06462052 ID is 856fe9a7-e4da-432b-9d1c-fd08222cf00b 2026-01-23 16:34:19,321 - DEBUG - VM ctest-TestBasicVMVN-45581161-06462052 launched on Node cn-jenkins-deploy-platform-ansible-os-5072-1 2026-01-23 16:34:19,412 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-machine/856fe9a7-e4da-432b-9d1c-fd08222cf00b 2026-01-23 16:34:19,420 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-machine-interface/0a2ef42b-63b0-4ad4-9a9f-4597e8887a4d 2026-01-23 16:34:20,676 - 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=5.27 ms\r\n64 bytes from 169.254.0.5: icmp_seq=2 ttl=63 time=0.920 ms\r\n\r\n--- 169.254.0.5 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 0.920/3.097/5.274/2.177 ms') 2026-01-23 16:34:20,676 - INFO - Ping to Metadata IP 169.254.0.5 of VM ctest-TestBasicVMVN-45581161-06462052 passed 2026-01-23 16:34:20,856 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-23 16:34:20,857 - DEBUG - Waiting to SSH to VM ctest-TestBasicVMVN-45581161-06462052, IP 31.1.1.3, Port 22 2026-01-23 16:34:21,051 - DEBUG - VM ctest-TestBasicVMVN-45581161-06462052 is ready for SSH connections 2026-01-23 16:34:21,052 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-23 16:34:21,052 - 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.22, gateway password: c0ntrail123 2026-01-23 16:34:21,052 - DEBUG - ping -s 56 -c 3 -W 1 31.1.2.4 2026-01-23 16:34:25,677 - 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=6.084 ms 64 bytes from 31.1.2.4: seq=1 ttl=63 time=0.811 ms 64 bytes from 31.1.2.4: seq=2 ttl=63 time=2.496 ms --- 31.1.2.4 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 0.811/3.130/6.084 ms 2026-01-23 16:34:25,677 - INFO - Ping to IP 31.1.2.4 from VM ctest-TestBasicVMVN-45581161-71818808 passed 2026-01-23 16:34:25,678 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-23 16:34:25,678 - 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.22, gateway password: c0ntrail123 2026-01-23 16:34:25,678 - DEBUG - ping -s 56 -c 3 -W 1 31.1.1.4 2026-01-23 16:34:29,878 - 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.496 ms 64 bytes from 31.1.1.4: seq=1 ttl=63 time=2.003 ms 64 bytes from 31.1.1.4: seq=2 ttl=63 time=2.199 ms --- 31.1.1.4 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 2.003/2.566/3.496 ms 2026-01-23 16:34:29,879 - INFO - Ping to IP 31.1.1.4 from VM ctest-TestBasicVMVN-45581161-34245844 passed 2026-01-23 16:34:29,879 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-23 16:34:29,879 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-23 16:34:29,879 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-23 16:34:29,879 - 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.22, gateway password: c0ntrail123 2026-01-23 16:34:29,880 - DEBUG - echo 0 > /proc/sys/net/ipv4/icmp_echo_ignore_broadcasts 2026-01-23 16:34:30,138 - DEBUG - None 2026-01-23 16:34:30,139 - 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.22, gateway password: c0ntrail123 2026-01-23 16:34:30,139 - DEBUG - echo 0 > /proc/sys/net/ipv4/icmp_echo_ignore_broadcasts 2026-01-23 16:34:30,328 - DEBUG - None 2026-01-23 16:34:30,328 - 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.22, gateway password: c0ntrail123 2026-01-23 16:34:30,328 - DEBUG - echo 0 > /proc/sys/net/ipv4/icmp_echo_ignore_broadcasts 2026-01-23 16:34:31,346 - DEBUG - None 2026-01-23 16:34:31,347 - 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.22, gateway password: c0ntrail123 2026-01-23 16:34:31,347 - DEBUG - ping -s 56 -c 3 -W 1 31.1.1.7 2026-01-23 16:34:33,669 - 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.769 ms 64 bytes from 31.1.1.3: seq=0 ttl=64 time=6.197 ms (DUP!) 64 bytes from 31.1.1.4: seq=1 ttl=64 time=1.449 ms 64 bytes from 31.1.1.3: seq=1 ttl=64 time=6.782 ms (DUP!) 64 bytes from 31.1.1.4: seq=2 ttl=64 time=1.322 ms --- 31.1.1.7 ping statistics --- 3 packets transmitted, 3 packets received, 2 duplicates, 0% packet loss round-trip min/avg/max = 1.322/3.503/6.782 ms 2026-01-23 16:34:33,670 - 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.22, gateway password: c0ntrail123 2026-01-23 16:34:33,670 - DEBUG - ping -s 56 -c 3 -W 1 224.0.0.1 2026-01-23 16:34:36,009 - 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.055 ms 64 bytes from 31.1.2.4: seq=0 ttl=63 time=3.781 ms (DUP!) 64 bytes from 31.1.1.3: seq=0 ttl=64 time=5.110 ms (DUP!) 64 bytes from 31.1.1.4: seq=1 ttl=64 time=0.453 ms 64 bytes from 31.1.2.4: seq=1 ttl=63 time=2.215 ms (DUP!) 64 bytes from 31.1.1.3: seq=1 ttl=64 time=5.534 ms (DUP!) 64 bytes from 31.1.1.4: seq=2 ttl=64 time=0.822 ms --- 224.0.0.1 ping statistics --- 3 packets transmitted, 3 packets received, 4 duplicates, 0% packet loss round-trip min/avg/max = 0.453/2.710/5.534 ms 2026-01-23 16:34:36,009 - 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.22, gateway password: c0ntrail123 2026-01-23 16:34:36,010 - DEBUG - ping -s 56 -c 3 -W 1 255.255.255.255 2026-01-23 16:34:38,329 - 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.672 ms 64 bytes from 31.1.1.3: seq=0 ttl=64 time=3.092 ms (DUP!) 64 bytes from 31.1.2.4: seq=0 ttl=63 time=4.090 ms (DUP!) 64 bytes from 31.1.1.4: seq=1 ttl=64 time=1.177 ms 64 bytes from 31.1.1.3: seq=1 ttl=64 time=5.053 ms (DUP!) 64 bytes from 31.1.2.4: seq=1 ttl=63 time=7.944 ms (DUP!) 64 bytes from 31.1.1.4: seq=2 ttl=64 time=0.507 ms --- 255.255.255.255 ping statistics --- 3 packets transmitted, 3 packets received, 4 duplicates, 0% packet loss round-trip min/avg/max = 0.507/3.219/7.944 ms 2026-01-23 16:34:38,330 - INFO - Deleting VM ctest-TestBasicVMVN-45581161-06462052 2026-01-23 16:34:38,522 - INFO - Detaching port 92e95095-8970-4266-ad00-5f922eb11602 from VM ctest-TestBasicVMVN-45581161-34245844 2026-01-23 16:34:38,614 - INFO - Deleting VM ctest-TestBasicVMVN-45581161-34245844 2026-01-23 16:34:38,793 - INFO - Detaching port db2b5d13-e407-4c38-ad33-8c5d6070acdb from VM ctest-TestBasicVMVN-45581161-71818808 2026-01-23 16:34:38,862 - INFO - Deleting VM ctest-TestBasicVMVN-45581161-71818808 2026-01-23 16:34:39,017 - INFO - Deleting VN ctest-vn-31496887 2026-01-23 16:34:39,242 - DEBUG - Response for delete_port : () 2026-01-23 16:34:39,407 - DEBUG - Response for delete_port : () 2026-01-23 16:34:39,535 - DEBUG - Response for deleting network () 2026-01-23 16:34:39,535 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/764ae6b1-ff8d-4ceb-9053-76fcabe1c6bb 2026-01-23 16:34:39,544 - DEBUG - Response Code: 404 2026-01-23 16:34:39,544 - DEBUG - Requesting: http://10.0.0.22:8082/domains 2026-01-23 16:34:39,549 - DEBUG - Requesting: http://10.0.0.22:8082/domain/de3300cd-e344-4558-8a52-706b9d313c5d 2026-01-23 16:34:39,601 - DEBUG - Requesting: http://10.0.0.22:8082/project/bfbe414c-748a-4287-9118-d9d26f4b435d 2026-01-23 16:34:39,694 - INFO - Validated that VN ctest-vn-31496887 is not found in API Server 2026-01-23 16:34:39,706 - DEBUG - VN ctest-vn-31496887 is not present in Agent 10.0.0.22 2026-01-23 16:34:39,706 - INFO - Validated that VN ctest-vn-31496887 is not in any agent 2026-01-23 16:34:39,712 - DEBUG - VRF 2 is not seen in agent 10.0.0.22 2026-01-23 16:34:39,720 - DEBUG - Vrouter 10.0.0.22 does not have vrf 2 for VN ctest-vn-31496887 2026-01-23 16:34:39,720 - INFO - Validated that all vrouters do not have the route table for VN default-domain:ctest-TestBasicVMVN-45581161:ctest-vn-31496887 2026-01-23 16:34:39,729 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn-31496887 info 2026-01-23 16:34:39,799 - INFO - IPAM: ctest-ipam-35909218 is not found in API Server 2026-01-23 16:34:39,812 - INFO - IPAM:ctest-ipam-35909218 is not found in control node 2026-01-23 16:34:40,100 - DEBUG - No XMPP flaps were noticed during the test
2026-01-23 16:34:40,100 - INFO - END TEST : test_ping_within_vn_two_vms_two_different_subnets : PASSED[0:00:46]
2026-01-23 16:34:40,100 - INFO - -------------------------------------------------------------------------------- 2026-01-23 16:34:40,935 - INFO - Deleted project: ctest-TestBasicVMVN-45581161, ID : bfbe414c-748a-4287-9118-d9d26f4b435d