2025-04-23 14:26:42,528 - INFO - Domain Default found not creating 2025-04-23 14:26:42,672 - INFO - Project ctest-TestRoutersBasic-10597597 not found, creating it 2025-04-23 14:26:43,144 - INFO - Created Project:ctest-TestRoutersBasic-10597597, ID : 367b1ba0-628b-46f9-965e-e2cc3e7efa8f 2025-04-23 14:26:44,687 - INFO - ================================================================================ 2025-04-23 14:26:44,687 - INFO - STARTING TEST : test_basic_snat_behavior_without_external_connectivity 2025-04-23 14:26:44,687 - 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 2025-04-23 14:26:44,941 - DEBUG - Nothing to compare xmpp stats {'10.0.0.129': {'10.20.0.17': '0'}} with 2025-04-23 14:26:44,941 - INFO - Initial checks done. Running the testcase now 2025-04-23 14:26:44,941 - INFO - 2025-04-23 14:26:45,726 - INFO - Default SG to be edited for allow all on project: ctest-TestRoutersBasic-10597597 2025-04-23 14:26:45,823 - INFO - Adding rules to the default security group in Project ctest-TestRoutersBasic-10597597 2025-04-23 14:26:46,151 - DEBUG - Reading firewall policy {'fq_name': ['default-domain', 'ctest-TestRoutersBasic-10597597', 'default']} 2025-04-23 14:26:46,454 - DEBUG - Response for create_network : {'network': {'id': '26118859-f41e-4918-a2a3-a9b15ac5b690', 'name': 'ctest-vn-67068655', 'tenant_id': '367b1ba0628b46f9965ee2cc3e7efa8f', 'project_id': '367b1ba0628b46f9965ee2cc3e7efa8f', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRoutersBasic-10597597', 'ctest-vn-67068655'], 'port_security_enabled': True, 'description': ''}} 2025-04-23 14:26:46,663 - DEBUG - Response for create_subnet : {'subnet': {'id': '7d4e958d-43de-4d72-9352-576076685858', 'name': '', 'tenant_id': '367b1ba0628b46f9965ee2cc3e7efa8f', 'network_id': '26118859-f41e-4918-a2a3-a9b15ac5b690', 'ip_version': 4, 'cidr': '62.148.245.0/26', 'allocation_pools': [{'start': '62.148.245.2', 'end': '62.148.245.62'}], 'gateway_ip': '62.148.245.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '62.148.245.2', 'tags': [], 'project_id': '367b1ba0628b46f9965ee2cc3e7efa8f'}} 2025-04-23 14:26:46,682 - INFO - Created VN ctest-vn-67068655 2025-04-23 14:26:46,735 - DEBUG - VN ctest-vn-67068655 UUID is 26118859-f41e-4918-a2a3-a9b15ac5b690 2025-04-23 14:26:47,081 - DEBUG - Response for create_network : {'network': {'id': 'b9abfffa-5fcc-42a5-bc18-e845ec4bab0b', 'name': 'ctest-ext_vn-26084389', 'tenant_id': '367b1ba0628b46f9965ee2cc3e7efa8f', 'project_id': '367b1ba0628b46f9965ee2cc3e7efa8f', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': True, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRoutersBasic-10597597', 'ctest-ext_vn-26084389'], 'port_security_enabled': True, 'description': ''}} 2025-04-23 14:26:47,301 - DEBUG - Response for create_subnet : {'subnet': {'id': '08ef1d3f-8af2-4191-a967-434d11d4e1ee', 'name': '', 'tenant_id': '367b1ba0628b46f9965ee2cc3e7efa8f', 'network_id': 'b9abfffa-5fcc-42a5-bc18-e845ec4bab0b', 'ip_version': 4, 'cidr': '192.52.170.64/26', 'allocation_pools': [{'start': '192.52.170.66', 'end': '192.52.170.126'}], 'gateway_ip': '192.52.170.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '192.52.170.66', 'tags': [], 'project_id': '367b1ba0628b46f9965ee2cc3e7efa8f'}} 2025-04-23 14:26:47,324 - INFO - Created VN ctest-ext_vn-26084389 2025-04-23 14:26:47,383 - DEBUG - VN ctest-ext_vn-26084389 UUID is b9abfffa-5fcc-42a5-bc18-e845ec4bab0b 2025-04-23 14:26:47,565 - DEBUG - Services list from nova: [, , ] 2025-04-23 14:26:48,658 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-3186-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-3186-1) 2025-04-23 14:26:50,029 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-3186-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-3186-1) 2025-04-23 14:26:50,123 - INFO - Setting gateway for router bc358c66-d483-4114-a848-ef7ed046a7fe to network b9abfffa-5fcc-42a5-bc18-e845ec4bab0b 2025-04-23 14:26:50,199 - INFO - Adding interface with subnet_id 7d4e958d-43de-4d72-9352-576076685858, port_id None to router bc358c66-d483-4114-a848-ef7ed046a7fe 2025-04-23 14:26:50,702 - INFO - Waiting for VM ctest-TestRoutersBasic-10597597-08137499 to be up.. 2025-04-23 14:26:50,899 - DEBUG - VM is in ACTIVE state now 2025-04-23 14:26:50,899 - INFO - VM name : ctest-TestRoutersBasic-10597597-08137499 2025-04-23 14:26:51,013 - DEBUG - VM ctest-TestRoutersBasic-10597597-08137499 ID is dce2653e-4f09-48be-9e04-406ae54ee379 2025-04-23 14:26:51,047 - DEBUG - VM ctest-TestRoutersBasic-10597597-08137499 launched on Node cn-jenkins-deploy-platform-ansible-os-3186-1 2025-04-23 14:26:51,161 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-machine/dce2653e-4f09-48be-9e04-406ae54ee379 2025-04-23 14:26:51,506 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-machine/dce2653e-4f09-48be-9e04-406ae54ee379 2025-04-23 14:26:51,549 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-machine-interface/66e8e05a-db66-400c-9440-92fb70cb7a6e 2025-04-23 14:26:54,853 - 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') 2025-04-23 14:26:54,853 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestRoutersBasic-10597597-08137499 failed! 2025-04-23 14:26:54,907 - DEBUG - Gateway for vn default-domain:ctest-TestRoutersBasic-10597597:ctest-vn-67068655 is 62.148.245.1 and allocation pool is NOT set 2025-04-23 14:26:58,984 - 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') 2025-04-23 14:26:58,984 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestRoutersBasic-10597597-08137499 failed! 2025-04-23 14:26:59,039 - DEBUG - Gateway for vn default-domain:ctest-TestRoutersBasic-10597597:ctest-vn-67068655 is 62.148.245.1 and allocation pool is NOT set 2025-04-23 14:27:01,102 - 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.05 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=1.29 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.288/3.670/6.053/2.382 ms') 2025-04-23 14:27:01,103 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestRoutersBasic-10597597-08137499 passed 2025-04-23 14:27:01,259 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-04-23 14:27:01,259 - DEBUG - Waiting to SSH to VM ctest-TestRoutersBasic-10597597-08137499, IP 62.148.245.3, Port 22 2025-04-23 14:27:01,414 - DEBUG - VM ctest-TestRoutersBasic-10597597-08137499 is ready for SSH connections 2025-04-23 14:27:01,415 - INFO - Waiting for VM ctest-TestRoutersBasic-10597597-87756765 to be up.. 2025-04-23 14:27:01,495 - DEBUG - VM is in ACTIVE state now 2025-04-23 14:27:01,495 - INFO - VM name : ctest-TestRoutersBasic-10597597-87756765 2025-04-23 14:27:01,567 - DEBUG - VM ctest-TestRoutersBasic-10597597-87756765 ID is ea6bba1f-dcda-4874-8661-bfdfec07eb60 2025-04-23 14:27:01,567 - DEBUG - VM ctest-TestRoutersBasic-10597597-87756765 launched on Node cn-jenkins-deploy-platform-ansible-os-3186-1 2025-04-23 14:27:01,641 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-machine/ea6bba1f-dcda-4874-8661-bfdfec07eb60 2025-04-23 14:27:01,650 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-machine-interface/554ea03b-a79d-4b83-931c-2285e0dca8bf 2025-04-23 14:27:02,890 - 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=5.62 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=1.75 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 = 1.753/3.687/5.621/1.934 ms') 2025-04-23 14:27:02,890 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-TestRoutersBasic-10597597-87756765 passed 2025-04-23 14:27:03,039 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-04-23 14:27:03,039 - DEBUG - Waiting to SSH to VM ctest-TestRoutersBasic-10597597-87756765, IP 192.52.170.67, Port 22 2025-04-23 14:27:03,198 - DEBUG - VM ctest-TestRoutersBasic-10597597-87756765 is ready for SSH connections 2025-04-23 14:27:03,198 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-04-23 14:27:03,198 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 192.52.170.67, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.129, gateway password: c0ntrail123 2025-04-23 14:27:03,199 - DEBUG - ping -s 56 -c 3 -W 1 192.52.170.67 2025-04-23 14:27:06,912 - DEBUG - PING 192.52.170.67 (192.52.170.67): 56 data bytes 64 bytes from 192.52.170.67: seq=0 ttl=62 time=6.239 ms 64 bytes from 192.52.170.67: seq=1 ttl=62 time=0.873 ms 64 bytes from 192.52.170.67: seq=2 ttl=62 time=0.941 ms --- 192.52.170.67 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 0.873/2.684/6.239 ms 2025-04-23 14:27:06,912 - INFO - Ping to IP 192.52.170.67 from VM ctest-TestRoutersBasic-10597597-08137499 passed 2025-04-23 14:27:06,912 - INFO - Deleting interface with subnet_id 7d4e958d-43de-4d72-9352-576076685858, port_id None from router bc358c66-d483-4114-a848-ef7ed046a7fe 2025-04-23 14:27:07,141 - INFO - Deleting VM ctest-TestRoutersBasic-10597597-87756765 2025-04-23 14:27:07,232 - INFO - Deleting VM ctest-TestRoutersBasic-10597597-08137499 2025-04-23 14:27:07,317 - INFO - Deleting VN ctest-ext_vn-26084389 2025-04-23 14:27:07,402 - DEBUG - VN b9abfffa-5fcc-42a5-bc18-e845ec4bab0b still in use: Unable to complete operation on network b9abfffa-5fcc-42a5-bc18-e845ec4bab0b. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-fdf8180a-8b82-4fd0-96da-385c64cd9a3d'] 2025-04-23 14:27:07,402 - WARNING - Deleting VN ctest-ext_vn-26084389 failed..Will retry 2025-04-23 14:27:09,515 - DEBUG - Response for deleting network () 2025-04-23 14:27:09,515 - INFO - Deleting VN ctest-vn-67068655 2025-04-23 14:27:09,618 - DEBUG - Response for deleting network () 2025-04-23 14:27:09,876 - DEBUG - No XMPP flaps were noticed during the test
2025-04-23 14:27:09,876 - INFO - END TEST : test_basic_snat_behavior_without_external_connectivity : PASSED[0:00:25]
2025-04-23 14:27:09,877 - INFO - -------------------------------------------------------------------------------- 2025-04-23 14:27:10,587 - INFO - Deleted project: ctest-TestRoutersBasic-10597597, ID : 367b1ba0-628b-46f9-965e-e2cc3e7efa8f