2024-08-26 13:25:35,633 - INFO - Domain Default found not creating 2024-08-26 13:25:35,809 - INFO - Project ctest-TestRoutersBasic-52651967 not found, creating it 2024-08-26 13:25:36,325 - INFO - Created Project:ctest-TestRoutersBasic-52651967, ID : 2258d4ac-ec98-45a9-91af-7e4142f34f6e 2024-08-26 13:25:37,969 - INFO - ================================================================================ 2024-08-26 13:25:37,969 - INFO - STARTING TEST : test_basic_snat_behavior_without_external_connectivity 2024-08-26 13:25:37,970 - INFO - TEST DESCRIPTION : Create an external network, a router set router-gateway to external network launch a private network and attach it to router validate left vm pinging right vm through Snat 2024-08-26 13:25:38,234 - DEBUG - Nothing to compare xmpp stats {'10.0.0.24': {'10.20.0.5': '0'}} with 2024-08-26 13:25:38,234 - INFO - Initial checks done. Running the testcase now 2024-08-26 13:25:38,235 - INFO - 2024-08-26 13:25:38,970 - INFO - Default SG to be edited for allow all on project: ctest-TestRoutersBasic-52651967 2024-08-26 13:25:39,069 - INFO - Adding rules to the default security group in Project ctest-TestRoutersBasic-52651967 2024-08-26 13:25:39,393 - DEBUG - Reading firewall policy {'fq_name': ['default-domain', 'ctest-TestRoutersBasic-52651967', 'default']} 2024-08-26 13:25:39,993 - DEBUG - Response for create_network : {'network': {'id': '768d4457-3162-44bf-b81e-5b78efa92d5a', 'name': 'ctest-vn-48010182', 'tenant_id': '2258d4acec9845a991af7e4142f34f6e', 'project_id': '2258d4acec9845a991af7e4142f34f6e', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRoutersBasic-52651967', 'ctest-vn-48010182'], 'port_security_enabled': True, 'description': ''}} 2024-08-26 13:25:40,174 - DEBUG - Response for create_subnet : {'subnet': {'id': '38d99807-5915-4b53-b49e-1b02667f5fcd', 'name': '', 'tenant_id': '2258d4acec9845a991af7e4142f34f6e', 'network_id': '768d4457-3162-44bf-b81e-5b78efa92d5a', 'ip_version': 4, 'cidr': '121.128.101.128/26', 'allocation_pools': [{'start': '121.128.101.130', 'end': '121.128.101.190'}], 'gateway_ip': '121.128.101.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '121.128.101.130', 'tags': [], 'project_id': '2258d4acec9845a991af7e4142f34f6e'}} 2024-08-26 13:25:40,201 - INFO - Created VN ctest-vn-48010182 2024-08-26 13:25:40,253 - DEBUG - VN ctest-vn-48010182 UUID is 768d4457-3162-44bf-b81e-5b78efa92d5a 2024-08-26 13:25:41,993 - DEBUG - Response for create_network : {'network': {'id': '41a03cc1-a97f-4e64-9c9f-f7830739447b', 'name': 'ctest-ext_vn-43440497', 'tenant_id': '2258d4acec9845a991af7e4142f34f6e', 'project_id': '2258d4acec9845a991af7e4142f34f6e', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': True, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRoutersBasic-52651967', 'ctest-ext_vn-43440497'], 'port_security_enabled': True, 'description': ''}} 2024-08-26 13:25:42,505 - DEBUG - Response for create_subnet : {'subnet': {'id': '6ed373ea-8eae-44cd-9d74-0a4faac827d4', 'name': '', 'tenant_id': '2258d4acec9845a991af7e4142f34f6e', 'network_id': '41a03cc1-a97f-4e64-9c9f-f7830739447b', 'ip_version': 4, 'cidr': '48.198.31.64/26', 'allocation_pools': [{'start': '48.198.31.66', 'end': '48.198.31.126'}], 'gateway_ip': '48.198.31.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '48.198.31.66', 'tags': [], 'project_id': '2258d4acec9845a991af7e4142f34f6e'}} 2024-08-26 13:25:42,527 - INFO - Created VN ctest-ext_vn-43440497 2024-08-26 13:25:42,593 - DEBUG - VN ctest-ext_vn-43440497 UUID is 41a03cc1-a97f-4e64-9c9f-f7830739447b 2024-08-26 13:25:42,785 - DEBUG - Services list from nova: [, , ] 2024-08-26 13:25:43,847 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-1759-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-1759-1) 2024-08-26 13:25:45,039 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-1759-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-1759-1) 2024-08-26 13:25:45,133 - INFO - Setting gateway for router 81e5d371-f06e-4edc-b1ca-d19dd3976f84 to network 41a03cc1-a97f-4e64-9c9f-f7830739447b 2024-08-26 13:25:45,219 - INFO - Adding interface with subnet_id 38d99807-5915-4b53-b49e-1b02667f5fcd, port_id None to router 81e5d371-f06e-4edc-b1ca-d19dd3976f84 2024-08-26 13:25:45,605 - INFO - Waiting for VM ctest-TestRoutersBasic-52651967-75914801 to be up.. 2024-08-26 13:25:45,790 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2024-08-26 13:25:50,894 - DEBUG - VM is in ACTIVE state now 2024-08-26 13:25:50,894 - INFO - VM name : ctest-TestRoutersBasic-52651967-75914801 2024-08-26 13:25:51,007 - DEBUG - VM ctest-TestRoutersBasic-52651967-75914801 ID is 6b0d538c-1095-4419-8626-9efc3436ac9e 2024-08-26 13:25:51,034 - DEBUG - VM ctest-TestRoutersBasic-52651967-75914801 launched on Node cn-jenkins-deploy-platform-ansible-os-1759-1 2024-08-26 13:25:51,154 - DEBUG - Requesting: http://10.0.0.24:8082/virtual-machine/6b0d538c-1095-4419-8626-9efc3436ac9e 2024-08-26 13:25:51,508 - DEBUG - Requesting: http://10.0.0.24:8082/virtual-machine/6b0d538c-1095-4419-8626-9efc3436ac9e 2024-08-26 13:25:51,545 - DEBUG - Requesting: http://10.0.0.24:8082/virtual-machine-interface/1af40fe2-6bea-425b-b12a-48f340323fa8 2024-08-26 13:25:54,843 - 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-08-26 13:25:54,843 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestRoutersBasic-52651967-75914801 failed! 2024-08-26 13:25:54,897 - DEBUG - Gateway for vn default-domain:ctest-TestRoutersBasic-52651967:ctest-vn-48010182 is 121.128.101.129 and allocation pool is NOT set 2024-08-26 13:25:56,955 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n64 bytes from 169.254.0.3: icmp_seq=1 ttl=63 time=3.05 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=1.02 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1000ms\r\nrtt min/avg/max/mdev = 1.016/2.034/3.053/1.018 ms') 2024-08-26 13:25:56,955 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestRoutersBasic-52651967-75914801 passed 2024-08-26 13:25:57,109 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2024-08-26 13:25:57,109 - DEBUG - Waiting to SSH to VM ctest-TestRoutersBasic-52651967-75914801, IP 121.128.101.131, Port 22 2024-08-26 13:25:57,265 - DEBUG - VM ctest-TestRoutersBasic-52651967-75914801 is ready for SSH connections 2024-08-26 13:25:57,265 - INFO - Waiting for VM ctest-TestRoutersBasic-52651967-43579750 to be up.. 2024-08-26 13:25:57,353 - DEBUG - VM is in ACTIVE state now 2024-08-26 13:25:57,353 - INFO - VM name : ctest-TestRoutersBasic-52651967-43579750 2024-08-26 13:25:57,443 - DEBUG - VM ctest-TestRoutersBasic-52651967-43579750 ID is 56231e51-8ac3-490f-bf0e-1518f92832d7 2024-08-26 13:25:57,444 - DEBUG - VM ctest-TestRoutersBasic-52651967-43579750 launched on Node cn-jenkins-deploy-platform-ansible-os-1759-1 2024-08-26 13:25:57,536 - DEBUG - Requesting: http://10.0.0.24:8082/virtual-machine/56231e51-8ac3-490f-bf0e-1518f92832d7 2024-08-26 13:25:57,548 - DEBUG - Requesting: http://10.0.0.24:8082/virtual-machine-interface/29bf5b38-ca22-47f8-955c-529635b17751 2024-08-26 13:25:58,788 - 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=6.66 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=3.08 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 3.081/4.871/6.661/1.790 ms') 2024-08-26 13:25:58,789 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-TestRoutersBasic-52651967-43579750 passed 2024-08-26 13:25:58,941 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2024-08-26 13:25:58,941 - DEBUG - Waiting to SSH to VM ctest-TestRoutersBasic-52651967-43579750, IP 48.198.31.67, Port 22 2024-08-26 13:25:59,097 - DEBUG - VM ctest-TestRoutersBasic-52651967-43579750 is ready for SSH connections 2024-08-26 13:25:59,097 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2024-08-26 13:25:59,097 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 48.198.31.67, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.24, gateway password: c0ntrail123 2024-08-26 13:25:59,097 - DEBUG - ping -s 56 -c 3 -W 1 48.198.31.67 2024-08-26 13:26:02,829 - DEBUG - PING 48.198.31.67 (48.198.31.67): 56 data bytes 64 bytes from 48.198.31.67: seq=0 ttl=62 time=5.241 ms 64 bytes from 48.198.31.67: seq=1 ttl=62 time=1.073 ms 64 bytes from 48.198.31.67: seq=2 ttl=62 time=0.990 ms --- 48.198.31.67 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 0.990/2.434/5.241 ms 2024-08-26 13:26:02,829 - INFO - Ping to IP 48.198.31.67 from VM ctest-TestRoutersBasic-52651967-75914801 passed 2024-08-26 13:26:02,829 - INFO - Deleting interface with subnet_id 38d99807-5915-4b53-b49e-1b02667f5fcd, port_id None from router 81e5d371-f06e-4edc-b1ca-d19dd3976f84 2024-08-26 13:26:03,052 - INFO - Deleting VM ctest-TestRoutersBasic-52651967-43579750 2024-08-26 13:26:03,143 - INFO - Deleting VM ctest-TestRoutersBasic-52651967-75914801 2024-08-26 13:26:03,239 - INFO - Deleting VN ctest-ext_vn-43440497 2024-08-26 13:26:03,331 - DEBUG - VN 41a03cc1-a97f-4e64-9c9f-f7830739447b still in use: Unable to complete operation on network 41a03cc1-a97f-4e64-9c9f-f7830739447b. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-0ef70f44-7658-43f8-abdc-93f28f8f6bbd'] 2024-08-26 13:26:03,331 - WARNING - Deleting VN ctest-ext_vn-43440497 failed..Will retry 2024-08-26 13:26:05,415 - DEBUG - VN 41a03cc1-a97f-4e64-9c9f-f7830739447b still in use: Unable to complete operation on network 41a03cc1-a97f-4e64-9c9f-f7830739447b. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-ed2933a6-5ecb-4a74-a0df-e56602042888'] 2024-08-26 13:26:05,416 - WARNING - Deleting VN ctest-ext_vn-43440497 failed..Will retry 2024-08-26 13:26:07,556 - DEBUG - Response for deleting network () 2024-08-26 13:26:07,557 - INFO - Deleting VN ctest-vn-48010182 2024-08-26 13:26:07,683 - DEBUG - Response for deleting network () 2024-08-26 13:26:07,944 - DEBUG - No XMPP flaps were noticed during the test
2024-08-26 13:26:07,944 - INFO - END TEST : test_basic_snat_behavior_without_external_connectivity : PASSED[0:00:30]
2024-08-26 13:26:07,944 - INFO - -------------------------------------------------------------------------------- 2024-08-26 13:26:08,651 - INFO - Deleted project: ctest-TestRoutersBasic-52651967, ID : 2258d4ac-ec98-45a9-91af-7e4142f34f6e