2024-11-10 19:33:36,325 - INFO - Domain Default found not creating 2024-11-10 19:33:36,532 - INFO - Project ctest-TestBasicVMVN-14138356 not found, creating it 2024-11-10 19:33:37,064 - INFO - Created Project:ctest-TestBasicVMVN-14138356, ID : 86750aaa-3dc5-4d11-8a01-7e48c9337bdf 2024-11-10 19:33:38,863 - INFO - ================================================================================ 2024-11-10 19:33:38,864 - INFO - STARTING TEST : test_generic_link_local_service 2024-11-10 19:33:38,864 - 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-11-10 19:33:39,120 - DEBUG - Nothing to compare xmpp stats {'10.0.0.56': {'10.20.0.10': '0'}} with 2024-11-10 19:33:39,120 - INFO - Initial checks done. Running the testcase now 2024-11-10 19:33:39,120 - INFO - 2024-11-10 19:33:39,769 - DEBUG - Response for create_network : {'network': {'id': '7b751c62-d4f7-4165-998d-573bc43de609', 'name': 'ctest-vn2_metadata-37668700', 'tenant_id': '86750aaa3dc54d118a017e48c9337bdf', 'project_id': '86750aaa3dc54d118a017e48c9337bdf', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicVMVN-14138356', 'ctest-vn2_metadata-37668700'], 'port_security_enabled': True, 'description': ''}} 2024-11-10 19:33:39,990 - DEBUG - Response for create_subnet : {'subnet': {'id': '22111c62-7692-49e0-b609-fc6be3651361', 'name': '', 'tenant_id': '86750aaa3dc54d118a017e48c9337bdf', 'network_id': '7b751c62-d4f7-4165-998d-573bc43de609', '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': '86750aaa3dc54d118a017e48c9337bdf'}} 2024-11-10 19:33:40,013 - INFO - Created VN ctest-vn2_metadata-37668700 2024-11-10 19:33:40,073 - DEBUG - VN ctest-vn2_metadata-37668700 UUID is 7b751c62-d4f7-4165-998d-573bc43de609 2024-11-10 19:33:40,272 - DEBUG - Services list from nova: [, , ] 2024-11-10 19:33:41,545 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-2261-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-2261-1) 2024-11-10 19:33:41,657 - DEBUG - Link local service introspect added 2024-11-10 19:33:41,714 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2024-11-10 19:33:46,801 - DEBUG - VM is in ACTIVE state now 2024-11-10 19:33:46,939 - INFO - Waiting for VM ctest-TestBasicVMVN-14138356-36145742 to be up.. 2024-11-10 19:33:46,939 - INFO - VM name : ctest-TestBasicVMVN-14138356-36145742 2024-11-10 19:33:47,032 - DEBUG - VM ctest-TestBasicVMVN-14138356-36145742 ID is cb134359-529d-4800-be5a-b332b26fe8ee 2024-11-10 19:33:47,032 - DEBUG - VM ctest-TestBasicVMVN-14138356-36145742 launched on Node cn-jenkins-deploy-platform-ansible-os-2261-1 2024-11-10 19:33:47,125 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-machine/cb134359-529d-4800-be5a-b332b26fe8ee 2024-11-10 19:33:47,468 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-machine/cb134359-529d-4800-be5a-b332b26fe8ee 2024-11-10 19:33:47,510 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-machine-interface/e2b46ca4-b0cf-41b6-8e90-6625424442a4 2024-11-10 19:33:50,803 - 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') 2024-11-10 19:33:50,803 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicVMVN-14138356-36145742 failed! 2024-11-10 19:33:50,865 - DEBUG - Gateway for vn default-domain:ctest-TestBasicVMVN-14138356:ctest-vn2_metadata-37668700 is 11.1.1.1 and allocation pool is NOT set 2024-11-10 19:33:52,930 - 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=7.50 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=1.40 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 1.397/4.450/7.503/3.053 ms') 2024-11-10 19:33:52,930 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicVMVN-14138356-36145742 passed 2024-11-10 19:33:53,089 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2024-11-10 19:33:53,089 - DEBUG - Waiting to SSH to VM ctest-TestBasicVMVN-14138356-36145742, IP 11.1.1.3, Port 22 2024-11-10 19:33:53,144 - DEBUG - Error on ssh to cirros@169.254.0.3:22, result: /bin/bash: connect: Connection refused /bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''} 2024-11-10 19:33:53,232 - DEBUG - VM ctest-TestBasicVMVN-14138356-36145742 is NOT ready for SSH connections, VM status: ACTIVE 2024-11-10 19:33:58,233 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2024-11-10 19:33:58,233 - DEBUG - Waiting to SSH to VM ctest-TestBasicVMVN-14138356-36145742, IP 11.1.1.3, Port 22 2024-11-10 19:33:58,393 - DEBUG - VM ctest-TestBasicVMVN-14138356-36145742 is ready for SSH connections 2024-11-10 19:33:58,393 - INFO - Retry 0 2024-11-10 19:33:58,393 - 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.56, gateway password: c0ntrail123 2024-11-10 19:33:58,393 - DEBUG - wget http://169.254.1.2:8083 --spider && echo "Successful" 2024-11-10 19:34:00,041 - DEBUG - Connecting to 169.254.1.2:8083 (169.254.1.2:8083) Successful 2024-11-10 19:34:00,041 - INFO - Generic metadata worked 2024-11-10 19:34:00,305 - DEBUG - Link local service introspect removed 2024-11-10 19:34:00,305 - INFO - Deleting VM ctest-TestBasicVMVN-14138356-36145742 2024-11-10 19:34:00,383 - INFO - Deleting VN ctest-vn2_metadata-37668700 2024-11-10 19:34:00,431 - DEBUG - VN 7b751c62-d4f7-4165-998d-573bc43de609 still in use: Unable to complete operation on network 7b751c62-d4f7-4165-998d-573bc43de609. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-acee94ef-aa3d-4e04-a9b4-d4652562a57b'] 2024-11-10 19:34:00,431 - WARNING - Deleting VN ctest-vn2_metadata-37668700 failed..Will retry 2024-11-10 19:34:02,579 - DEBUG - Response for deleting network () 2024-11-10 19:34:02,843 - DEBUG - No XMPP flaps were noticed during the test 2024-11-10 19:34:02,844 - INFO - -------------------------------------------------------------------------------- 2024-11-10 19:34:02,846 - INFO - ================================================================================ 2024-11-10 19:34:02,846 - INFO - STARTING TEST : test_ping_within_vn_two_vms_two_different_subnets 2024-11-10 19:34:02,847 - 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-11-10 19:34:03,137 - DEBUG - Nothing to compare xmpp stats {'10.0.0.56': {'10.20.0.10': '0'}} with 2024-11-10 19:34:03,137 - INFO - Initial checks done. Running the testcase now 2024-11-10 19:34:03,137 - INFO - 2024-11-10 19:34:03,659 - DEBUG - Response for create_network : {'network': {'id': 'b8d6b462-0bb1-46b1-a836-04cb00335fec', 'name': 'ctest-vn-76470877', 'tenant_id': '86750aaa3dc54d118a017e48c9337bdf', 'project_id': '86750aaa3dc54d118a017e48c9337bdf', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicVMVN-14138356', 'ctest-vn-76470877'], 'port_security_enabled': True, 'description': ''}} 2024-11-10 19:34:03,841 - DEBUG - Response for create_subnet : {'subnet': {'id': '92da971c-ffad-4157-bf59-0d5fd9aae81c', 'name': '', 'tenant_id': '86750aaa3dc54d118a017e48c9337bdf', 'network_id': 'b8d6b462-0bb1-46b1-a836-04cb00335fec', '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': '86750aaa3dc54d118a017e48c9337bdf'}} 2024-11-10 19:34:04,042 - DEBUG - Response for create_subnet : {'subnet': {'id': '0bc70dae-8de3-4c9b-b684-37838696daf4', 'name': '', 'tenant_id': '86750aaa3dc54d118a017e48c9337bdf', 'network_id': 'b8d6b462-0bb1-46b1-a836-04cb00335fec', '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': '86750aaa3dc54d118a017e48c9337bdf'}} 2024-11-10 19:34:04,070 - INFO - Created VN ctest-vn-76470877 2024-11-10 19:34:04,129 - DEBUG - VN ctest-vn-76470877 UUID is b8d6b462-0bb1-46b1-a836-04cb00335fec 2024-11-10 19:34:04,544 - DEBUG - Response for create_port : {'port': {'name': '78e2141f-43d3-457b-b697-cda59c3856aa', 'id': '78e2141f-43d3-457b-b697-cda59c3856aa', 'tenant_id': '86750aaa3dc54d118a017e48c9337bdf', 'network_id': 'b8d6b462-0bb1-46b1-a836-04cb00335fec', 'mac_address': '02:78:e2:14:1f:43', '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': '92da971c-ffad-4157-bf59-0d5fd9aae81c'}], 'security_groups': ['e6c6f03d-e481-4bde-91fe-b71d0978b9f2'], 'admin_state_up': True, 'device_id': '', 'device_owner': '', 'status': 'DOWN', 'port_security_enabled': True, 'description': '', 'project_id': '86750aaa3dc54d118a017e48c9337bdf'}} 2024-11-10 19:34:04,732 - DEBUG - Response for create_port : {'port': {'name': 'd6f7e0b9-ad5b-40a7-882b-4dfcb049d687', 'id': 'd6f7e0b9-ad5b-40a7-882b-4dfcb049d687', 'tenant_id': '86750aaa3dc54d118a017e48c9337bdf', 'network_id': 'b8d6b462-0bb1-46b1-a836-04cb00335fec', 'mac_address': '02:d6:f7:e0:b9:ad', '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': '0bc70dae-8de3-4c9b-b684-37838696daf4'}], 'security_groups': ['e6c6f03d-e481-4bde-91fe-b71d0978b9f2'], 'admin_state_up': True, 'device_id': '', 'device_owner': '', 'status': 'DOWN', 'port_security_enabled': True, 'description': '', 'project_id': '86750aaa3dc54d118a017e48c9337bdf'}} 2024-11-10 19:34:05,098 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-2261-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-2261-1) 2024-11-10 19:34:05,535 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-2261-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-2261-1) 2024-11-10 19:34:06,894 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-2261-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-2261-1) 2024-11-10 19:34:06,894 - DEBUG - Requesting: http://10.0.0.56:8082/domains 2024-11-10 19:34:06,904 - DEBUG - Requesting: http://10.0.0.56:8082/domain/3ce32a09-9753-46a1-b877-6f01cbf88076 2024-11-10 19:34:06,922 - DEBUG - Requesting: http://10.0.0.56:8082/project/86750aaa-3dc5-4d11-8a01-7e48c9337bdf 2024-11-10 19:34:07,022 - DEBUG - Requesting: http://10.0.0.56:8082/network-ipam/1a807d40-5e99-4670-b0c1-fbd8c6c102f8 2024-11-10 19:34:07,038 - INFO - Verifications in API Server for IPAM: ctest-ipam-72581032 passed 2024-11-10 19:34:07,046 - DEBUG - Control-node 10.0.0.56 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestBasicVMVN-14138356:ctest-ipam-72581032', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicVMVN-14138356', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1909663957423572592', 'uuid-lslong': '12736738129646519032'}, 'enable': 'true', 'created': '2024-11-10T19:34:03', 'last-modified': '2024-11-10T19:34:03', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:03.668680'} 2024-11-10 19:34:07,047 - INFO - Verifications in Control node for IPAM: ctest-ipam-72581032 passed 2024-11-10 19:34:07,047 - DEBUG - Requesting: http://10.0.0.56:8082/domains 2024-11-10 19:34:07,055 - DEBUG - Requesting: http://10.0.0.56:8082/domain/3ce32a09-9753-46a1-b877-6f01cbf88076 2024-11-10 19:34:07,089 - DEBUG - Requesting: http://10.0.0.56:8082/project/86750aaa-3dc5-4d11-8a01-7e48c9337bdf 2024-11-10 19:34:07,187 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/b8d6b462-0bb1-46b1-a836-04cb00335fec 2024-11-10 19:34:07,210 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/b8d6b462-0bb1-46b1-a836-04cb00335fec 2024-11-10 19:34:07,230 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/8f6bb125-4f72-471c-be39-e679eda24968 2024-11-10 19:34:07,246 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/8f6bb125-4f72-471c-be39-e679eda24968 2024-11-10 19:34:07,258 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/bf44100c-1344-40ba-8370-a90be9be97dd 2024-11-10 19:34:07,268 - DEBUG - Route Targets: ['target:64512:8000005'] 2024-11-10 19:34:07,268 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/b8d6b462-0bb1-46b1-a836-04cb00335fec 2024-11-10 19:34:07,296 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/8f6bb125-4f72-471c-be39-e679eda24968 2024-11-10 19:34:07,335 - INFO - Verified VN network id 8 for VN b8d6b462-0bb1-46b1-a836-04cb00335fec 2024-11-10 19:34:07,335 - INFO - Verifications in API Server for VN ctest-vn-76470877 passed 2024-11-10 19:34:07,335 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/b8d6b462-0bb1-46b1-a836-04cb00335fec 2024-11-10 19:34:07,359 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/8f6bb125-4f72-471c-be39-e679eda24968 2024-11-10 19:34:07,372 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/8f6bb125-4f72-471c-be39-e679eda24968 2024-11-10 19:34:07,383 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/bf44100c-1344-40ba-8370-a90be9be97dd 2024-11-10 19:34:07,401 - DEBUG - Control-node 10.0.0.56 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicVMVN-14138356:ctest-vn-76470877', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicVMVN-14138356', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13319031281190782641', 'uuid-lslong': '12120880717042507756'}, 'enable': 'true', 'created': '2024-11-10T19:34:03', 'last-modified': '2024-11-10T19:34:03', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:03.389636'} 2024-11-10 19:34:07,410 - DEBUG - Route Targets: ['target:64512:8000005'] 2024-11-10 19:34:07,411 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn-76470877 passed 2024-11-10 19:34:07,412 - DEBUG - ====Verifying policy data for ctest-vn-76470877 in API_Server ====== 2024-11-10 19:34:07,412 - DEBUG - Requesting: http://10.0.0.56:8082/domains 2024-11-10 19:34:07,420 - DEBUG - Requesting: http://10.0.0.56:8082/domain/3ce32a09-9753-46a1-b877-6f01cbf88076 2024-11-10 19:34:07,438 - DEBUG - Requesting: http://10.0.0.56:8082/project/86750aaa-3dc5-4d11-8a01-7e48c9337bdf 2024-11-10 19:34:07,562 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/b8d6b462-0bb1-46b1-a836-04cb00335fec 2024-11-10 19:34:07,582 - DEBUG - =>VN ctest-vn-76470877 has no policy to be verified 2024-11-10 19:34:07,582 - DEBUG - Verifying the vn in opserver 2024-11-10 19:34:07,582 - DEBUG - Verifying the default-domain:ctest-TestBasicVMVN-14138356:ctest-vn-76470877 virtual network link through opserver 10.0.0.56 2024-11-10 19:34:07,583 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-networks 2024-11-10 19:34:07,994 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-networks 2024-11-10 19:34:08,134 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicVMVN-14138356:ctest-vn-76470877', 'href': 'http://10.0.0.56:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicVMVN-14138356:ctest-vn-76470877?flat'} 2024-11-10 19:34:08,134 - INFO - Validated that VN default-domain:ctest-TestBasicVMVN-14138356:ctest-vn-76470877 is found in opserver 2024-11-10 19:34:08,145 - DEBUG - VRF ids for VN ctest-vn-76470877: {'10.0.0.56': '2'} 2024-11-10 19:34:08,145 - INFO - Waiting for VM ctest-TestBasicVMVN-14138356-24362790 to be up.. 2024-11-10 19:34:08,279 - DEBUG - VM is in ACTIVE state now 2024-11-10 19:34:08,279 - INFO - VM name : ctest-TestBasicVMVN-14138356-24362790 2024-11-10 19:34:08,387 - DEBUG - VM ctest-TestBasicVMVN-14138356-24362790 ID is 99406cdb-b61a-4cc7-b584-962905298fc3 2024-11-10 19:34:08,388 - DEBUG - VM ctest-TestBasicVMVN-14138356-24362790 launched on Node cn-jenkins-deploy-platform-ansible-os-2261-1 2024-11-10 19:34:08,495 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-machine/99406cdb-b61a-4cc7-b584-962905298fc3 2024-11-10 19:34:08,511 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-machine-interface/78e2141f-43d3-457b-b697-cda59c3856aa 2024-11-10 19:34:11,825 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1031ms') 2024-11-10 19:34:11,825 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicVMVN-14138356-24362790 failed! 2024-11-10 19:34:11,885 - DEBUG - Gateway for vn default-domain:ctest-TestBasicVMVN-14138356:ctest-vn-76470877 is 31.1.1.1 and allocation pool is NOT set 2024-11-10 19:34:11,885 - DEBUG - Gateway for vn default-domain:ctest-TestBasicVMVN-14138356:ctest-vn-76470877 is 31.1.2.1 and allocation pool is NOT set 2024-11-10 19:34:15,948 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1002ms') 2024-11-10 19:34:15,948 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicVMVN-14138356-24362790 failed! 2024-11-10 19:34:16,013 - DEBUG - Gateway for vn default-domain:ctest-TestBasicVMVN-14138356:ctest-vn-76470877 is 31.1.1.1 and allocation pool is NOT set 2024-11-10 19:34:16,013 - DEBUG - Gateway for vn default-domain:ctest-TestBasicVMVN-14138356:ctest-vn-76470877 is 31.1.2.1 and allocation pool is NOT set 2024-11-10 19:34:18,076 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n64 bytes from 169.254.0.3: icmp_seq=1 ttl=63 time=8.21 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=2.23 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 = 2.234/5.220/8.207/2.986 ms') 2024-11-10 19:34:18,077 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicVMVN-14138356-24362790 passed 2024-11-10 19:34:18,237 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2024-11-10 19:34:18,237 - DEBUG - Waiting to SSH to VM ctest-TestBasicVMVN-14138356-24362790, IP 31.1.1.4, Port 22 2024-11-10 19:34:18,293 - DEBUG - Error on ssh to cirros@169.254.0.3:22, result: /bin/bash: connect: Connection refused /bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''} 2024-11-10 19:34:18,380 - DEBUG - VM ctest-TestBasicVMVN-14138356-24362790 is NOT ready for SSH connections, VM status: ACTIVE 2024-11-10 19:34:23,381 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2024-11-10 19:34:23,381 - DEBUG - Waiting to SSH to VM ctest-TestBasicVMVN-14138356-24362790, IP 31.1.1.4, Port 22 2024-11-10 19:34:23,555 - DEBUG - VM ctest-TestBasicVMVN-14138356-24362790 is ready for SSH connections 2024-11-10 19:34:23,556 - INFO - Waiting for VM ctest-TestBasicVMVN-14138356-78423558 to be up.. 2024-11-10 19:34:23,688 - DEBUG - VM is in ACTIVE state now 2024-11-10 19:34:23,689 - INFO - VM name : ctest-TestBasicVMVN-14138356-78423558 2024-11-10 19:34:23,839 - DEBUG - VM ctest-TestBasicVMVN-14138356-78423558 ID is b543c45c-2d6e-44ee-a5ad-e8b8ba237d1e 2024-11-10 19:34:23,839 - DEBUG - VM ctest-TestBasicVMVN-14138356-78423558 launched on Node cn-jenkins-deploy-platform-ansible-os-2261-1 2024-11-10 19:34:23,983 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-machine/b543c45c-2d6e-44ee-a5ad-e8b8ba237d1e 2024-11-10 19:34:23,997 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-machine-interface/d6f7e0b9-ad5b-40a7-882b-4dfcb049d687 2024-11-10 19:34:25,270 - 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.11 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.607 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.607/1.857/3.107/1.250 ms') 2024-11-10 19:34:25,270 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-TestBasicVMVN-14138356-78423558 passed 2024-11-10 19:34:25,437 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2024-11-10 19:34:25,438 - DEBUG - Waiting to SSH to VM ctest-TestBasicVMVN-14138356-78423558, IP 31.1.2.4, Port 22 2024-11-10 19:34:25,612 - DEBUG - VM ctest-TestBasicVMVN-14138356-78423558 is ready for SSH connections 2024-11-10 19:34:25,612 - INFO - Waiting for VM ctest-TestBasicVMVN-14138356-26833014 to be up.. 2024-11-10 19:34:25,787 - DEBUG - VM is in ACTIVE state now 2024-11-10 19:34:25,787 - INFO - VM name : ctest-TestBasicVMVN-14138356-26833014 2024-11-10 19:34:25,956 - DEBUG - VM ctest-TestBasicVMVN-14138356-26833014 ID is 68c1f0ec-99d9-4636-9fb9-04bd90eef7fa 2024-11-10 19:34:25,956 - DEBUG - VM ctest-TestBasicVMVN-14138356-26833014 launched on Node cn-jenkins-deploy-platform-ansible-os-2261-1 2024-11-10 19:34:26,122 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-machine/68c1f0ec-99d9-4636-9fb9-04bd90eef7fa 2024-11-10 19:34:26,138 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-machine-interface/063ee17f-5892-4c74-a0df-69ae9f6cb4ed 2024-11-10 19:34:27,437 - 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=4.43 ms\r\n64 bytes from 169.254.0.5: icmp_seq=2 ttl=63 time=0.475 ms\r\n\r\n--- 169.254.0.5 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 0.475/2.450/4.425/1.975 ms') 2024-11-10 19:34:27,437 - INFO - Ping to Metadata IP 169.254.0.5 of VM ctest-TestBasicVMVN-14138356-26833014 passed 2024-11-10 19:34:27,609 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2024-11-10 19:34:27,609 - DEBUG - Waiting to SSH to VM ctest-TestBasicVMVN-14138356-26833014, IP 31.1.1.3, Port 22 2024-11-10 19:34:27,765 - DEBUG - VM ctest-TestBasicVMVN-14138356-26833014 is ready for SSH connections 2024-11-10 19:34:27,765 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2024-11-10 19:34:27,765 - 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.56, gateway password: c0ntrail123 2024-11-10 19:34:27,765 - DEBUG - ping -s 56 -c 3 -W 1 31.1.2.4 2024-11-10 19:34:31,540 - 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=3.508 ms 64 bytes from 31.1.2.4: seq=1 ttl=63 time=0.908 ms 64 bytes from 31.1.2.4: seq=2 ttl=63 time=0.892 ms --- 31.1.2.4 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 0.892/1.769/3.508 ms 2024-11-10 19:34:31,540 - INFO - Ping to IP 31.1.2.4 from VM ctest-TestBasicVMVN-14138356-24362790 passed 2024-11-10 19:34:31,540 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2024-11-10 19:34:31,540 - 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.56, gateway password: c0ntrail123 2024-11-10 19:34:31,541 - DEBUG - ping -s 56 -c 3 -W 1 31.1.1.4 2024-11-10 19:34:35,609 - 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.122 ms 64 bytes from 31.1.1.4: seq=1 ttl=63 time=1.223 ms 64 bytes from 31.1.1.4: seq=2 ttl=63 time=1.009 ms --- 31.1.1.4 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 1.009/1.784/3.122 ms 2024-11-10 19:34:35,609 - INFO - Ping to IP 31.1.1.4 from VM ctest-TestBasicVMVN-14138356-78423558 passed 2024-11-10 19:34:35,610 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2024-11-10 19:34:35,610 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2024-11-10 19:34:35,610 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2024-11-10 19:34:35,610 - 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.56, gateway password: c0ntrail123 2024-11-10 19:34:35,610 - DEBUG - echo 0 > /proc/sys/net/ipv4/icmp_echo_ignore_broadcasts 2024-11-10 19:34:35,805 - DEBUG - None 2024-11-10 19:34:35,806 - 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.56, gateway password: c0ntrail123 2024-11-10 19:34:35,806 - DEBUG - echo 0 > /proc/sys/net/ipv4/icmp_echo_ignore_broadcasts 2024-11-10 19:34:36,002 - DEBUG - None 2024-11-10 19:34:36,002 - 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.56, gateway password: c0ntrail123 2024-11-10 19:34:36,002 - DEBUG - echo 0 > /proc/sys/net/ipv4/icmp_echo_ignore_broadcasts 2024-11-10 19:34:36,676 - DEBUG - None 2024-11-10 19:34:36,676 - 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.56, gateway password: c0ntrail123 2024-11-10 19:34:36,676 - DEBUG - ping -s 56 -c 3 -W 1 31.1.1.7 2024-11-10 19:34:38,919 - 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.127 ms 64 bytes from 31.1.1.3: seq=0 ttl=64 time=4.930 ms (DUP!) 64 bytes from 31.1.1.4: seq=1 ttl=64 time=0.431 ms 64 bytes from 31.1.1.3: seq=1 ttl=64 time=2.641 ms (DUP!) 64 bytes from 31.1.1.4: seq=2 ttl=64 time=0.423 ms --- 31.1.1.7 ping statistics --- 3 packets transmitted, 3 packets received, 2 duplicates, 0% packet loss round-trip min/avg/max = 0.423/2.110/4.930 ms 2024-11-10 19:34:38,919 - 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.56, gateway password: c0ntrail123 2024-11-10 19:34:38,919 - DEBUG - ping -s 56 -c 3 -W 1 224.0.0.1 2024-11-10 19:34:41,184 - DEBUG - PING 224.0.0.1 (224.0.0.1): 56 data bytes 64 bytes from 31.1.1.4: seq=0 ttl=64 time=1.064 ms 64 bytes from 31.1.2.4: seq=0 ttl=63 time=3.114 ms (DUP!) 64 bytes from 31.1.1.3: seq=0 ttl=64 time=4.490 ms (DUP!) 64 bytes from 31.1.1.4: seq=1 ttl=64 time=0.468 ms 64 bytes from 31.1.1.3: seq=1 ttl=64 time=1.828 ms (DUP!) 64 bytes from 31.1.2.4: seq=1 ttl=63 time=2.660 ms (DUP!) 64 bytes from 31.1.1.4: seq=2 ttl=64 time=0.581 ms --- 224.0.0.1 ping statistics --- 3 packets transmitted, 3 packets received, 4 duplicates, 0% packet loss round-trip min/avg/max = 0.468/2.029/4.490 ms 2024-11-10 19:34:41,185 - 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.56, gateway password: c0ntrail123 2024-11-10 19:34:41,185 - DEBUG - ping -s 56 -c 3 -W 1 255.255.255.255 2024-11-10 19:34:43,454 - 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.814 ms 64 bytes from 31.1.1.3: seq=0 ttl=64 time=4.149 ms (DUP!) 64 bytes from 31.1.2.4: seq=0 ttl=63 time=5.026 ms (DUP!) 64 bytes from 31.1.1.4: seq=1 ttl=64 time=0.444 ms 64 bytes from 31.1.1.3: seq=1 ttl=64 time=1.825 ms (DUP!) 64 bytes from 31.1.2.4: seq=1 ttl=63 time=2.733 ms (DUP!) 64 bytes from 31.1.1.4: seq=2 ttl=64 time=0.386 ms --- 255.255.255.255 ping statistics --- 3 packets transmitted, 3 packets received, 4 duplicates, 0% packet loss round-trip min/avg/max = 0.386/2.196/5.026 ms 2024-11-10 19:34:43,454 - INFO - Deleting VM ctest-TestBasicVMVN-14138356-26833014 2024-11-10 19:34:43,526 - INFO - Detaching port d6f7e0b9-ad5b-40a7-882b-4dfcb049d687 from VM ctest-TestBasicVMVN-14138356-78423558 2024-11-10 19:34:43,578 - INFO - Deleting VM ctest-TestBasicVMVN-14138356-78423558 2024-11-10 19:34:43,655 - INFO - Detaching port 78e2141f-43d3-457b-b697-cda59c3856aa from VM ctest-TestBasicVMVN-14138356-24362790 2024-11-10 19:34:43,689 - INFO - Deleting VM ctest-TestBasicVMVN-14138356-24362790 2024-11-10 19:34:43,795 - INFO - Deleting VN ctest-vn-76470877 2024-11-10 19:34:44,042 - DEBUG - Response for delete_port : () 2024-11-10 19:34:44,381 - DEBUG - Response for delete_port : () 2024-11-10 19:34:44,645 - DEBUG - Response for deleting network () 2024-11-10 19:34:44,645 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/8f6bb125-4f72-471c-be39-e679eda24968 2024-11-10 19:34:44,659 - DEBUG - Response Code: 404 2024-11-10 19:34:44,659 - DEBUG - Requesting: http://10.0.0.56:8082/domains 2024-11-10 19:34:44,667 - DEBUG - Requesting: http://10.0.0.56:8082/domain/3ce32a09-9753-46a1-b877-6f01cbf88076 2024-11-10 19:34:44,685 - DEBUG - Requesting: http://10.0.0.56:8082/project/86750aaa-3dc5-4d11-8a01-7e48c9337bdf 2024-11-10 19:34:44,769 - INFO - Validated that VN ctest-vn-76470877 is not found in API Server 2024-11-10 19:34:44,784 - DEBUG - VN ctest-vn-76470877 is not present in Agent 10.0.0.56 2024-11-10 19:34:44,784 - INFO - Validated that VN ctest-vn-76470877 is not in any agent 2024-11-10 19:34:44,790 - DEBUG - VRF 2 is not seen in agent 10.0.0.56 2024-11-10 19:34:44,795 - DEBUG - Vrouter 10.0.0.56 does not have vrf 2 for VN ctest-vn-76470877 2024-11-10 19:34:44,795 - INFO - Validated that all vrouters do not have the route table for VN default-domain:ctest-TestBasicVMVN-14138356:ctest-vn-76470877 2024-11-10 19:34:44,806 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn-76470877 info 2024-11-10 19:34:44,893 - INFO - IPAM: ctest-ipam-72581032 is not found in API Server 2024-11-10 19:34:44,906 - INFO - IPAM:ctest-ipam-72581032 is not found in control node 2024-11-10 19:34:45,168 - DEBUG - No XMPP flaps were noticed during the test
2024-11-10 19:34:45,168 - INFO - END TEST : test_ping_within_vn_two_vms_two_different_subnets : PASSED[0:00:43]
2024-11-10 19:34:45,168 - INFO - -------------------------------------------------------------------------------- 2024-11-10 19:34:45,960 - INFO - Deleted project: ctest-TestBasicVMVN-14138356, ID : 86750aaa-3dc5-4d11-8a01-7e48c9337bdf