2024-10-25 18:00:20,614 - INFO - Domain Default found not creating 2024-10-25 18:00:20,823 - INFO - Project ctest-TestBasicVMVN-44388096 not found, creating it 2024-10-25 18:00:21,393 - INFO - Created Project:ctest-TestBasicVMVN-44388096, ID : 76783609-a9d7-419d-a55e-75b5cd8c3e15 2024-10-25 18:00:23,226 - INFO - ================================================================================ 2024-10-25 18:00:23,226 - INFO - STARTING TEST : test_generic_link_local_service 2024-10-25 18:00:23,226 - 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 2024-10-25 18:00:23,511 - DEBUG - Nothing to compare xmpp stats {'10.0.0.28': {'10.20.0.11': '0'}} with 2024-10-25 18:00:23,511 - INFO - Initial checks done. Running the testcase now 2024-10-25 18:00:23,511 - INFO - 2024-10-25 18:00:24,216 - DEBUG - Response for create_network : {'network': {'id': '63ddecb5-4d9d-44ee-95b1-99a4650546de', 'name': 'ctest-vn2_metadata-38267161', 'tenant_id': '76783609a9d7419da55e75b5cd8c3e15', 'project_id': '76783609a9d7419da55e75b5cd8c3e15', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicVMVN-44388096', 'ctest-vn2_metadata-38267161'], 'port_security_enabled': True, 'description': ''}} 2024-10-25 18:00:24,439 - DEBUG - Response for create_subnet : {'subnet': {'id': '8f206a49-31b8-4c30-996b-fe96f4dbdc09', 'name': '', 'tenant_id': '76783609a9d7419da55e75b5cd8c3e15', 'network_id': '63ddecb5-4d9d-44ee-95b1-99a4650546de', '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': '76783609a9d7419da55e75b5cd8c3e15'}} 2024-10-25 18:00:24,468 - INFO - Created VN ctest-vn2_metadata-38267161 2024-10-25 18:00:24,533 - DEBUG - VN ctest-vn2_metadata-38267161 UUID is 63ddecb5-4d9d-44ee-95b1-99a4650546de 2024-10-25 18:00:24,769 - DEBUG - Services list from nova: [, , ] 2024-10-25 18:00:26,022 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-2147-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-2147-1) 2024-10-25 18:00:26,145 - DEBUG - Link local service introspect added 2024-10-25 18:00:26,219 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2024-10-25 18:00:31,325 - DEBUG - VM is in ACTIVE state now 2024-10-25 18:00:31,478 - INFO - Waiting for VM ctest-TestBasicVMVN-44388096-63112729 to be up.. 2024-10-25 18:00:31,478 - INFO - VM name : ctest-TestBasicVMVN-44388096-63112729 2024-10-25 18:00:31,585 - DEBUG - VM ctest-TestBasicVMVN-44388096-63112729 ID is 9692b751-445b-4eff-8658-8818b0e738ee 2024-10-25 18:00:31,585 - DEBUG - VM ctest-TestBasicVMVN-44388096-63112729 launched on Node cn-jenkins-deploy-platform-ansible-os-2147-1 2024-10-25 18:00:31,695 - DEBUG - Requesting: http://10.0.0.28:8082/virtual-machine/9692b751-445b-4eff-8658-8818b0e738ee 2024-10-25 18:00:32,001 - DEBUG - Requesting: http://10.0.0.28:8082/virtual-machine/9692b751-445b-4eff-8658-8818b0e738ee 2024-10-25 18:00:32,051 - DEBUG - Requesting: http://10.0.0.28:8082/virtual-machine-interface/50c5c8d5-fc56-4164-a21c-4b4ea55f07b2 2024-10-25 18:00:35,347 - 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') 2024-10-25 18:00:35,347 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicVMVN-44388096-63112729 failed! 2024-10-25 18:00:35,413 - DEBUG - Gateway for vn default-domain:ctest-TestBasicVMVN-44388096:ctest-vn2_metadata-38267161 is 11.1.1.1 and allocation pool is NOT set 2024-10-25 18:00:39,507 - 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=9.90 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 1 received, 50% packet loss, time 1027ms\r\nrtt min/avg/max/mdev = 9.895/9.895/9.895/0.000 ms') 2024-10-25 18:00:39,507 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicVMVN-44388096-63112729 passed 2024-10-25 18:00:39,677 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2024-10-25 18:00:39,677 - DEBUG - Waiting to SSH to VM ctest-TestBasicVMVN-44388096-63112729, IP 11.1.1.3, Port 22 2024-10-25 18:00:39,852 - DEBUG - VM ctest-TestBasicVMVN-44388096-63112729 is ready for SSH connections 2024-10-25 18:00:39,853 - INFO - Retry 0 2024-10-25 18:00:39,853 - 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.28, gateway password: c0ntrail123 2024-10-25 18:00:39,853 - DEBUG - wget http://169.254.1.2:8083 --spider && echo "Successful" 2024-10-25 18:00:41,522 - DEBUG - Connecting to 169.254.1.2:8083 (169.254.1.2:8083) Successful 2024-10-25 18:00:41,523 - INFO - Generic metadata worked 2024-10-25 18:00:41,797 - DEBUG - Link local service introspect removed 2024-10-25 18:00:41,797 - INFO - Deleting VM ctest-TestBasicVMVN-44388096-63112729 2024-10-25 18:00:41,890 - INFO - Deleting VN ctest-vn2_metadata-38267161 2024-10-25 18:00:41,937 - DEBUG - VN 63ddecb5-4d9d-44ee-95b1-99a4650546de still in use: Unable to complete operation on network 63ddecb5-4d9d-44ee-95b1-99a4650546de. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-e562974d-894d-42d6-9986-ea2c7b9c3ba2'] 2024-10-25 18:00:41,937 - WARNING - Deleting VN ctest-vn2_metadata-38267161 failed..Will retry 2024-10-25 18:00:44,090 - DEBUG - Response for deleting network () 2024-10-25 18:00:44,377 - DEBUG - No XMPP flaps were noticed during the test 2024-10-25 18:00:44,377 - INFO - -------------------------------------------------------------------------------- 2024-10-25 18:00:44,383 - INFO - ================================================================================ 2024-10-25 18:00:44,383 - INFO - STARTING TEST : test_ping_within_vn_two_vms_two_different_subnets 2024-10-25 18:00:44,383 - 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 2024-10-25 18:00:44,678 - DEBUG - Nothing to compare xmpp stats {'10.0.0.28': {'10.20.0.11': '0'}} with 2024-10-25 18:00:44,678 - INFO - Initial checks done. Running the testcase now 2024-10-25 18:00:44,678 - INFO - 2024-10-25 18:00:45,254 - DEBUG - Response for create_network : {'network': {'id': 'f68ba63f-4760-405f-b2ef-b4f4161a9f97', 'name': 'ctest-vn-66800192', 'tenant_id': '76783609a9d7419da55e75b5cd8c3e15', 'project_id': '76783609a9d7419da55e75b5cd8c3e15', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicVMVN-44388096', 'ctest-vn-66800192'], 'port_security_enabled': True, 'description': ''}} 2024-10-25 18:00:45,504 - DEBUG - Response for create_subnet : {'subnet': {'id': '580f87fa-2117-4938-b39b-c6078f0a9f49', 'name': '', 'tenant_id': '76783609a9d7419da55e75b5cd8c3e15', 'network_id': 'f68ba63f-4760-405f-b2ef-b4f4161a9f97', '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': '76783609a9d7419da55e75b5cd8c3e15'}} 2024-10-25 18:00:45,727 - DEBUG - Response for create_subnet : {'subnet': {'id': '2f25fe9b-9ca0-4386-be0c-d5016e5e2853', 'name': '', 'tenant_id': '76783609a9d7419da55e75b5cd8c3e15', 'network_id': 'f68ba63f-4760-405f-b2ef-b4f4161a9f97', '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': '76783609a9d7419da55e75b5cd8c3e15'}} 2024-10-25 18:00:45,768 - INFO - Created VN ctest-vn-66800192 2024-10-25 18:00:45,837 - DEBUG - VN ctest-vn-66800192 UUID is f68ba63f-4760-405f-b2ef-b4f4161a9f97 2024-10-25 18:00:46,320 - DEBUG - Response for create_port : {'port': {'name': '8ff617b7-7cdd-49ac-b80c-dbad90eed72f', 'id': '8ff617b7-7cdd-49ac-b80c-dbad90eed72f', 'tenant_id': '76783609a9d7419da55e75b5cd8c3e15', 'network_id': 'f68ba63f-4760-405f-b2ef-b4f4161a9f97', 'mac_address': '02:8f:f6:17:b7:7c', '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': '580f87fa-2117-4938-b39b-c6078f0a9f49'}], 'security_groups': ['c0c77005-ccd9-47f0-b193-323817c65761'], 'admin_state_up': True, 'device_id': '', 'device_owner': '', 'status': 'DOWN', 'port_security_enabled': True, 'description': '', 'project_id': '76783609a9d7419da55e75b5cd8c3e15'}} 2024-10-25 18:00:46,595 - DEBUG - Response for create_port : {'port': {'name': 'bad346d8-cd3f-41f7-9607-831ecf2d7774', 'id': 'bad346d8-cd3f-41f7-9607-831ecf2d7774', 'tenant_id': '76783609a9d7419da55e75b5cd8c3e15', 'network_id': 'f68ba63f-4760-405f-b2ef-b4f4161a9f97', 'mac_address': '02:ba:d3:46:d8:cd', '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': '2f25fe9b-9ca0-4386-be0c-d5016e5e2853'}], 'security_groups': ['c0c77005-ccd9-47f0-b193-323817c65761'], 'admin_state_up': True, 'device_id': '', 'device_owner': '', 'status': 'DOWN', 'port_security_enabled': True, 'description': '', 'project_id': '76783609a9d7419da55e75b5cd8c3e15'}} 2024-10-25 18:00:47,199 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-2147-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-2147-1) 2024-10-25 18:00:47,736 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-2147-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-2147-1) 2024-10-25 18:00:49,250 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-2147-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-2147-1) 2024-10-25 18:00:49,250 - DEBUG - Requesting: http://10.0.0.28:8082/domains 2024-10-25 18:00:49,259 - DEBUG - Requesting: http://10.0.0.28:8082/domain/9764e033-f80e-4d9e-8646-d666ef0eea36 2024-10-25 18:00:49,283 - DEBUG - Requesting: http://10.0.0.28:8082/project/76783609-a9d7-419d-a55e-75b5cd8c3e15 2024-10-25 18:00:49,401 - DEBUG - Requesting: http://10.0.0.28:8082/network-ipam/62c1b357-0f37-4d06-a7f7-4553d19c7ebb 2024-10-25 18:00:49,426 - INFO - Verifications in API Server for IPAM: ctest-ipam-55236974 passed 2024-10-25 18:00:49,437 - DEBUG - Control-node 10.0.0.28 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestBasicVMVN-44388096:ctest-ipam-55236974', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicVMVN-44388096', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7116166072720903430', 'uuid-lslong': '12103218749882793659'}, 'enable': 'true', 'created': '2024-10-25T18:00:44', 'last-modified': '2024-10-25T18:00:44', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:04.500250'} 2024-10-25 18:00:49,437 - INFO - Verifications in Control node for IPAM: ctest-ipam-55236974 passed 2024-10-25 18:00:49,437 - DEBUG - Requesting: http://10.0.0.28:8082/domains 2024-10-25 18:00:49,447 - DEBUG - Requesting: http://10.0.0.28:8082/domain/9764e033-f80e-4d9e-8646-d666ef0eea36 2024-10-25 18:00:49,470 - DEBUG - Requesting: http://10.0.0.28:8082/project/76783609-a9d7-419d-a55e-75b5cd8c3e15 2024-10-25 18:00:49,568 - DEBUG - Requesting: http://10.0.0.28:8082/virtual-network/f68ba63f-4760-405f-b2ef-b4f4161a9f97 2024-10-25 18:00:49,595 - DEBUG - Requesting: http://10.0.0.28:8082/virtual-network/f68ba63f-4760-405f-b2ef-b4f4161a9f97 2024-10-25 18:00:49,615 - DEBUG - Requesting: http://10.0.0.28:8082/routing-instance/fcafbd7d-3b9f-423f-93ac-6130514bf021 2024-10-25 18:00:49,635 - DEBUG - Requesting: http://10.0.0.28:8082/routing-instance/fcafbd7d-3b9f-423f-93ac-6130514bf021 2024-10-25 18:00:49,648 - DEBUG - Requesting: http://10.0.0.28:8082/route-target/0f12c939-5dd8-481a-a45d-44a3569b7012 2024-10-25 18:00:49,658 - DEBUG - Route Targets: ['target:64512:8000005'] 2024-10-25 18:00:49,658 - DEBUG - Requesting: http://10.0.0.28:8082/virtual-network/f68ba63f-4760-405f-b2ef-b4f4161a9f97 2024-10-25 18:00:49,675 - DEBUG - Requesting: http://10.0.0.28:8082/routing-instance/fcafbd7d-3b9f-423f-93ac-6130514bf021 2024-10-25 18:00:49,704 - INFO - Verified VN network id 8 for VN f68ba63f-4760-405f-b2ef-b4f4161a9f97 2024-10-25 18:00:49,704 - INFO - Verifications in API Server for VN ctest-vn-66800192 passed 2024-10-25 18:00:49,704 - DEBUG - Requesting: http://10.0.0.28:8082/virtual-network/f68ba63f-4760-405f-b2ef-b4f4161a9f97 2024-10-25 18:00:49,726 - DEBUG - Requesting: http://10.0.0.28:8082/routing-instance/fcafbd7d-3b9f-423f-93ac-6130514bf021 2024-10-25 18:00:49,755 - DEBUG - Requesting: http://10.0.0.28:8082/routing-instance/fcafbd7d-3b9f-423f-93ac-6130514bf021 2024-10-25 18:00:49,771 - DEBUG - Requesting: http://10.0.0.28:8082/route-target/0f12c939-5dd8-481a-a45d-44a3569b7012 2024-10-25 18:00:49,796 - DEBUG - Control-node 10.0.0.28 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicVMVN-44388096:ctest-vn-66800192', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicVMVN-44388096', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17765475945803694175', 'uuid-lslong': '12893723218620882839'}, 'enable': 'true', 'created': '2024-10-25T18:00:45', 'last-modified': '2024-10-25T18:00:45', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:04.127870'} 2024-10-25 18:00:49,813 - DEBUG - Route Targets: ['target:64512:8000005'] 2024-10-25 18:00:49,814 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn-66800192 passed 2024-10-25 18:00:49,815 - DEBUG - ====Verifying policy data for ctest-vn-66800192 in API_Server ====== 2024-10-25 18:00:49,815 - DEBUG - Requesting: http://10.0.0.28:8082/domains 2024-10-25 18:00:49,834 - DEBUG - Requesting: http://10.0.0.28:8082/domain/9764e033-f80e-4d9e-8646-d666ef0eea36 2024-10-25 18:00:49,901 - DEBUG - Requesting: http://10.0.0.28:8082/project/76783609-a9d7-419d-a55e-75b5cd8c3e15 2024-10-25 18:00:50,010 - DEBUG - Requesting: http://10.0.0.28:8082/virtual-network/f68ba63f-4760-405f-b2ef-b4f4161a9f97 2024-10-25 18:00:50,031 - DEBUG - =>VN ctest-vn-66800192 has no policy to be verified 2024-10-25 18:00:50,031 - DEBUG - Verifying the vn in opserver 2024-10-25 18:00:50,031 - DEBUG - Verifying the default-domain:ctest-TestBasicVMVN-44388096:ctest-vn-66800192 virtual network link through opserver 10.0.0.28 2024-10-25 18:00:50,032 - DEBUG - Requesting: http://10.0.0.28:8081/analytics/uves/virtual-networks 2024-10-25 18:00:50,559 - DEBUG - Requesting: http://10.0.0.28:8081/analytics/uves/virtual-networks 2024-10-25 18:00:50,669 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicVMVN-44388096:ctest-vn-66800192', 'href': 'http://10.0.0.28:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicVMVN-44388096:ctest-vn-66800192?flat'} 2024-10-25 18:00:50,669 - INFO - Validated that VN default-domain:ctest-TestBasicVMVN-44388096:ctest-vn-66800192 is found in opserver 2024-10-25 18:00:50,678 - DEBUG - VRF ids for VN ctest-vn-66800192: {'10.0.0.28': '2'} 2024-10-25 18:00:50,679 - INFO - Waiting for VM ctest-TestBasicVMVN-44388096-04623949 to be up.. 2024-10-25 18:00:50,794 - DEBUG - VM is in ACTIVE state now 2024-10-25 18:00:50,794 - INFO - VM name : ctest-TestBasicVMVN-44388096-04623949 2024-10-25 18:00:50,908 - DEBUG - VM ctest-TestBasicVMVN-44388096-04623949 ID is fd2aa284-c404-432e-8604-fa2aa98f64de 2024-10-25 18:00:50,908 - DEBUG - VM ctest-TestBasicVMVN-44388096-04623949 launched on Node cn-jenkins-deploy-platform-ansible-os-2147-1 2024-10-25 18:00:51,006 - DEBUG - Requesting: http://10.0.0.28:8082/virtual-machine/fd2aa284-c404-432e-8604-fa2aa98f64de 2024-10-25 18:00:51,018 - DEBUG - Requesting: http://10.0.0.28:8082/virtual-machine-interface/8ff617b7-7cdd-49ac-b80c-dbad90eed72f 2024-10-25 18:00:54,355 - 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 1030ms') 2024-10-25 18:00:54,355 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicVMVN-44388096-04623949 failed! 2024-10-25 18:00:54,421 - DEBUG - Gateway for vn default-domain:ctest-TestBasicVMVN-44388096:ctest-vn-66800192 is 31.1.1.1 and allocation pool is NOT set 2024-10-25 18:00:54,421 - DEBUG - Gateway for vn default-domain:ctest-TestBasicVMVN-44388096:ctest-vn-66800192 is 31.1.2.1 and allocation pool is NOT set 2024-10-25 18:00:58,517 - 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 1024ms') 2024-10-25 18:00:58,518 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicVMVN-44388096-04623949 failed! 2024-10-25 18:00:58,585 - DEBUG - Gateway for vn default-domain:ctest-TestBasicVMVN-44388096:ctest-vn-66800192 is 31.1.1.1 and allocation pool is NOT set 2024-10-25 18:00:58,585 - DEBUG - Gateway for vn default-domain:ctest-TestBasicVMVN-44388096:ctest-vn-66800192 is 31.1.2.1 and allocation pool is NOT set 2024-10-25 18:01:00,655 - 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.74 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=2.71 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 2.706/2.721/2.736/0.015 ms') 2024-10-25 18:01:00,656 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicVMVN-44388096-04623949 passed 2024-10-25 18:01:00,821 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2024-10-25 18:01:00,821 - DEBUG - Waiting to SSH to VM ctest-TestBasicVMVN-44388096-04623949, IP 31.1.1.4, Port 22 2024-10-25 18:01:00,991 - DEBUG - VM ctest-TestBasicVMVN-44388096-04623949 is ready for SSH connections 2024-10-25 18:01:00,991 - INFO - Waiting for VM ctest-TestBasicVMVN-44388096-04755253 to be up.. 2024-10-25 18:01:01,107 - DEBUG - VM is in ACTIVE state now 2024-10-25 18:01:01,107 - INFO - VM name : ctest-TestBasicVMVN-44388096-04755253 2024-10-25 18:01:01,222 - DEBUG - VM ctest-TestBasicVMVN-44388096-04755253 ID is 6c588044-401d-4086-83d7-a4aa3e319721 2024-10-25 18:01:01,222 - DEBUG - VM ctest-TestBasicVMVN-44388096-04755253 launched on Node cn-jenkins-deploy-platform-ansible-os-2147-1 2024-10-25 18:01:01,339 - DEBUG - Requesting: http://10.0.0.28:8082/virtual-machine/6c588044-401d-4086-83d7-a4aa3e319721 2024-10-25 18:01:01,356 - DEBUG - Requesting: http://10.0.0.28:8082/virtual-machine-interface/bad346d8-cd3f-41f7-9607-831ecf2d7774 2024-10-25 18:01:02,626 - 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.29 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.480 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.480/1.885/3.290/1.405 ms') 2024-10-25 18:01:02,626 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-TestBasicVMVN-44388096-04755253 passed 2024-10-25 18:01:02,797 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2024-10-25 18:01:02,797 - DEBUG - Waiting to SSH to VM ctest-TestBasicVMVN-44388096-04755253, IP 31.1.2.4, Port 22 2024-10-25 18:01:02,961 - DEBUG - VM ctest-TestBasicVMVN-44388096-04755253 is ready for SSH connections 2024-10-25 18:01:02,961 - INFO - Waiting for VM ctest-TestBasicVMVN-44388096-13518021 to be up.. 2024-10-25 18:01:03,075 - DEBUG - VM is in ACTIVE state now 2024-10-25 18:01:03,075 - INFO - VM name : ctest-TestBasicVMVN-44388096-13518021 2024-10-25 18:01:03,196 - DEBUG - VM ctest-TestBasicVMVN-44388096-13518021 ID is e3a92aaa-c2b7-4246-85b8-401ede7dfa0e 2024-10-25 18:01:03,196 - DEBUG - VM ctest-TestBasicVMVN-44388096-13518021 launched on Node cn-jenkins-deploy-platform-ansible-os-2147-1 2024-10-25 18:01:03,308 - DEBUG - Requesting: http://10.0.0.28:8082/virtual-machine/e3a92aaa-c2b7-4246-85b8-401ede7dfa0e 2024-10-25 18:01:03,321 - DEBUG - Requesting: http://10.0.0.28:8082/virtual-machine-interface/39e0669e-8028-4d15-918c-fbc3df14744f 2024-10-25 18:01:04,607 - 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.50 ms\r\n64 bytes from 169.254.0.5: icmp_seq=2 ttl=63 time=2.03 ms\r\n\r\n--- 169.254.0.5 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 2.032/3.766/5.501/1.734 ms') 2024-10-25 18:01:04,608 - INFO - Ping to Metadata IP 169.254.0.5 of VM ctest-TestBasicVMVN-44388096-13518021 passed 2024-10-25 18:01:04,769 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2024-10-25 18:01:04,769 - DEBUG - Waiting to SSH to VM ctest-TestBasicVMVN-44388096-13518021, IP 31.1.1.3, Port 22 2024-10-25 18:01:04,935 - DEBUG - VM ctest-TestBasicVMVN-44388096-13518021 is ready for SSH connections 2024-10-25 18:01:04,936 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2024-10-25 18:01:04,936 - 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.28, gateway password: c0ntrail123 2024-10-25 18:01:04,936 - DEBUG - ping -s 56 -c 3 -W 1 31.1.2.4 2024-10-25 18:01:08,687 - 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=4.025 ms 64 bytes from 31.1.2.4: seq=1 ttl=63 time=1.345 ms 64 bytes from 31.1.2.4: seq=2 ttl=63 time=1.498 ms --- 31.1.2.4 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 1.345/2.289/4.025 ms 2024-10-25 18:01:08,687 - INFO - Ping to IP 31.1.2.4 from VM ctest-TestBasicVMVN-44388096-04623949 passed 2024-10-25 18:01:08,687 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2024-10-25 18:01:08,687 - 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.28, gateway password: c0ntrail123 2024-10-25 18:01:08,687 - DEBUG - ping -s 56 -c 3 -W 1 31.1.1.4 2024-10-25 18:01:12,559 - DEBUG - PING 31.1.1.4 (31.1.1.4): 56 data bytes 64 bytes from 31.1.1.4: seq=0 ttl=63 time=3.214 ms 64 bytes from 31.1.1.4: seq=1 ttl=63 time=1.418 ms 64 bytes from 31.1.1.4: seq=2 ttl=63 time=1.668 ms --- 31.1.1.4 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 1.418/2.100/3.214 ms 2024-10-25 18:01:12,560 - INFO - Ping to IP 31.1.1.4 from VM ctest-TestBasicVMVN-44388096-04755253 passed 2024-10-25 18:01:12,560 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2024-10-25 18:01:12,560 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2024-10-25 18:01:12,560 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2024-10-25 18:01:12,561 - 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.28, gateway password: c0ntrail123 2024-10-25 18:01:12,561 - DEBUG - echo 0 > /proc/sys/net/ipv4/icmp_echo_ignore_broadcasts 2024-10-25 18:01:12,759 - DEBUG - None 2024-10-25 18:01:12,760 - 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.28, gateway password: c0ntrail123 2024-10-25 18:01:12,760 - DEBUG - echo 0 > /proc/sys/net/ipv4/icmp_echo_ignore_broadcasts 2024-10-25 18:01:12,971 - DEBUG - None 2024-10-25 18:01:12,971 - 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.28, gateway password: c0ntrail123 2024-10-25 18:01:12,971 - DEBUG - echo 0 > /proc/sys/net/ipv4/icmp_echo_ignore_broadcasts 2024-10-25 18:01:13,693 - DEBUG - None 2024-10-25 18:01:13,693 - 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.28, gateway password: c0ntrail123 2024-10-25 18:01:13,694 - DEBUG - ping -s 56 -c 3 -W 1 31.1.1.7 2024-10-25 18:01:15,988 - 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=2.695 ms 64 bytes from 31.1.1.3: seq=0 ttl=64 time=7.677 ms (DUP!) 64 bytes from 31.1.1.4: seq=1 ttl=64 time=0.605 ms 64 bytes from 31.1.1.3: seq=1 ttl=64 time=2.296 ms (DUP!) 64 bytes from 31.1.1.4: seq=2 ttl=64 time=0.538 ms --- 31.1.1.7 ping statistics --- 3 packets transmitted, 3 packets received, 2 duplicates, 0% packet loss round-trip min/avg/max = 0.538/2.762/7.677 ms 2024-10-25 18:01:15,988 - 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.28, gateway password: c0ntrail123 2024-10-25 18:01:15,989 - DEBUG - ping -s 56 -c 3 -W 1 224.0.0.1 2024-10-25 18:01:18,253 - DEBUG - PING 224.0.0.1 (224.0.0.1): 56 data bytes 64 bytes from 31.1.1.4: seq=0 ttl=64 time=2.457 ms 64 bytes from 31.1.1.3: seq=0 ttl=64 time=4.713 ms (DUP!) 64 bytes from 31.1.2.4: seq=0 ttl=63 time=6.047 ms (DUP!) 64 bytes from 31.1.1.4: seq=1 ttl=64 time=0.712 ms 64 bytes from 31.1.2.4: seq=1 ttl=63 time=2.566 ms (DUP!) 64 bytes from 31.1.1.3: seq=1 ttl=64 time=3.492 ms (DUP!) 64 bytes from 31.1.1.4: seq=2 ttl=64 time=0.514 ms --- 224.0.0.1 ping statistics --- 3 packets transmitted, 3 packets received, 4 duplicates, 0% packet loss round-trip min/avg/max = 0.514/2.928/6.047 ms 2024-10-25 18:01:18,253 - 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.28, gateway password: c0ntrail123 2024-10-25 18:01:18,254 - DEBUG - ping -s 56 -c 3 -W 1 255.255.255.255 2024-10-25 18:01:20,504 - 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.932 ms 64 bytes from 31.1.1.3: seq=0 ttl=64 time=3.000 ms (DUP!) 64 bytes from 31.1.2.4: seq=0 ttl=63 time=4.676 ms (DUP!) 64 bytes from 31.1.1.4: seq=1 ttl=64 time=0.659 ms 64 bytes from 31.1.1.3: seq=1 ttl=64 time=5.148 ms (DUP!) 64 bytes from 31.1.2.4: seq=1 ttl=63 time=7.211 ms (DUP!) 64 bytes from 31.1.1.4: seq=2 ttl=64 time=0.480 ms --- 255.255.255.255 ping statistics --- 3 packets transmitted, 3 packets received, 4 duplicates, 0% packet loss round-trip min/avg/max = 0.480/3.158/7.211 ms 2024-10-25 18:01:20,504 - INFO - Deleting VM ctest-TestBasicVMVN-44388096-13518021 2024-10-25 18:01:20,594 - INFO - Detaching port bad346d8-cd3f-41f7-9607-831ecf2d7774 from VM ctest-TestBasicVMVN-44388096-04755253 2024-10-25 18:01:20,658 - INFO - Deleting VM ctest-TestBasicVMVN-44388096-04755253 2024-10-25 18:01:20,745 - INFO - Detaching port 8ff617b7-7cdd-49ac-b80c-dbad90eed72f from VM ctest-TestBasicVMVN-44388096-04623949 2024-10-25 18:01:20,782 - INFO - Deleting VM ctest-TestBasicVMVN-44388096-04623949 2024-10-25 18:01:20,866 - INFO - Deleting VN ctest-vn-66800192 2024-10-25 18:01:21,163 - DEBUG - Response for delete_port : () 2024-10-25 18:01:21,454 - DEBUG - Response for delete_port : () 2024-10-25 18:01:21,669 - DEBUG - Response for deleting network () 2024-10-25 18:01:21,669 - DEBUG - Requesting: http://10.0.0.28:8082/routing-instance/fcafbd7d-3b9f-423f-93ac-6130514bf021 2024-10-25 18:01:21,678 - DEBUG - Response Code: 404 2024-10-25 18:01:21,678 - DEBUG - Requesting: http://10.0.0.28:8082/domains 2024-10-25 18:01:21,699 - DEBUG - Requesting: http://10.0.0.28:8082/domain/9764e033-f80e-4d9e-8646-d666ef0eea36 2024-10-25 18:01:21,724 - DEBUG - Requesting: http://10.0.0.28:8082/project/76783609-a9d7-419d-a55e-75b5cd8c3e15 2024-10-25 18:01:21,843 - INFO - Validated that VN ctest-vn-66800192 is not found in API Server 2024-10-25 18:01:21,858 - DEBUG - VN ctest-vn-66800192 is not present in Agent 10.0.0.28 2024-10-25 18:01:21,858 - INFO - Validated that VN ctest-vn-66800192 is not in any agent 2024-10-25 18:01:21,867 - DEBUG - VRF 2 is not seen in agent 10.0.0.28 2024-10-25 18:01:21,876 - DEBUG - Vrouter 10.0.0.28 does not have vrf 2 for VN ctest-vn-66800192 2024-10-25 18:01:21,877 - INFO - Validated that all vrouters do not have the route table for VN default-domain:ctest-TestBasicVMVN-44388096:ctest-vn-66800192 2024-10-25 18:01:21,892 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn-66800192 info 2024-10-25 18:01:21,984 - INFO - IPAM: ctest-ipam-55236974 is not found in API Server 2024-10-25 18:01:22,003 - INFO - IPAM:ctest-ipam-55236974 is not found in control node 2024-10-25 18:01:22,265 - DEBUG - No XMPP flaps were noticed during the test
2024-10-25 18:01:22,265 - INFO - END TEST : test_ping_within_vn_two_vms_two_different_subnets : PASSED[0:00:38]
2024-10-25 18:01:22,265 - INFO - -------------------------------------------------------------------------------- 2024-10-25 18:01:23,043 - INFO - Deleted project: ctest-TestBasicVMVN-44388096, ID : 76783609-a9d7-419d-a55e-75b5cd8c3e15