2026-01-19 03:18:51,101 - INFO - Domain Default found not creating 2026-01-19 03:18:51,266 - INFO - Project ctest-TestBasicVMVN-46250458 not found, creating it 2026-01-19 03:18:51,804 - INFO - Created Project:ctest-TestBasicVMVN-46250458, ID : bec8cec2-0393-4967-a16c-66efa05a66f0 2026-01-19 03:18:53,301 - INFO - ================================================================================ 2026-01-19 03:18:53,301 - INFO - STARTING TEST : test_generic_link_local_service 2026-01-19 03:18:53,301 - 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 03:18:54,579 - DEBUG - Nothing to compare xmpp stats {'10.0.0.249': {'10.20.0.25': '0', '10.20.0.193': '0'}, '10.0.0.65': {'10.20.0.17': '0', '10.20.0.25': '0'}} with 2026-01-19 03:18:54,579 - INFO - Initial checks done. Running the testcase now 2026-01-19 03:18:54,579 - INFO - 2026-01-19 03:18:55,272 - DEBUG - Response for create_network : {'network': {'id': '57f05da1-00c3-4826-aa17-644c19f308cf', 'name': 'ctest-vn2_metadata-00055434', 'tenant_id': 'bec8cec203934967a16c66efa05a66f0', 'project_id': 'bec8cec203934967a16c66efa05a66f0', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicVMVN-46250458', 'ctest-vn2_metadata-00055434'], 'port_security_enabled': True, 'description': ''}} 2026-01-19 03:18:55,534 - DEBUG - Response for create_subnet : {'subnet': {'id': '4e5e55d6-0288-43df-8c32-443f96cadf9d', 'name': '', 'tenant_id': 'bec8cec203934967a16c66efa05a66f0', 'network_id': '57f05da1-00c3-4826-aa17-644c19f308cf', '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': 'bec8cec203934967a16c66efa05a66f0'}} 2026-01-19 03:18:55,557 - INFO - Created VN ctest-vn2_metadata-00055434 2026-01-19 03:18:55,570 - DEBUG - VN ctest-vn2_metadata-00055434 UUID is 57f05da1-00c3-4826-aa17-644c19f308cf 2026-01-19 03:18:55,732 - DEBUG - Services list from nova: [, , , ] 2026-01-19 03:18:56,854 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5040-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5040-1) 2026-01-19 03:18:56,946 - DEBUG - Link local service introspect added 2026-01-19 03:18:56,998 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-01-19 03:19:02,087 - DEBUG - VM is in ACTIVE state now 2026-01-19 03:19:02,228 - INFO - Waiting for VM ctest-TestBasicVMVN-46250458-20979427 to be up.. 2026-01-19 03:19:02,228 - INFO - VM name : ctest-TestBasicVMVN-46250458-20979427 2026-01-19 03:19:02,314 - DEBUG - VM ctest-TestBasicVMVN-46250458-20979427 ID is f39a0ba2-e730-4800-a6b2-6215371d211b 2026-01-19 03:19:02,314 - DEBUG - VM ctest-TestBasicVMVN-46250458-20979427 launched on Node an-jenkins-deploy-platform-ansible-os-5040-1 2026-01-19 03:19:02,404 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/f39a0ba2-e730-4800-a6b2-6215371d211b 2026-01-19 03:19:02,710 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/f39a0ba2-e730-4800-a6b2-6215371d211b 2026-01-19 03:19:02,750 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/d5d03e6d-9b76-4326-bf4b-75884b0fca3e 2026-01-19 03:19:05,921 - 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 1015ms') 2026-01-19 03:19:05,921 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicVMVN-46250458-20979427 failed! 2026-01-19 03:19:05,934 - DEBUG - Gateway for vn default-domain:ctest-TestBasicVMVN-46250458:ctest-vn2_metadata-00055434 is 11.1.1.1 and allocation pool is NOT set 2026-01-19 03:19:08,001 - 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=10.3 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=1.44 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 = 1.443/5.854/10.265/4.411 ms') 2026-01-19 03:19:08,001 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicVMVN-46250458-20979427 passed 2026-01-19 03:19:08,085 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-19 03:19:08,085 - DEBUG - Waiting to SSH to VM ctest-TestBasicVMVN-46250458-20979427, IP 11.1.1.3, Port 22 2026-01-19 03:19:08,256 - DEBUG - VM ctest-TestBasicVMVN-46250458-20979427 is ready for SSH connections 2026-01-19 03:19:08,256 - INFO - Retry 0 2026-01-19 03:19:08,256 - 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.249, gateway password: c0ntrail123 2026-01-19 03:19:08,256 - DEBUG - wget http://169.254.1.2:8083 --spider && echo "Successful" 2026-01-19 03:19:10,161 - DEBUG - Connecting to 169.254.1.2:8083 (169.254.1.2:8083) Successful 2026-01-19 03:19:10,161 - INFO - Generic metadata worked 2026-01-19 03:19:10,391 - DEBUG - Link local service introspect removed 2026-01-19 03:19:10,391 - INFO - Deleting VM ctest-TestBasicVMVN-46250458-20979427 2026-01-19 03:19:10,460 - INFO - Deleting VN ctest-vn2_metadata-00055434 2026-01-19 03:19:10,497 - DEBUG - VN 57f05da1-00c3-4826-aa17-644c19f308cf still in use: Unable to complete operation on network 57f05da1-00c3-4826-aa17-644c19f308cf. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-eca91f90-8039-43ef-a3a6-9820256d7978'] 2026-01-19 03:19:10,497 - WARNING - Deleting VN ctest-vn2_metadata-00055434 failed..Will retry 2026-01-19 03:19:12,659 - DEBUG - Response for deleting network () 2026-01-19 03:19:13,924 - DEBUG - No XMPP flaps were noticed during the test 2026-01-19 03:19:13,925 - INFO - -------------------------------------------------------------------------------- 2026-01-19 03:19:13,927 - INFO - ================================================================================ 2026-01-19 03:19:13,927 - INFO - STARTING TEST : test_metadata_service 2026-01-19 03:19:13,927 - 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 03:19:15,205 - DEBUG - Nothing to compare xmpp stats {'10.0.0.249': {'10.20.0.25': '0', '10.20.0.193': '0'}, '10.0.0.65': {'10.20.0.17': '0', '10.20.0.25': '0'}} with 2026-01-19 03:19:15,205 - INFO - Initial checks done. Running the testcase now 2026-01-19 03:19:15,205 - INFO - 2026-01-19 03:19:15,205 - DEBUG - Requesting: http://10.0.0.27:8082/global-vrouter-configs 2026-01-19 03:19:15,512 - DEBUG - Requesting: http://10.0.0.27:8082/global-vrouter-configs 2026-01-19 03:19:15,546 - DEBUG - Requesting: http://10.0.0.27:8082/global-vrouter-config/3436b87a-a242-4b32-b01b-c8cc3fbe15da 2026-01-19 03:19:15,812 - DEBUG - Response for create_network : {'network': {'id': '0fb7351d-9fbd-45c6-85a2-16490a33eba7', 'name': 'ctest-vn-51806520', 'tenant_id': 'bec8cec203934967a16c66efa05a66f0', 'project_id': 'bec8cec203934967a16c66efa05a66f0', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicVMVN-46250458', 'ctest-vn-51806520'], 'port_security_enabled': True, 'description': ''}} 2026-01-19 03:19:16,058 - DEBUG - Response for create_subnet : {'subnet': {'id': '39d3caf0-a668-4b31-a659-8fb45be6cb2f', 'name': '', 'tenant_id': 'bec8cec203934967a16c66efa05a66f0', 'network_id': '0fb7351d-9fbd-45c6-85a2-16490a33eba7', 'ip_version': 4, 'cidr': '49.51.193.0/26', 'allocation_pools': [{'start': '49.51.193.2', 'end': '49.51.193.62'}], 'gateway_ip': '49.51.193.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '49.51.193.2', 'tags': [], 'project_id': 'bec8cec203934967a16c66efa05a66f0'}} 2026-01-19 03:19:16,082 - INFO - Created VN ctest-vn-51806520 2026-01-19 03:19:16,094 - DEBUG - VN ctest-vn-51806520 UUID is 0fb7351d-9fbd-45c6-85a2-16490a33eba7 2026-01-19 03:19:17,216 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5040-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5040-2) 2026-01-19 03:19:17,216 - INFO - Waiting for VM ctest-TestBasicVMVN-46250458-05516250 to be up.. 2026-01-19 03:19:17,269 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-01-19 03:19:22,352 - DEBUG - VM is in ACTIVE state now 2026-01-19 03:19:22,352 - INFO - VM name : ctest-TestBasicVMVN-46250458-05516250 2026-01-19 03:19:22,450 - DEBUG - VM ctest-TestBasicVMVN-46250458-05516250 ID is ea3f8ced-689a-4169-9c23-2698e196535f 2026-01-19 03:19:22,450 - DEBUG - VM ctest-TestBasicVMVN-46250458-05516250 launched on Node an-jenkins-deploy-platform-ansible-os-5040-2 2026-01-19 03:19:22,543 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/ea3f8ced-689a-4169-9c23-2698e196535f 2026-01-19 03:19:22,555 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/e2d86cf2-baf4-4c57-8c66-71400cc63938 2026-01-19 03:19:25,753 - 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 1033ms') 2026-01-19 03:19:25,753 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicVMVN-46250458-05516250 failed! 2026-01-19 03:19:25,769 - DEBUG - Gateway for vn default-domain:ctest-TestBasicVMVN-46250458:ctest-vn-51806520 is 49.51.193.1 and allocation pool is NOT set 2026-01-19 03:19:27,828 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n64 bytes from 169.254.0.3: icmp_seq=1 ttl=63 time=4.48 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.467 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.467/2.471/4.476/2.004 ms') 2026-01-19 03:19:27,828 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicVMVN-46250458-05516250 passed 2026-01-19 03:19:27,904 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-19 03:19:27,904 - DEBUG - Waiting to SSH to VM ctest-TestBasicVMVN-46250458-05516250, IP 49.51.193.3, Port 22 2026-01-19 03:19:28,057 - DEBUG - VM ctest-TestBasicVMVN-46250458-05516250 is ready for SSH connections 2026-01-19 03:19:28,057 - DEBUG - Running remote_cmd, Cmd : cat /tmp/output.txt, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.65, gateway password: c0ntrail123 2026-01-19 03:19:28,057 - DEBUG - cat /tmp/output.txt 2026-01-19 03:19:29,662 - DEBUG - Hello World. The time is now Sun, 18 Jan 2026 20:19:26 -0700! 2026-01-19 03:19:29,662 - DEBUG - ret : {'cat /tmp/output.txt': 'Hello World. The time is now Sun, 18 Jan 2026 20:19:26 -0700!'} 2026-01-19 03:19:29,662 - INFO - metadata_script.txt got executed in the vm 2026-01-19 03:19:29,662 - INFO - Deleting VM ctest-TestBasicVMVN-46250458-05516250 2026-01-19 03:19:29,726 - INFO - Deleting VN ctest-vn-51806520 2026-01-19 03:19:29,762 - DEBUG - VN 0fb7351d-9fbd-45c6-85a2-16490a33eba7 still in use: Unable to complete operation on network 0fb7351d-9fbd-45c6-85a2-16490a33eba7. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-cb26e692-fd4b-49c6-a913-1a16760944b3'] 2026-01-19 03:19:29,762 - WARNING - Deleting VN ctest-vn-51806520 failed..Will retry 2026-01-19 03:19:31,924 - DEBUG - Response for deleting network () 2026-01-19 03:19:33,186 - DEBUG - No XMPP flaps were noticed during the test
2026-01-19 03:19:33,186 - INFO - END TEST : test_metadata_service : PASSED[0:00:20]
2026-01-19 03:19:33,186 - INFO - -------------------------------------------------------------------------------- 2026-01-19 03:19:33,188 - INFO - ================================================================================ 2026-01-19 03:19:33,189 - INFO - STARTING TEST : test_ping_within_vn_two_vms_two_different_subnets 2026-01-19 03:19:33,189 - 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 03:19:34,483 - DEBUG - Nothing to compare xmpp stats {'10.0.0.249': {'10.20.0.25': '0', '10.20.0.193': '0'}, '10.0.0.65': {'10.20.0.17': '0', '10.20.0.25': '0'}} with 2026-01-19 03:19:34,483 - INFO - Initial checks done. Running the testcase now 2026-01-19 03:19:34,483 - INFO - 2026-01-19 03:19:35,145 - DEBUG - Response for create_network : {'network': {'id': 'df8bc363-7daa-40ff-b782-770b29c711f6', 'name': 'ctest-vn-44407006', 'tenant_id': 'bec8cec203934967a16c66efa05a66f0', 'project_id': 'bec8cec203934967a16c66efa05a66f0', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicVMVN-46250458', 'ctest-vn-44407006'], 'port_security_enabled': True, 'description': ''}} 2026-01-19 03:19:35,413 - DEBUG - Response for create_subnet : {'subnet': {'id': '2d644bcf-4be5-437b-aa6a-d000c81461fd', 'name': '', 'tenant_id': 'bec8cec203934967a16c66efa05a66f0', 'network_id': 'df8bc363-7daa-40ff-b782-770b29c711f6', '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': 'bec8cec203934967a16c66efa05a66f0'}} 2026-01-19 03:19:35,681 - DEBUG - Response for create_subnet : {'subnet': {'id': 'd0338b6f-744d-44dc-87d3-e974db576bf8', 'name': '', 'tenant_id': 'bec8cec203934967a16c66efa05a66f0', 'network_id': 'df8bc363-7daa-40ff-b782-770b29c711f6', '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': 'bec8cec203934967a16c66efa05a66f0'}} 2026-01-19 03:19:35,706 - INFO - Created VN ctest-vn-44407006 2026-01-19 03:19:35,722 - DEBUG - VN ctest-vn-44407006 UUID is df8bc363-7daa-40ff-b782-770b29c711f6 2026-01-19 03:19:36,137 - DEBUG - Response for create_port : {'port': {'name': '48d914fd-d6cb-4513-a5e0-62fd8922cfe0', 'id': '48d914fd-d6cb-4513-a5e0-62fd8922cfe0', 'tenant_id': 'bec8cec203934967a16c66efa05a66f0', 'network_id': 'df8bc363-7daa-40ff-b782-770b29c711f6', 'mac_address': '02:48:d9:14:fd:d6', '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': '2d644bcf-4be5-437b-aa6a-d000c81461fd'}], 'security_groups': ['22de2f66-388d-4d81-a15c-80605cf7ab2f'], 'admin_state_up': True, 'device_id': '', 'device_owner': '', 'status': 'DOWN', 'port_security_enabled': True, 'description': '', 'project_id': 'bec8cec203934967a16c66efa05a66f0'}} 2026-01-19 03:19:36,409 - DEBUG - Response for create_port : {'port': {'name': 'aa1f01bd-2c49-45de-a951-057350a70d0c', 'id': 'aa1f01bd-2c49-45de-a951-057350a70d0c', 'tenant_id': 'bec8cec203934967a16c66efa05a66f0', 'network_id': 'df8bc363-7daa-40ff-b782-770b29c711f6', 'mac_address': '02:aa:1f:01:bd:2c', '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': 'd0338b6f-744d-44dc-87d3-e974db576bf8'}], 'security_groups': ['22de2f66-388d-4d81-a15c-80605cf7ab2f'], 'admin_state_up': True, 'device_id': '', 'device_owner': '', 'status': 'DOWN', 'port_security_enabled': True, 'description': '', 'project_id': 'bec8cec203934967a16c66efa05a66f0'}} 2026-01-19 03:19:36,877 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5040-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5040-1) 2026-01-19 03:19:37,433 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5040-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5040-2) 2026-01-19 03:19:38,897 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5040-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5040-1) 2026-01-19 03:19:38,898 - DEBUG - Requesting: http://10.0.0.27:8082/domains 2026-01-19 03:19:38,904 - DEBUG - Requesting: http://10.0.0.27:8082/domain/bc123c74-c45e-4d7a-a955-76f59d7a4cc7 2026-01-19 03:19:38,918 - DEBUG - Requesting: http://10.0.0.27:8082/project/bec8cec2-0393-4967-a16c-66efa05a66f0 2026-01-19 03:19:39,008 - DEBUG - Requesting: http://10.0.0.27:8082/network-ipam/7fe9314d-5fc0-407c-adfc-4a8d410a8f03 2026-01-19 03:19:39,021 - INFO - Verifications in API Server for IPAM: ctest-ipam-65487501 passed 2026-01-19 03:19:39,028 - DEBUG - Control-node 10.0.0.27 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestBasicVMVN-46250458:ctest-ipam-65487501', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicVMVN-46250458', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9216952320779108476', 'uuid-lslong': '12536977433234673411'}, 'enable': 'true', 'created': '2026-01-19T03:19:34', 'last-modified': '2026-01-19T03:19:34', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:04.270909'} 2026-01-19 03:19:39,034 - DEBUG - Control-node 10.0.0.50 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestBasicVMVN-46250458:ctest-ipam-65487501', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicVMVN-46250458', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9216952320779108476', 'uuid-lslong': '12536977433234673411'}, 'enable': 'true', 'created': '2026-01-19T03:19:34', 'last-modified': '2026-01-19T03:19:34', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:04.281362'} 2026-01-19 03:19:39,040 - DEBUG - Control-node 10.0.0.23 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestBasicVMVN-46250458:ctest-ipam-65487501', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicVMVN-46250458', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9216952320779108476', 'uuid-lslong': '12536977433234673411'}, 'enable': 'true', 'created': '2026-01-19T03:19:34', 'last-modified': '2026-01-19T03:19:34', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:04.282240'} 2026-01-19 03:19:39,040 - INFO - Verifications in Control node for IPAM: ctest-ipam-65487501 passed 2026-01-19 03:19:39,040 - DEBUG - Requesting: http://10.0.0.27:8082/domains 2026-01-19 03:19:39,050 - DEBUG - Requesting: http://10.0.0.27:8082/domain/bc123c74-c45e-4d7a-a955-76f59d7a4cc7 2026-01-19 03:19:39,064 - DEBUG - Requesting: http://10.0.0.27:8082/project/bec8cec2-0393-4967-a16c-66efa05a66f0 2026-01-19 03:19:39,171 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/df8bc363-7daa-40ff-b782-770b29c711f6 2026-01-19 03:19:39,185 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/df8bc363-7daa-40ff-b782-770b29c711f6 2026-01-19 03:19:39,197 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/dc3fc4d1-6bcf-4022-b364-caa8893954fa 2026-01-19 03:19:39,208 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/dc3fc4d1-6bcf-4022-b364-caa8893954fa 2026-01-19 03:19:39,224 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/f19b4562-7337-4a4b-b774-4688f0fc399d 2026-01-19 03:19:39,231 - DEBUG - Route Targets: ['target:64512:8000004'] 2026-01-19 03:19:39,231 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/df8bc363-7daa-40ff-b782-770b29c711f6 2026-01-19 03:19:39,242 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/dc3fc4d1-6bcf-4022-b364-caa8893954fa 2026-01-19 03:19:39,259 - INFO - Verified VN network id 16 for VN df8bc363-7daa-40ff-b782-770b29c711f6 2026-01-19 03:19:39,259 - INFO - Verifications in API Server for VN ctest-vn-44407006 passed 2026-01-19 03:19:39,259 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/df8bc363-7daa-40ff-b782-770b29c711f6 2026-01-19 03:19:39,270 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/dc3fc4d1-6bcf-4022-b364-caa8893954fa 2026-01-19 03:19:39,278 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/dc3fc4d1-6bcf-4022-b364-caa8893954fa 2026-01-19 03:19:39,290 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/f19b4562-7337-4a4b-b774-4688f0fc399d 2026-01-19 03:19:39,305 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicVMVN-46250458:ctest-vn-44407006', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicVMVN-46250458', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '16108183324298199295', 'uuid-lslong': '13223262345742455286'}, 'enable': 'true', 'created': '2026-01-19T03:19:35', 'last-modified': '2026-01-19T03:19:35', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:03.681484'} 2026-01-19 03:19:39,313 - DEBUG - Route Targets: ['target:64512:8000004'] 2026-01-19 03:19:39,323 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicVMVN-46250458:ctest-vn-44407006', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicVMVN-46250458', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '16108183324298199295', 'uuid-lslong': '13223262345742455286'}, 'enable': 'true', 'created': '2026-01-19T03:19:35', 'last-modified': '2026-01-19T03:19:35', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:03.706454'} 2026-01-19 03:19:39,331 - DEBUG - Route Targets: ['target:64512:8000004'] 2026-01-19 03:19:39,339 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicVMVN-46250458:ctest-vn-44407006', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicVMVN-46250458', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '16108183324298199295', 'uuid-lslong': '13223262345742455286'}, 'enable': 'true', 'created': '2026-01-19T03:19:35', 'last-modified': '2026-01-19T03:19:35', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:03.717756'} 2026-01-19 03:19:39,347 - DEBUG - Route Targets: ['target:64512:8000004'] 2026-01-19 03:19:39,348 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn-44407006 passed 2026-01-19 03:19:39,348 - DEBUG - ====Verifying policy data for ctest-vn-44407006 in API_Server ====== 2026-01-19 03:19:39,348 - DEBUG - Requesting: http://10.0.0.27:8082/domains 2026-01-19 03:19:39,359 - DEBUG - Requesting: http://10.0.0.27:8082/domain/bc123c74-c45e-4d7a-a955-76f59d7a4cc7 2026-01-19 03:19:39,370 - DEBUG - Requesting: http://10.0.0.27:8082/project/bec8cec2-0393-4967-a16c-66efa05a66f0 2026-01-19 03:19:39,494 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/df8bc363-7daa-40ff-b782-770b29c711f6 2026-01-19 03:19:39,520 - DEBUG - =>VN ctest-vn-44407006 has no policy to be verified 2026-01-19 03:19:39,520 - DEBUG - Verifying the vn in opserver 2026-01-19 03:19:39,520 - DEBUG - Verifying the default-domain:ctest-TestBasicVMVN-46250458:ctest-vn-44407006 virtual network link through opserver 10.0.0.27 2026-01-19 03:19:39,520 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks 2026-01-19 03:19:39,913 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks 2026-01-19 03:19:39,980 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicVMVN-46250458:ctest-vn-44407006', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicVMVN-46250458:ctest-vn-44407006?flat'} 2026-01-19 03:19:39,980 - DEBUG - Verifying the default-domain:ctest-TestBasicVMVN-46250458:ctest-vn-44407006 virtual network link through opserver 10.0.0.50 2026-01-19 03:19:39,980 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks 2026-01-19 03:19:40,283 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks 2026-01-19 03:19:40,342 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicVMVN-46250458:ctest-vn-44407006', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicVMVN-46250458:ctest-vn-44407006?flat'} 2026-01-19 03:19:40,342 - DEBUG - Verifying the default-domain:ctest-TestBasicVMVN-46250458:ctest-vn-44407006 virtual network link through opserver 10.0.0.23 2026-01-19 03:19:40,342 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks 2026-01-19 03:19:40,643 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks 2026-01-19 03:19:40,705 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicVMVN-46250458:ctest-vn-44407006', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicVMVN-46250458:ctest-vn-44407006?flat'} 2026-01-19 03:19:40,706 - INFO - Validated that VN default-domain:ctest-TestBasicVMVN-46250458:ctest-vn-44407006 is found in opserver 2026-01-19 03:19:40,716 - DEBUG - VRF ids for VN ctest-vn-44407006: {'10.0.0.249': '2', '10.0.0.65': '2'} 2026-01-19 03:19:40,716 - INFO - Waiting for VM ctest-TestBasicVMVN-46250458-30167434 to be up.. 2026-01-19 03:19:40,808 - DEBUG - VM is in ACTIVE state now 2026-01-19 03:19:40,808 - INFO - VM name : ctest-TestBasicVMVN-46250458-30167434 2026-01-19 03:19:40,893 - DEBUG - VM ctest-TestBasicVMVN-46250458-30167434 ID is cc07318f-65f2-4160-98f2-eaaf65c04eb5 2026-01-19 03:19:40,893 - DEBUG - VM ctest-TestBasicVMVN-46250458-30167434 launched on Node an-jenkins-deploy-platform-ansible-os-5040-1 2026-01-19 03:19:40,980 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/cc07318f-65f2-4160-98f2-eaaf65c04eb5 2026-01-19 03:19:40,995 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/48d914fd-d6cb-4513-a5e0-62fd8922cfe0 2026-01-19 03:19:44,227 - 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 1029ms') 2026-01-19 03:19:44,227 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicVMVN-46250458-30167434 failed! 2026-01-19 03:19:44,242 - DEBUG - Gateway for vn default-domain:ctest-TestBasicVMVN-46250458:ctest-vn-44407006 is 31.1.1.1 and allocation pool is NOT set 2026-01-19 03:19:44,242 - DEBUG - Gateway for vn default-domain:ctest-TestBasicVMVN-46250458:ctest-vn-44407006 is 31.1.2.1 and allocation pool is NOT set 2026-01-19 03:19:48,327 - 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 1022ms') 2026-01-19 03:19:48,327 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicVMVN-46250458-30167434 failed! 2026-01-19 03:19:48,341 - DEBUG - Gateway for vn default-domain:ctest-TestBasicVMVN-46250458:ctest-vn-44407006 is 31.1.1.1 and allocation pool is NOT set 2026-01-19 03:19:48,341 - DEBUG - Gateway for vn default-domain:ctest-TestBasicVMVN-46250458:ctest-vn-44407006 is 31.1.2.1 and allocation pool is NOT set 2026-01-19 03:19:50,400 - 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=6.00 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.426 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.426/3.211/5.997/2.785 ms') 2026-01-19 03:19:50,400 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicVMVN-46250458-30167434 passed 2026-01-19 03:19:50,472 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-19 03:19:50,472 - DEBUG - Waiting to SSH to VM ctest-TestBasicVMVN-46250458-30167434, IP 31.1.1.4, Port 22 2026-01-19 03:19:50,644 - DEBUG - VM ctest-TestBasicVMVN-46250458-30167434 is ready for SSH connections 2026-01-19 03:19:50,644 - INFO - Waiting for VM ctest-TestBasicVMVN-46250458-60192914 to be up.. 2026-01-19 03:19:50,763 - DEBUG - VM is in ACTIVE state now 2026-01-19 03:19:50,763 - INFO - VM name : ctest-TestBasicVMVN-46250458-60192914 2026-01-19 03:19:50,857 - DEBUG - VM ctest-TestBasicVMVN-46250458-60192914 ID is 0dcc8f35-771d-4ba8-ab97-af1932f58067 2026-01-19 03:19:50,857 - DEBUG - VM ctest-TestBasicVMVN-46250458-60192914 launched on Node an-jenkins-deploy-platform-ansible-os-5040-2 2026-01-19 03:19:50,955 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/0dcc8f35-771d-4ba8-ab97-af1932f58067 2026-01-19 03:19:50,966 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/aa1f01bd-2c49-45de-a951-057350a70d0c 2026-01-19 03:19:52,135 - 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=2.46 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.412 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.412/1.434/2.456/1.022 ms') 2026-01-19 03:19:52,136 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicVMVN-46250458-60192914 passed 2026-01-19 03:19:52,208 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-19 03:19:52,209 - DEBUG - Waiting to SSH to VM ctest-TestBasicVMVN-46250458-60192914, IP 31.1.2.4, Port 22 2026-01-19 03:19:52,364 - DEBUG - VM ctest-TestBasicVMVN-46250458-60192914 is ready for SSH connections 2026-01-19 03:19:52,364 - INFO - Waiting for VM ctest-TestBasicVMVN-46250458-34156827 to be up.. 2026-01-19 03:19:52,442 - DEBUG - VM is in ACTIVE state now 2026-01-19 03:19:52,442 - INFO - VM name : ctest-TestBasicVMVN-46250458-34156827 2026-01-19 03:19:52,528 - DEBUG - VM ctest-TestBasicVMVN-46250458-34156827 ID is d161e5e4-5dc5-4c83-aaec-931dcafe8c59 2026-01-19 03:19:52,528 - DEBUG - VM ctest-TestBasicVMVN-46250458-34156827 launched on Node an-jenkins-deploy-platform-ansible-os-5040-1 2026-01-19 03:19:52,606 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/d161e5e4-5dc5-4c83-aaec-931dcafe8c59 2026-01-19 03:19:52,616 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/1ad43154-fb0c-4a50-8828-bb1de509768d 2026-01-19 03:19:53,780 - 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=3.17 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.588 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.588/1.879/3.171/1.291 ms') 2026-01-19 03:19:53,780 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-TestBasicVMVN-46250458-34156827 passed 2026-01-19 03:19:53,852 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-19 03:19:53,852 - DEBUG - Waiting to SSH to VM ctest-TestBasicVMVN-46250458-34156827, IP 31.1.1.3, Port 22 2026-01-19 03:19:54,010 - DEBUG - VM ctest-TestBasicVMVN-46250458-34156827 is ready for SSH connections 2026-01-19 03:19:54,010 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-19 03:19:54,010 - 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.249, gateway password: c0ntrail123 2026-01-19 03:19:54,011 - DEBUG - ping -s 56 -c 3 -W 1 31.1.2.4 2026-01-19 03:19:57,714 - 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=7.020 ms 64 bytes from 31.1.2.4: seq=1 ttl=63 time=1.873 ms 64 bytes from 31.1.2.4: seq=2 ttl=63 time=1.310 ms --- 31.1.2.4 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 1.310/3.401/7.020 ms 2026-01-19 03:19:57,715 - INFO - Ping to IP 31.1.2.4 from VM ctest-TestBasicVMVN-46250458-30167434 passed 2026-01-19 03:19:57,715 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-19 03:19:57,715 - 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.65, gateway password: c0ntrail123 2026-01-19 03:19:57,715 - DEBUG - ping -s 56 -c 3 -W 1 31.1.1.4 2026-01-19 03:20:01,624 - 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=2.923 ms 64 bytes from 31.1.1.4: seq=1 ttl=63 time=1.188 ms 64 bytes from 31.1.1.4: seq=2 ttl=63 time=1.292 ms --- 31.1.1.4 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 1.188/1.801/2.923 ms 2026-01-19 03:20:01,624 - INFO - Ping to IP 31.1.1.4 from VM ctest-TestBasicVMVN-46250458-60192914 passed 2026-01-19 03:20:01,625 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-19 03:20:01,625 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-19 03:20:01,625 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-19 03:20:01,625 - 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.249, gateway password: c0ntrail123 2026-01-19 03:20:01,625 - DEBUG - echo 0 > /proc/sys/net/ipv4/icmp_echo_ignore_broadcasts 2026-01-19 03:20:01,809 - DEBUG - None 2026-01-19 03:20:01,810 - 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.65, gateway password: c0ntrail123 2026-01-19 03:20:01,810 - DEBUG - echo 0 > /proc/sys/net/ipv4/icmp_echo_ignore_broadcasts 2026-01-19 03:20:01,986 - DEBUG - None 2026-01-19 03:20:01,986 - 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.249, gateway password: c0ntrail123 2026-01-19 03:20:01,986 - DEBUG - echo 0 > /proc/sys/net/ipv4/icmp_echo_ignore_broadcasts 2026-01-19 03:20:02,658 - DEBUG - None 2026-01-19 03:20:02,658 - 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.249, gateway password: c0ntrail123 2026-01-19 03:20:02,658 - DEBUG - ping -s 56 -c 3 -W 1 31.1.1.7 2026-01-19 03:20:04,870 - 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.833 ms 64 bytes from 31.1.1.3: seq=0 ttl=64 time=6.788 ms (DUP!) 64 bytes from 31.1.1.4: seq=1 ttl=64 time=1.421 ms 64 bytes from 31.1.1.3: seq=1 ttl=64 time=2.954 ms (DUP!) 64 bytes from 31.1.1.4: seq=2 ttl=64 time=0.430 ms --- 31.1.1.7 ping statistics --- 3 packets transmitted, 3 packets received, 2 duplicates, 0% packet loss round-trip min/avg/max = 0.430/2.685/6.788 ms 2026-01-19 03:20:04,870 - 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.249, gateway password: c0ntrail123 2026-01-19 03:20:04,870 - DEBUG - ping -s 56 -c 3 -W 1 224.0.0.1 2026-01-19 03:20:07,111 - 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=4.500 ms 64 bytes from 31.1.1.3: seq=0 ttl=64 time=6.665 ms (DUP!) 64 bytes from 31.1.2.4: seq=0 ttl=63 time=7.761 ms (DUP!) 64 bytes from 31.1.1.4: seq=1 ttl=64 time=0.439 ms 64 bytes from 31.1.1.3: seq=1 ttl=64 time=2.067 ms (DUP!) 64 bytes from 31.1.2.4: seq=1 ttl=63 time=2.970 ms (DUP!) 64 bytes from 31.1.1.4: seq=2 ttl=64 time=0.495 ms --- 224.0.0.1 ping statistics --- 3 packets transmitted, 3 packets received, 4 duplicates, 0% packet loss round-trip min/avg/max = 0.439/3.556/7.761 ms 2026-01-19 03:20:07,111 - 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.249, gateway password: c0ntrail123 2026-01-19 03:20:07,111 - DEBUG - ping -s 56 -c 3 -W 1 255.255.255.255 2026-01-19 03:20:09,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=1.190 ms 64 bytes from 31.1.1.3: seq=0 ttl=64 time=5.643 ms (DUP!) 64 bytes from 31.1.2.4: seq=0 ttl=63 time=6.322 ms (DUP!) 64 bytes from 31.1.1.4: seq=1 ttl=64 time=0.852 ms 64 bytes from 31.1.1.3: seq=1 ttl=64 time=2.212 ms (DUP!) 64 bytes from 31.1.2.4: seq=1 ttl=63 time=3.029 ms (DUP!) 64 bytes from 31.1.1.4: seq=2 ttl=64 time=2.011 ms --- 255.255.255.255 ping statistics --- 3 packets transmitted, 3 packets received, 4 duplicates, 0% packet loss round-trip min/avg/max = 0.852/3.037/6.322 ms 2026-01-19 03:20:09,366 - INFO - Deleting VM ctest-TestBasicVMVN-46250458-34156827 2026-01-19 03:20:09,436 - INFO - Detaching port aa1f01bd-2c49-45de-a951-057350a70d0c from VM ctest-TestBasicVMVN-46250458-60192914 2026-01-19 03:20:09,466 - INFO - Deleting VM ctest-TestBasicVMVN-46250458-60192914 2026-01-19 03:20:09,542 - INFO - Detaching port 48d914fd-d6cb-4513-a5e0-62fd8922cfe0 from VM ctest-TestBasicVMVN-46250458-30167434 2026-01-19 03:20:09,573 - INFO - Deleting VM ctest-TestBasicVMVN-46250458-30167434 2026-01-19 03:20:09,642 - INFO - Deleting VN ctest-vn-44407006 2026-01-19 03:20:09,871 - DEBUG - Response for delete_port : () 2026-01-19 03:20:10,152 - DEBUG - Response for delete_port : () 2026-01-19 03:20:10,214 - DEBUG - VN df8bc363-7daa-40ff-b782-770b29c711f6 still in use: Unable to complete operation on network df8bc363-7daa-40ff-b782-770b29c711f6. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-dff596b0-e003-4b65-b100-a839127ec287'] 2026-01-19 03:20:10,214 - WARNING - Deleting VN ctest-vn-44407006 failed..Will retry 2026-01-19 03:20:12,426 - DEBUG - Response for deleting network () 2026-01-19 03:20:12,426 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/dc3fc4d1-6bcf-4022-b364-caa8893954fa 2026-01-19 03:20:12,435 - DEBUG - Response Code: 404 2026-01-19 03:20:12,435 - DEBUG - Requesting: http://10.0.0.27:8082/domains 2026-01-19 03:20:12,441 - DEBUG - Requesting: http://10.0.0.27:8082/domain/bc123c74-c45e-4d7a-a955-76f59d7a4cc7 2026-01-19 03:20:12,453 - DEBUG - Requesting: http://10.0.0.27:8082/project/bec8cec2-0393-4967-a16c-66efa05a66f0 2026-01-19 03:20:12,546 - INFO - Validated that VN ctest-vn-44407006 is not found in API Server 2026-01-19 03:20:12,559 - DEBUG - VN ctest-vn-44407006 is not present in Agent 10.0.0.249 2026-01-19 03:20:12,569 - DEBUG - VN ctest-vn-44407006 is not present in Agent 10.0.0.65 2026-01-19 03:20:12,569 - INFO - Validated that VN ctest-vn-44407006 is not in any agent 2026-01-19 03:20:12,573 - DEBUG - VRF 2 is not seen in agent 10.0.0.249 2026-01-19 03:20:12,578 - DEBUG - Vrouter 10.0.0.249 does not have vrf 2 for VN ctest-vn-44407006 2026-01-19 03:20:12,583 - DEBUG - VRF 2 is not seen in agent 10.0.0.65 2026-01-19 03:20:12,588 - DEBUG - Vrouter 10.0.0.65 does not have vrf 2 for VN ctest-vn-44407006 2026-01-19 03:20:12,588 - INFO - Validated that all vrouters do not have the route table for VN default-domain:ctest-TestBasicVMVN-46250458:ctest-vn-44407006 2026-01-19 03:20:12,607 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn-44407006 info 2026-01-19 03:20:12,702 - INFO - IPAM: ctest-ipam-65487501 is not found in API Server 2026-01-19 03:20:12,726 - INFO - IPAM:ctest-ipam-65487501 is not found in control node 2026-01-19 03:20:14,016 - DEBUG - No XMPP flaps were noticed during the test
2026-01-19 03:20:14,016 - INFO - END TEST : test_ping_within_vn_two_vms_two_different_subnets : PASSED[0:00:41]
2026-01-19 03:20:14,016 - INFO - -------------------------------------------------------------------------------- 2026-01-19 03:20:14,740 - INFO - Deleted project: ctest-TestBasicVMVN-46250458, ID : bec8cec2-0393-4967-a16c-66efa05a66f0