2026-01-19 15:08:44,310 - INFO - Domain Default found not creating 2026-01-19 15:08:44,510 - INFO - Project ctest-TestBasicVMVN-31955753 not found, creating it 2026-01-19 15:08:45,210 - INFO - Created Project:ctest-TestBasicVMVN-31955753, ID : 9e05ea34-1aad-4d0c-adae-ea977e70f91b 2026-01-19 15:08:46,709 - INFO - ================================================================================ 2026-01-19 15:08:46,709 - INFO - STARTING TEST : test_generic_link_local_service 2026-01-19 15:08:46,709 - 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-19 15:08:47,986 - DEBUG - Nothing to compare xmpp stats {'10.0.0.23': {'10.20.0.18': '0', '10.20.0.129': '0'}, '10.0.0.240': {'10.20.0.18': '0', '10.20.0.129': '0'}} with 2026-01-19 15:08:47,986 - INFO - Initial checks done. Running the testcase now 2026-01-19 15:08:47,986 - INFO - 2026-01-19 15:08:48,783 - DEBUG - Response for create_network : {'network': {'id': '31632d40-0c5f-4cd8-9819-6b9d668fcb61', 'name': 'ctest-vn2_metadata-20099211', 'tenant_id': '9e05ea341aad4d0cadaeea977e70f91b', 'project_id': '9e05ea341aad4d0cadaeea977e70f91b', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicVMVN-31955753', 'ctest-vn2_metadata-20099211'], 'port_security_enabled': True, 'description': ''}} 2026-01-19 15:08:49,097 - DEBUG - Response for create_subnet : {'subnet': {'id': 'd2c93fb0-36e6-43c4-ae5a-366060bfedb6', 'name': '', 'tenant_id': '9e05ea341aad4d0cadaeea977e70f91b', 'network_id': '31632d40-0c5f-4cd8-9819-6b9d668fcb61', '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': '9e05ea341aad4d0cadaeea977e70f91b'}} 2026-01-19 15:08:49,124 - INFO - Created VN ctest-vn2_metadata-20099211 2026-01-19 15:08:49,147 - DEBUG - VN ctest-vn2_metadata-20099211 UUID is 31632d40-0c5f-4cd8-9819-6b9d668fcb61 2026-01-19 15:08:49,354 - DEBUG - Services list from nova: [, , , ] 2026-01-19 15:08:50,463 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5045-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5045-1) 2026-01-19 15:08:50,581 - DEBUG - Link local service introspect added 2026-01-19 15:08:50,630 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-01-19 15:08:55,720 - DEBUG - VM is in ACTIVE state now 2026-01-19 15:08:55,885 - INFO - Waiting for VM ctest-TestBasicVMVN-31955753-49262069 to be up.. 2026-01-19 15:08:55,885 - INFO - VM name : ctest-TestBasicVMVN-31955753-49262069 2026-01-19 15:08:55,979 - DEBUG - VM ctest-TestBasicVMVN-31955753-49262069 ID is 82fdc484-9b51-46d1-9220-d17cbf93a8d0 2026-01-19 15:08:55,980 - DEBUG - VM ctest-TestBasicVMVN-31955753-49262069 launched on Node an-jenkins-deploy-platform-ansible-os-5045-1 2026-01-19 15:08:56,068 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/82fdc484-9b51-46d1-9220-d17cbf93a8d0 2026-01-19 15:08:56,373 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/82fdc484-9b51-46d1-9220-d17cbf93a8d0 2026-01-19 15:08:56,413 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/3a1455e9-a27f-4a9a-ad45-f3f0b3c8b96f 2026-01-19 15:08:59,586 - 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 1005ms') 2026-01-19 15:08:59,586 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicVMVN-31955753-49262069 failed! 2026-01-19 15:08:59,603 - DEBUG - Gateway for vn default-domain:ctest-TestBasicVMVN-31955753:ctest-vn2_metadata-20099211 is 11.1.1.1 and allocation pool is NOT set 2026-01-19 15:09:01,668 - 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.31 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.314 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.314/1.813/3.312/1.499 ms') 2026-01-19 15:09:01,669 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicVMVN-31955753-49262069 passed 2026-01-19 15:09:01,745 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-19 15:09:01,746 - DEBUG - Waiting to SSH to VM ctest-TestBasicVMVN-31955753-49262069, IP 11.1.1.3, Port 22 2026-01-19 15:09:01,919 - DEBUG - VM ctest-TestBasicVMVN-31955753-49262069 is ready for SSH connections 2026-01-19 15:09:01,920 - INFO - Retry 0 2026-01-19 15:09:01,920 - 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.23, gateway password: c0ntrail123 2026-01-19 15:09:01,920 - DEBUG - wget http://169.254.1.2:8083 --spider && echo "Successful" 2026-01-19 15:09:03,798 - DEBUG - Connecting to 169.254.1.2:8083 (169.254.1.2:8083) Successful 2026-01-19 15:09:03,798 - INFO - Generic metadata worked 2026-01-19 15:09:04,026 - DEBUG - Link local service introspect removed 2026-01-19 15:09:04,026 - INFO - Deleting VM ctest-TestBasicVMVN-31955753-49262069 2026-01-19 15:09:04,112 - INFO - Deleting VN ctest-vn2_metadata-20099211 2026-01-19 15:09:04,162 - DEBUG - VN 31632d40-0c5f-4cd8-9819-6b9d668fcb61 still in use: Unable to complete operation on network 31632d40-0c5f-4cd8-9819-6b9d668fcb61. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-f5f17b22-76c2-4551-82c4-4915afe275e1'] 2026-01-19 15:09:04,162 - WARNING - Deleting VN ctest-vn2_metadata-20099211 failed..Will retry 2026-01-19 15:09:06,358 - DEBUG - Response for deleting network () 2026-01-19 15:09:07,656 - DEBUG - No XMPP flaps were noticed during the test 2026-01-19 15:09:07,656 - INFO - -------------------------------------------------------------------------------- 2026-01-19 15:09:07,660 - INFO - ================================================================================ 2026-01-19 15:09:07,660 - INFO - STARTING TEST : test_metadata_service 2026-01-19 15:09:07,661 - 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-19 15:09:08,950 - DEBUG - Nothing to compare xmpp stats {'10.0.0.23': {'10.20.0.18': '0', '10.20.0.129': '0'}, '10.0.0.240': {'10.20.0.18': '0', '10.20.0.129': '0'}} with 2026-01-19 15:09:08,950 - INFO - Initial checks done. Running the testcase now 2026-01-19 15:09:08,950 - INFO - 2026-01-19 15:09:08,950 - DEBUG - Requesting: http://10.0.0.22:8082/global-vrouter-configs 2026-01-19 15:09:09,245 - DEBUG - Requesting: http://10.0.0.22:8082/global-vrouter-configs 2026-01-19 15:09:09,277 - DEBUG - Requesting: http://10.0.0.22:8082/global-vrouter-config/efbd9246-da1a-401b-8e1d-93e8d9bf6484 2026-01-19 15:09:09,607 - DEBUG - Response for create_network : {'network': {'id': '9f8c633a-60f6-4dac-ac56-d7fef8fabbb7', 'name': 'ctest-vn-93343692', 'tenant_id': '9e05ea341aad4d0cadaeea977e70f91b', 'project_id': '9e05ea341aad4d0cadaeea977e70f91b', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicVMVN-31955753', 'ctest-vn-93343692'], 'port_security_enabled': True, 'description': ''}} 2026-01-19 15:09:09,914 - DEBUG - Response for create_subnet : {'subnet': {'id': '67111579-647d-463c-a55a-ef6deb443943', 'name': '', 'tenant_id': '9e05ea341aad4d0cadaeea977e70f91b', 'network_id': '9f8c633a-60f6-4dac-ac56-d7fef8fabbb7', 'ip_version': 4, 'cidr': '120.83.202.64/26', 'allocation_pools': [{'start': '120.83.202.66', 'end': '120.83.202.126'}], 'gateway_ip': '120.83.202.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '120.83.202.66', 'tags': [], 'project_id': '9e05ea341aad4d0cadaeea977e70f91b'}} 2026-01-19 15:09:09,937 - INFO - Created VN ctest-vn-93343692 2026-01-19 15:09:09,951 - DEBUG - VN ctest-vn-93343692 UUID is 9f8c633a-60f6-4dac-ac56-d7fef8fabbb7 2026-01-19 15:09:11,180 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5045-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5045-2) 2026-01-19 15:09:11,181 - INFO - Waiting for VM ctest-TestBasicVMVN-31955753-79717648 to be up.. 2026-01-19 15:09:11,239 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-01-19 15:09:16,329 - DEBUG - VM is in ACTIVE state now 2026-01-19 15:09:16,329 - INFO - VM name : ctest-TestBasicVMVN-31955753-79717648 2026-01-19 15:09:16,416 - DEBUG - VM ctest-TestBasicVMVN-31955753-79717648 ID is ff5dcfb8-f1fc-4295-88c0-a59762a583f9 2026-01-19 15:09:16,416 - DEBUG - VM ctest-TestBasicVMVN-31955753-79717648 launched on Node an-jenkins-deploy-platform-ansible-os-5045-2 2026-01-19 15:09:16,511 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/ff5dcfb8-f1fc-4295-88c0-a59762a583f9 2026-01-19 15:09:16,524 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/d7e34b54-b1cb-4137-9304-156559c272ab 2026-01-19 15:09:19,748 - 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 1027ms') 2026-01-19 15:09:19,748 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicVMVN-31955753-79717648 failed! 2026-01-19 15:09:19,766 - DEBUG - Gateway for vn default-domain:ctest-TestBasicVMVN-31955753:ctest-vn-93343692 is 120.83.202.65 and allocation pool is NOT set 2026-01-19 15:09:21,833 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n64 bytes from 169.254.0.3: icmp_seq=1 ttl=63 time=3.38 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.350 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 999ms\r\nrtt min/avg/max/mdev = 0.350/1.865/3.380/1.515 ms') 2026-01-19 15:09:21,833 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicVMVN-31955753-79717648 passed 2026-01-19 15:09:21,947 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-19 15:09:21,947 - DEBUG - Waiting to SSH to VM ctest-TestBasicVMVN-31955753-79717648, IP 120.83.202.67, Port 22 2026-01-19 15:09:22,103 - DEBUG - VM ctest-TestBasicVMVN-31955753-79717648 is ready for SSH connections 2026-01-19 15:09:22,103 - DEBUG - Running remote_cmd, Cmd : cat /tmp/output.txt, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.240, gateway password: c0ntrail123 2026-01-19 15:09:22,103 - DEBUG - cat /tmp/output.txt 2026-01-19 15:09:24,002 - DEBUG - Hello World. The time is now Mon, 19 Jan 2026 08:09:21 -0700! 2026-01-19 15:09:24,002 - DEBUG - ret : {'cat /tmp/output.txt': 'Hello World. The time is now Mon, 19 Jan 2026 08:09:21 -0700!'} 2026-01-19 15:09:24,002 - INFO - metadata_script.txt got executed in the vm 2026-01-19 15:09:24,002 - INFO - Deleting VM ctest-TestBasicVMVN-31955753-79717648 2026-01-19 15:09:24,077 - INFO - Deleting VN ctest-vn-93343692 2026-01-19 15:09:24,123 - DEBUG - VN 9f8c633a-60f6-4dac-ac56-d7fef8fabbb7 still in use: Unable to complete operation on network 9f8c633a-60f6-4dac-ac56-d7fef8fabbb7. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-7199d01f-8333-4b30-ac7a-a0df7f4bcb19'] 2026-01-19 15:09:24,123 - WARNING - Deleting VN ctest-vn-93343692 failed..Will retry 2026-01-19 15:09:26,354 - DEBUG - Response for deleting network () 2026-01-19 15:09:27,622 - DEBUG - No XMPP flaps were noticed during the test
2026-01-19 15:09:27,622 - INFO - END TEST : test_metadata_service : PASSED[0:00:20]
2026-01-19 15:09:27,622 - INFO - -------------------------------------------------------------------------------- 2026-01-19 15:09:27,624 - INFO - ================================================================================ 2026-01-19 15:09:27,624 - INFO - STARTING TEST : test_ping_within_vn_two_vms_two_different_subnets 2026-01-19 15:09:27,624 - 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-19 15:09:28,922 - DEBUG - Nothing to compare xmpp stats {'10.0.0.23': {'10.20.0.18': '0', '10.20.0.129': '0'}, '10.0.0.240': {'10.20.0.18': '0', '10.20.0.129': '0'}} with 2026-01-19 15:09:28,922 - INFO - Initial checks done. Running the testcase now 2026-01-19 15:09:28,922 - INFO - 2026-01-19 15:09:29,631 - DEBUG - Response for create_network : {'network': {'id': '6ce98eb4-cbe5-454f-8f54-7a321aa7adc4', 'name': 'ctest-vn-19417712', 'tenant_id': '9e05ea341aad4d0cadaeea977e70f91b', 'project_id': '9e05ea341aad4d0cadaeea977e70f91b', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicVMVN-31955753', 'ctest-vn-19417712'], 'port_security_enabled': True, 'description': ''}} 2026-01-19 15:09:29,908 - DEBUG - Response for create_subnet : {'subnet': {'id': '31541403-0ceb-4d18-9b39-54f94096bde3', 'name': '', 'tenant_id': '9e05ea341aad4d0cadaeea977e70f91b', 'network_id': '6ce98eb4-cbe5-454f-8f54-7a321aa7adc4', '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': '9e05ea341aad4d0cadaeea977e70f91b'}} 2026-01-19 15:09:30,157 - DEBUG - Response for create_subnet : {'subnet': {'id': '253414fb-7aa0-4a84-8cbc-4bd5146c4a86', 'name': '', 'tenant_id': '9e05ea341aad4d0cadaeea977e70f91b', 'network_id': '6ce98eb4-cbe5-454f-8f54-7a321aa7adc4', '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': '9e05ea341aad4d0cadaeea977e70f91b'}} 2026-01-19 15:09:30,197 - INFO - Created VN ctest-vn-19417712 2026-01-19 15:09:30,221 - DEBUG - VN ctest-vn-19417712 UUID is 6ce98eb4-cbe5-454f-8f54-7a321aa7adc4 2026-01-19 15:09:30,748 - DEBUG - Response for create_port : {'port': {'name': '9dd8fd36-c3d6-419f-8e56-04a9c1a903ca', 'id': '9dd8fd36-c3d6-419f-8e56-04a9c1a903ca', 'tenant_id': '9e05ea341aad4d0cadaeea977e70f91b', 'network_id': '6ce98eb4-cbe5-454f-8f54-7a321aa7adc4', 'mac_address': '02:9d:d8:fd:36:c3', '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': '31541403-0ceb-4d18-9b39-54f94096bde3'}], 'security_groups': ['b7bb226a-7b5e-4ac5-abb8-47a11aeb6f19'], 'admin_state_up': True, 'device_id': '', 'device_owner': '', 'status': 'DOWN', 'port_security_enabled': True, 'description': '', 'project_id': '9e05ea341aad4d0cadaeea977e70f91b'}} 2026-01-19 15:09:31,067 - DEBUG - Response for create_port : {'port': {'name': '82c2be77-1d7f-4191-af5a-a2f80d1256f1', 'id': '82c2be77-1d7f-4191-af5a-a2f80d1256f1', 'tenant_id': '9e05ea341aad4d0cadaeea977e70f91b', 'network_id': '6ce98eb4-cbe5-454f-8f54-7a321aa7adc4', 'mac_address': '02:82:c2:be:77:1d', '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': '253414fb-7aa0-4a84-8cbc-4bd5146c4a86'}], 'security_groups': ['b7bb226a-7b5e-4ac5-abb8-47a11aeb6f19'], 'admin_state_up': True, 'device_id': '', 'device_owner': '', 'status': 'DOWN', 'port_security_enabled': True, 'description': '', 'project_id': '9e05ea341aad4d0cadaeea977e70f91b'}} 2026-01-19 15:09:31,476 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5045-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5045-1) 2026-01-19 15:09:32,011 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5045-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5045-2) 2026-01-19 15:09:33,596 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5045-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5045-1) 2026-01-19 15:09:33,596 - DEBUG - Requesting: http://10.0.0.22:8082/domains 2026-01-19 15:09:33,607 - DEBUG - Requesting: http://10.0.0.22:8082/domain/5a05e3ad-7c50-4a45-9ba6-ba5e0b0e3b90 2026-01-19 15:09:33,631 - DEBUG - Requesting: http://10.0.0.22:8082/project/9e05ea34-1aad-4d0c-adae-ea977e70f91b 2026-01-19 15:09:33,728 - DEBUG - Requesting: http://10.0.0.22:8082/network-ipam/7f2c1152-5040-412d-823b-dd637fac9397 2026-01-19 15:09:33,746 - INFO - Verifications in API Server for IPAM: ctest-ipam-89846464 passed 2026-01-19 15:09:33,755 - DEBUG - Control-node 10.0.0.22 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestBasicVMVN-31955753:ctest-ipam-89846464', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicVMVN-31955753', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9163718387023495469', 'uuid-lslong': '9384337667970077591'}, 'enable': 'true', 'created': '2026-01-19T15:09:28', 'last-modified': '2026-01-19T15:09:29', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:04.541413'} 2026-01-19 15:09:33,769 - DEBUG - Control-node 10.0.0.33 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestBasicVMVN-31955753:ctest-ipam-89846464', 'interests': '0-1', 'advertised': '0-1', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicVMVN-31955753', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9163718387023495469', 'uuid-lslong': '9384337667970077591'}, 'enable': 'true', 'created': '2026-01-19T15:09:28', 'last-modified': '2026-01-19T15:09:29', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:04.548921'} 2026-01-19 15:09:33,777 - DEBUG - Control-node 10.0.0.27 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestBasicVMVN-31955753:ctest-ipam-89846464', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicVMVN-31955753', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9163718387023495469', 'uuid-lslong': '9384337667970077591'}, 'enable': 'true', 'created': '2026-01-19T15:09:28', 'last-modified': '2026-01-19T15:09:29', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:04.563827'} 2026-01-19 15:09:33,777 - INFO - Verifications in Control node for IPAM: ctest-ipam-89846464 passed 2026-01-19 15:09:33,777 - DEBUG - Requesting: http://10.0.0.22:8082/domains 2026-01-19 15:09:33,785 - DEBUG - Requesting: http://10.0.0.22:8082/domain/5a05e3ad-7c50-4a45-9ba6-ba5e0b0e3b90 2026-01-19 15:09:33,800 - DEBUG - Requesting: http://10.0.0.22:8082/project/9e05ea34-1aad-4d0c-adae-ea977e70f91b 2026-01-19 15:09:33,898 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/6ce98eb4-cbe5-454f-8f54-7a321aa7adc4 2026-01-19 15:09:33,923 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/6ce98eb4-cbe5-454f-8f54-7a321aa7adc4 2026-01-19 15:09:33,935 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/e51e6c8a-52d5-47b0-ab38-08dc170ee310 2026-01-19 15:09:33,946 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/e51e6c8a-52d5-47b0-ab38-08dc170ee310 2026-01-19 15:09:33,955 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/7b42c80e-c915-4ddf-bd3f-249d22c1bf14 2026-01-19 15:09:33,964 - DEBUG - Route Targets: ['target:64512:8000005'] 2026-01-19 15:09:33,965 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/6ce98eb4-cbe5-454f-8f54-7a321aa7adc4 2026-01-19 15:09:33,978 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/e51e6c8a-52d5-47b0-ab38-08dc170ee310 2026-01-19 15:09:33,995 - INFO - Verified VN network id 11 for VN 6ce98eb4-cbe5-454f-8f54-7a321aa7adc4 2026-01-19 15:09:33,995 - INFO - Verifications in API Server for VN ctest-vn-19417712 passed 2026-01-19 15:09:33,995 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/6ce98eb4-cbe5-454f-8f54-7a321aa7adc4 2026-01-19 15:09:34,007 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/e51e6c8a-52d5-47b0-ab38-08dc170ee310 2026-01-19 15:09:34,016 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/e51e6c8a-52d5-47b0-ab38-08dc170ee310 2026-01-19 15:09:34,025 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/7b42c80e-c915-4ddf-bd3f-249d22c1bf14 2026-01-19 15:09:34,037 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicVMVN-31955753:ctest-vn-19417712', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicVMVN-31955753', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7847960732835857743', 'uuid-lslong': '10328014201081540036'}, 'enable': 'true', 'created': '2026-01-19T15:09:29', 'last-modified': '2026-01-19T15:09:30', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:03.940588'} 2026-01-19 15:09:34,050 - DEBUG - Route Targets: ['target:64512:8000005'] 2026-01-19 15:09:34,060 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicVMVN-31955753:ctest-vn-19417712', 'interests': '0-1', 'advertised': '0-1', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicVMVN-31955753', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7847960732835857743', 'uuid-lslong': '10328014201081540036'}, 'enable': 'true', 'created': '2026-01-19T15:09:29', 'last-modified': '2026-01-19T15:09:30', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:03.959231'} 2026-01-19 15:09:34,067 - DEBUG - Route Targets: ['target:64512:8000005'] 2026-01-19 15:09:34,074 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicVMVN-31955753:ctest-vn-19417712', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicVMVN-31955753', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7847960732835857743', 'uuid-lslong': '10328014201081540036'}, 'enable': 'true', 'created': '2026-01-19T15:09:29', 'last-modified': '2026-01-19T15:09:30', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:03.977418'} 2026-01-19 15:09:34,080 - DEBUG - Route Targets: ['target:64512:8000005'] 2026-01-19 15:09:34,080 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn-19417712 passed 2026-01-19 15:09:34,081 - DEBUG - ====Verifying policy data for ctest-vn-19417712 in API_Server ====== 2026-01-19 15:09:34,081 - DEBUG - Requesting: http://10.0.0.22:8082/domains 2026-01-19 15:09:34,087 - DEBUG - Requesting: http://10.0.0.22:8082/domain/5a05e3ad-7c50-4a45-9ba6-ba5e0b0e3b90 2026-01-19 15:09:34,100 - DEBUG - Requesting: http://10.0.0.22:8082/project/9e05ea34-1aad-4d0c-adae-ea977e70f91b 2026-01-19 15:09:34,230 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/6ce98eb4-cbe5-454f-8f54-7a321aa7adc4 2026-01-19 15:09:34,253 - DEBUG - =>VN ctest-vn-19417712 has no policy to be verified 2026-01-19 15:09:34,253 - DEBUG - Verifying the vn in opserver 2026-01-19 15:09:34,253 - DEBUG - Verifying the default-domain:ctest-TestBasicVMVN-31955753:ctest-vn-19417712 virtual network link through opserver 10.0.0.22 2026-01-19 15:09:34,253 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks 2026-01-19 15:09:34,679 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks 2026-01-19 15:09:35,097 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicVMVN-31955753:ctest-vn-19417712', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicVMVN-31955753:ctest-vn-19417712?flat'} 2026-01-19 15:09:35,097 - DEBUG - Verifying the default-domain:ctest-TestBasicVMVN-31955753:ctest-vn-19417712 virtual network link through opserver 10.0.0.33 2026-01-19 15:09:35,097 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks 2026-01-19 15:09:35,686 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks 2026-01-19 15:09:35,784 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicVMVN-31955753:ctest-vn-19417712', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicVMVN-31955753:ctest-vn-19417712?flat'} 2026-01-19 15:09:35,784 - DEBUG - Verifying the default-domain:ctest-TestBasicVMVN-31955753:ctest-vn-19417712 virtual network link through opserver 10.0.0.27 2026-01-19 15:09:35,784 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks 2026-01-19 15:09:36,104 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks 2026-01-19 15:09:36,169 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicVMVN-31955753:ctest-vn-19417712', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicVMVN-31955753:ctest-vn-19417712?flat'} 2026-01-19 15:09:36,169 - INFO - Validated that VN default-domain:ctest-TestBasicVMVN-31955753:ctest-vn-19417712 is found in opserver 2026-01-19 15:09:36,183 - DEBUG - VRF ids for VN ctest-vn-19417712: {'10.0.0.23': '2', '10.0.0.240': '2'} 2026-01-19 15:09:36,184 - INFO - Waiting for VM ctest-TestBasicVMVN-31955753-42714728 to be up.. 2026-01-19 15:09:36,277 - DEBUG - VM is in ACTIVE state now 2026-01-19 15:09:36,277 - INFO - VM name : ctest-TestBasicVMVN-31955753-42714728 2026-01-19 15:09:36,369 - DEBUG - VM ctest-TestBasicVMVN-31955753-42714728 ID is cd92a5d6-6995-4b7b-8e20-6359340ca6e9 2026-01-19 15:09:36,369 - DEBUG - VM ctest-TestBasicVMVN-31955753-42714728 launched on Node an-jenkins-deploy-platform-ansible-os-5045-1 2026-01-19 15:09:36,456 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/cd92a5d6-6995-4b7b-8e20-6359340ca6e9 2026-01-19 15:09:36,470 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/9dd8fd36-c3d6-419f-8e56-04a9c1a903ca 2026-01-19 15:09:39,711 - 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 1028ms') 2026-01-19 15:09:39,711 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicVMVN-31955753-42714728 failed! 2026-01-19 15:09:39,727 - DEBUG - Gateway for vn default-domain:ctest-TestBasicVMVN-31955753:ctest-vn-19417712 is 31.1.1.1 and allocation pool is NOT set 2026-01-19 15:09:39,727 - DEBUG - Gateway for vn default-domain:ctest-TestBasicVMVN-31955753:ctest-vn-19417712 is 31.1.2.1 and allocation pool is NOT set 2026-01-19 15:09:43,806 - 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=8.45 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 1 received, 50% packet loss, time 1018ms\r\nrtt min/avg/max/mdev = 8.449/8.449/8.449/0.000 ms') 2026-01-19 15:09:43,806 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicVMVN-31955753-42714728 passed 2026-01-19 15:09:43,929 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-19 15:09:43,929 - DEBUG - Waiting to SSH to VM ctest-TestBasicVMVN-31955753-42714728, IP 31.1.1.4, Port 22 2026-01-19 15:09:44,100 - DEBUG - VM ctest-TestBasicVMVN-31955753-42714728 is ready for SSH connections 2026-01-19 15:09:44,100 - INFO - Waiting for VM ctest-TestBasicVMVN-31955753-69413156 to be up.. 2026-01-19 15:09:44,196 - DEBUG - VM is in ACTIVE state now 2026-01-19 15:09:44,196 - INFO - VM name : ctest-TestBasicVMVN-31955753-69413156 2026-01-19 15:09:44,298 - DEBUG - VM ctest-TestBasicVMVN-31955753-69413156 ID is 05c31de3-5d97-48cb-a1b2-01ec18c102b4 2026-01-19 15:09:44,298 - DEBUG - VM ctest-TestBasicVMVN-31955753-69413156 launched on Node an-jenkins-deploy-platform-ansible-os-5045-2 2026-01-19 15:09:44,385 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/05c31de3-5d97-48cb-a1b2-01ec18c102b4 2026-01-19 15:09:44,398 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/82c2be77-1d7f-4191-af5a-a2f80d1256f1 2026-01-19 15:09:45,596 - 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.25 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.374 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.374/1.811/3.248/1.437 ms') 2026-01-19 15:09:45,596 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicVMVN-31955753-69413156 passed 2026-01-19 15:09:45,717 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-19 15:09:45,717 - DEBUG - Waiting to SSH to VM ctest-TestBasicVMVN-31955753-69413156, IP 31.1.2.4, Port 22 2026-01-19 15:09:45,886 - DEBUG - VM ctest-TestBasicVMVN-31955753-69413156 is ready for SSH connections 2026-01-19 15:09:45,886 - INFO - Waiting for VM ctest-TestBasicVMVN-31955753-40044460 to be up.. 2026-01-19 15:09:45,991 - DEBUG - VM is in ACTIVE state now 2026-01-19 15:09:45,991 - INFO - VM name : ctest-TestBasicVMVN-31955753-40044460 2026-01-19 15:09:46,081 - DEBUG - VM ctest-TestBasicVMVN-31955753-40044460 ID is f867350b-6758-41ec-ab14-641d23cdfa5e 2026-01-19 15:09:46,081 - DEBUG - VM ctest-TestBasicVMVN-31955753-40044460 launched on Node an-jenkins-deploy-platform-ansible-os-5045-1 2026-01-19 15:09:46,179 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/f867350b-6758-41ec-ab14-641d23cdfa5e 2026-01-19 15:09:46,192 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/23d63625-ed53-4a3f-94d9-678d33b68adc 2026-01-19 15:09:47,419 - 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.86 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=3.19 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.864/3.025/3.187/0.161 ms') 2026-01-19 15:09:47,420 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-TestBasicVMVN-31955753-40044460 passed 2026-01-19 15:09:47,550 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-19 15:09:47,550 - DEBUG - Waiting to SSH to VM ctest-TestBasicVMVN-31955753-40044460, IP 31.1.1.3, Port 22 2026-01-19 15:09:47,605 - 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-01-19 15:09:47,694 - DEBUG - VM ctest-TestBasicVMVN-31955753-40044460 is NOT ready for SSH connections, VM status: ACTIVE 2026-01-19 15:09:52,695 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-19 15:09:52,695 - DEBUG - Waiting to SSH to VM ctest-TestBasicVMVN-31955753-40044460, IP 31.1.1.3, Port 22 2026-01-19 15:09:52,857 - DEBUG - VM ctest-TestBasicVMVN-31955753-40044460 is ready for SSH connections 2026-01-19 15:09:52,857 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-19 15:09:52,857 - 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.23, gateway password: c0ntrail123 2026-01-19 15:09:52,857 - DEBUG - ping -s 56 -c 3 -W 1 31.1.2.4 2026-01-19 15:09:56,593 - 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.163 ms 64 bytes from 31.1.2.4: seq=1 ttl=63 time=1.184 ms 64 bytes from 31.1.2.4: seq=2 ttl=63 time=1.255 ms --- 31.1.2.4 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 1.184/2.867/6.163 ms 2026-01-19 15:09:56,593 - INFO - Ping to IP 31.1.2.4 from VM ctest-TestBasicVMVN-31955753-42714728 passed 2026-01-19 15:09:56,593 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-19 15:09:56,593 - 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.240, gateway password: c0ntrail123 2026-01-19 15:09:56,593 - DEBUG - ping -s 56 -c 3 -W 1 31.1.1.4 2026-01-19 15:10:00,250 - 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.519 ms 64 bytes from 31.1.1.4: seq=1 ttl=63 time=1.144 ms 64 bytes from 31.1.1.4: seq=2 ttl=63 time=1.185 ms --- 31.1.1.4 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 1.144/1.949/3.519 ms 2026-01-19 15:10:00,251 - INFO - Ping to IP 31.1.1.4 from VM ctest-TestBasicVMVN-31955753-69413156 passed 2026-01-19 15:10:00,251 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-19 15:10:00,251 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-19 15:10:00,251 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-19 15:10:00,251 - 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.23, gateway password: c0ntrail123 2026-01-19 15:10:00,251 - DEBUG - echo 0 > /proc/sys/net/ipv4/icmp_echo_ignore_broadcasts 2026-01-19 15:10:00,451 - DEBUG - None 2026-01-19 15:10:00,451 - 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.240, gateway password: c0ntrail123 2026-01-19 15:10:00,452 - DEBUG - echo 0 > /proc/sys/net/ipv4/icmp_echo_ignore_broadcasts 2026-01-19 15:10:00,637 - DEBUG - None 2026-01-19 15:10:00,637 - 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.23, gateway password: c0ntrail123 2026-01-19 15:10:00,637 - DEBUG - echo 0 > /proc/sys/net/ipv4/icmp_echo_ignore_broadcasts 2026-01-19 15:10:01,256 - DEBUG - None 2026-01-19 15:10:01,257 - 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.23, gateway password: c0ntrail123 2026-01-19 15:10:01,257 - DEBUG - ping -s 56 -c 3 -W 1 31.1.1.7 2026-01-19 15:10:03,471 - 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.824 ms 64 bytes from 31.1.1.3: seq=0 ttl=64 time=5.282 ms (DUP!) 64 bytes from 31.1.1.4: seq=1 ttl=64 time=0.419 ms 64 bytes from 31.1.1.3: seq=1 ttl=64 time=1.856 ms (DUP!) 64 bytes from 31.1.1.4: seq=2 ttl=64 time=0.339 ms --- 31.1.1.7 ping statistics --- 3 packets transmitted, 3 packets received, 2 duplicates, 0% packet loss round-trip min/avg/max = 0.339/1.944/5.282 ms 2026-01-19 15:10:03,472 - 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.23, gateway password: c0ntrail123 2026-01-19 15:10:03,472 - DEBUG - ping -s 56 -c 3 -W 1 224.0.0.1 2026-01-19 15:10:05,716 - 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=3.290 ms 64 bytes from 31.1.1.3: seq=0 ttl=64 time=5.006 ms (DUP!) 64 bytes from 31.1.2.4: seq=0 ttl=63 time=6.079 ms (DUP!) 64 bytes from 31.1.1.4: seq=1 ttl=64 time=0.466 ms 64 bytes from 31.1.1.3: seq=1 ttl=64 time=1.790 ms (DUP!) 64 bytes from 31.1.2.4: seq=1 ttl=63 time=2.582 ms (DUP!) 64 bytes from 31.1.1.4: seq=2 ttl=64 time=0.334 ms --- 224.0.0.1 ping statistics --- 3 packets transmitted, 3 packets received, 4 duplicates, 0% packet loss round-trip min/avg/max = 0.334/2.792/6.079 ms 2026-01-19 15:10:05,716 - 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.23, gateway password: c0ntrail123 2026-01-19 15:10:05,716 - DEBUG - ping -s 56 -c 3 -W 1 255.255.255.255 2026-01-19 15:10:07,955 - 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.086 ms 64 bytes from 31.1.1.3: seq=0 ttl=64 time=2.978 ms (DUP!) 64 bytes from 31.1.2.4: seq=0 ttl=63 time=3.791 ms (DUP!) 64 bytes from 31.1.1.4: seq=1 ttl=64 time=0.974 ms 64 bytes from 31.1.1.3: seq=1 ttl=64 time=2.215 ms (DUP!) 64 bytes from 31.1.2.4: seq=1 ttl=63 time=3.055 ms (DUP!) 64 bytes from 31.1.1.4: seq=2 ttl=64 time=0.354 ms --- 255.255.255.255 ping statistics --- 3 packets transmitted, 3 packets received, 4 duplicates, 0% packet loss round-trip min/avg/max = 0.354/2.064/3.791 ms 2026-01-19 15:10:07,955 - INFO - Deleting VM ctest-TestBasicVMVN-31955753-40044460 2026-01-19 15:10:08,024 - INFO - Detaching port 82c2be77-1d7f-4191-af5a-a2f80d1256f1 from VM ctest-TestBasicVMVN-31955753-69413156 2026-01-19 15:10:08,056 - INFO - Deleting VM ctest-TestBasicVMVN-31955753-69413156 2026-01-19 15:10:08,131 - INFO - Detaching port 9dd8fd36-c3d6-419f-8e56-04a9c1a903ca from VM ctest-TestBasicVMVN-31955753-42714728 2026-01-19 15:10:08,169 - INFO - Deleting VM ctest-TestBasicVMVN-31955753-42714728 2026-01-19 15:10:08,252 - INFO - Deleting VN ctest-vn-19417712 2026-01-19 15:10:08,561 - DEBUG - Response for delete_port : () 2026-01-19 15:10:08,906 - DEBUG - Response for delete_port : () 2026-01-19 15:10:09,254 - DEBUG - Response for deleting network () 2026-01-19 15:10:09,254 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/e51e6c8a-52d5-47b0-ab38-08dc170ee310 2026-01-19 15:10:09,260 - DEBUG - Response Code: 404 2026-01-19 15:10:09,261 - DEBUG - Requesting: http://10.0.0.22:8082/domains 2026-01-19 15:10:09,267 - DEBUG - Requesting: http://10.0.0.22:8082/domain/5a05e3ad-7c50-4a45-9ba6-ba5e0b0e3b90 2026-01-19 15:10:09,285 - DEBUG - Requesting: http://10.0.0.22:8082/project/9e05ea34-1aad-4d0c-adae-ea977e70f91b 2026-01-19 15:10:09,385 - INFO - Validated that VN ctest-vn-19417712 is not found in API Server 2026-01-19 15:10:09,395 - DEBUG - VN ctest-vn-19417712 is not present in Agent 10.0.0.23 2026-01-19 15:10:09,404 - DEBUG - VN ctest-vn-19417712 is not present in Agent 10.0.0.240 2026-01-19 15:10:09,404 - INFO - Validated that VN ctest-vn-19417712 is not in any agent 2026-01-19 15:10:09,410 - DEBUG - VRF 2 is not seen in agent 10.0.0.23 2026-01-19 15:10:09,417 - DEBUG - Vrouter 10.0.0.23 does not have vrf 2 for VN ctest-vn-19417712 2026-01-19 15:10:09,424 - DEBUG - VRF 2 is not seen in agent 10.0.0.240 2026-01-19 15:10:09,432 - DEBUG - Vrouter 10.0.0.240 does not have vrf 2 for VN ctest-vn-19417712 2026-01-19 15:10:09,432 - INFO - Validated that all vrouters do not have the route table for VN default-domain:ctest-TestBasicVMVN-31955753:ctest-vn-19417712 2026-01-19 15:10:09,454 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn-19417712 info 2026-01-19 15:10:09,562 - INFO - IPAM: ctest-ipam-89846464 is not found in API Server 2026-01-19 15:10:09,588 - INFO - IPAM:ctest-ipam-89846464 is not found in control node 2026-01-19 15:10:10,890 - DEBUG - No XMPP flaps were noticed during the test
2026-01-19 15:10:10,890 - INFO - END TEST : test_ping_within_vn_two_vms_two_different_subnets : PASSED[0:00:43]
2026-01-19 15:10:10,890 - INFO - -------------------------------------------------------------------------------- 2026-01-19 15:10:11,741 - INFO - Deleted project: ctest-TestBasicVMVN-31955753, ID : 9e05ea34-1aad-4d0c-adae-ea977e70f91b