2024-11-13 08:47:22,758 - INFO - Domain Default found not creating
2024-11-13 08:47:22,965 - INFO - Project ctest-TestRoutersBasic-08750858 not found, creating it
2024-11-13 08:47:23,500 - INFO - Created Project:ctest-TestRoutersBasic-08750858, ID : 2c3bffe8-d04d-44e9-82df-c4c7fbc78d97
2024-11-13 08:47:25,067 - INFO - ================================================================================
2024-11-13 08:47:25,068 - INFO - STARTING TEST : test_basic_snat_behavior_without_external_connectivity
2024-11-13 08:47:25,068 - 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-11-13 08:47:25,329 - DEBUG - Nothing to compare xmpp stats {'10.0.0.24': {'10.20.0.13': '0'}} with
2024-11-13 08:47:25,329 - INFO - Initial checks done. Running the testcase now
2024-11-13 08:47:25,329 - INFO -
2024-11-13 08:47:26,114 - INFO - Default SG to be edited for allow all on project: ctest-TestRoutersBasic-08750858
2024-11-13 08:47:26,216 - INFO - Adding rules to the default security group in Project ctest-TestRoutersBasic-08750858
2024-11-13 08:47:26,567 - DEBUG - Reading firewall policy {'fq_name': ['default-domain', 'ctest-TestRoutersBasic-08750858', 'default']}
2024-11-13 08:47:26,907 - DEBUG - Response for create_network : {'network': {'id': 'f13684e1-aaa7-4de7-bac0-c1b5f7cfe327', 'name': 'ctest-vn-20792985', 'tenant_id': '2c3bffe8d04d44e982dfc4c7fbc78d97', 'project_id': '2c3bffe8d04d44e982dfc4c7fbc78d97', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRoutersBasic-08750858', 'ctest-vn-20792985'], 'port_security_enabled': True, 'description': ''}}
2024-11-13 08:47:27,136 - DEBUG - Response for create_subnet : {'subnet': {'id': 'ec60e7ff-d244-448d-a8e1-d7162134799c', 'name': '', 'tenant_id': '2c3bffe8d04d44e982dfc4c7fbc78d97', 'network_id': 'f13684e1-aaa7-4de7-bac0-c1b5f7cfe327', 'ip_version': 4, 'cidr': '88.24.148.0/26', 'allocation_pools': [{'start': '88.24.148.2', 'end': '88.24.148.62'}], 'gateway_ip': '88.24.148.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '88.24.148.2', 'tags': [], 'project_id': '2c3bffe8d04d44e982dfc4c7fbc78d97'}}
2024-11-13 08:47:27,161 - INFO - Created VN ctest-vn-20792985
2024-11-13 08:47:27,220 - DEBUG - VN ctest-vn-20792985 UUID is f13684e1-aaa7-4de7-bac0-c1b5f7cfe327
2024-11-13 08:47:27,629 - DEBUG - Response for create_network : {'network': {'id': '32c048a3-4228-4b5f-adb4-297eb4810a0e', 'name': 'ctest-ext_vn-78812695', 'tenant_id': '2c3bffe8d04d44e982dfc4c7fbc78d97', 'project_id': '2c3bffe8d04d44e982dfc4c7fbc78d97', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': True, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRoutersBasic-08750858', 'ctest-ext_vn-78812695'], 'port_security_enabled': True, 'description': ''}}
2024-11-13 08:47:27,863 - DEBUG - Response for create_subnet : {'subnet': {'id': '33f0ed91-ef78-4359-a466-4b837ed58976', 'name': '', 'tenant_id': '2c3bffe8d04d44e982dfc4c7fbc78d97', 'network_id': '32c048a3-4228-4b5f-adb4-297eb4810a0e', 'ip_version': 4, 'cidr': '80.121.211.0/26', 'allocation_pools': [{'start': '80.121.211.2', 'end': '80.121.211.62'}], 'gateway_ip': '80.121.211.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '80.121.211.2', 'tags': [], 'project_id': '2c3bffe8d04d44e982dfc4c7fbc78d97'}}
2024-11-13 08:47:27,883 - INFO - Created VN ctest-ext_vn-78812695
2024-11-13 08:47:27,944 - DEBUG - VN ctest-ext_vn-78812695 UUID is 32c048a3-4228-4b5f-adb4-297eb4810a0e
2024-11-13 08:47:28,135 - DEBUG - Services list from nova: [, , ]
2024-11-13 08:47:29,246 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-2281-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-2281-1)
2024-11-13 08:47:30,489 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-2281-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-2281-1)
2024-11-13 08:47:30,594 - INFO - Setting gateway for router 1586b5e8-eea5-4bb8-a062-625c805ff7af to network 32c048a3-4228-4b5f-adb4-297eb4810a0e
2024-11-13 08:47:30,735 - INFO - Adding interface with subnet_id ec60e7ff-d244-448d-a8e1-d7162134799c, port_id None to router 1586b5e8-eea5-4bb8-a062-625c805ff7af
2024-11-13 08:47:31,118 - INFO - Waiting for VM ctest-TestRoutersBasic-08750858-16049943 to be up..
2024-11-13 08:47:31,307 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2024-11-13 08:47:36,424 - DEBUG - VM is in ACTIVE state now
2024-11-13 08:47:36,424 - INFO - VM name : ctest-TestRoutersBasic-08750858-16049943
2024-11-13 08:47:36,528 - DEBUG - VM ctest-TestRoutersBasic-08750858-16049943 ID is 89634f39-b68a-4924-a44e-f604c678fe8e
2024-11-13 08:47:36,553 - DEBUG - VM ctest-TestRoutersBasic-08750858-16049943 launched on Node cn-jenkins-deploy-platform-ansible-os-2281-1
2024-11-13 08:47:36,668 - DEBUG - Requesting: http://10.0.0.24:8082/virtual-machine/89634f39-b68a-4924-a44e-f604c678fe8e
2024-11-13 08:47:37,009 - DEBUG - Requesting: http://10.0.0.24:8082/virtual-machine/89634f39-b68a-4924-a44e-f604c678fe8e
2024-11-13 08:47:37,057 - DEBUG - Requesting: http://10.0.0.24:8082/virtual-machine-interface/e80bf06b-cad9-4da9-82a7-bed53540468c
2024-11-13 08:47:40,349 - 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 1014ms')
2024-11-13 08:47:40,349 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestRoutersBasic-08750858-16049943 failed!
2024-11-13 08:47:40,416 - DEBUG - Gateway for vn default-domain:ctest-TestRoutersBasic-08750858:ctest-vn-20792985 is 88.24.148.1 and allocation pool is NOT set
2024-11-13 08:47:42,476 - 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.71 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=2.55 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.547/3.129/3.712/0.582 ms')
2024-11-13 08:47:42,476 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestRoutersBasic-08750858-16049943 passed
2024-11-13 08:47:42,640 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2024-11-13 08:47:42,640 - DEBUG - Waiting to SSH to VM ctest-TestRoutersBasic-08750858-16049943, IP 88.24.148.3, Port 22
2024-11-13 08:47:42,813 - DEBUG - VM ctest-TestRoutersBasic-08750858-16049943 is ready for SSH connections
2024-11-13 08:47:42,814 - INFO - Waiting for VM ctest-TestRoutersBasic-08750858-42235696 to be up..
2024-11-13 08:47:42,907 - DEBUG - VM is in ACTIVE state now
2024-11-13 08:47:42,907 - INFO - VM name : ctest-TestRoutersBasic-08750858-42235696
2024-11-13 08:47:42,989 - DEBUG - VM ctest-TestRoutersBasic-08750858-42235696 ID is a5c88dee-a32c-4453-86be-c8ff77b55cec
2024-11-13 08:47:42,989 - DEBUG - VM ctest-TestRoutersBasic-08750858-42235696 launched on Node cn-jenkins-deploy-platform-ansible-os-2281-1
2024-11-13 08:47:43,078 - DEBUG - Requesting: http://10.0.0.24:8082/virtual-machine/a5c88dee-a32c-4453-86be-c8ff77b55cec
2024-11-13 08:47:43,089 - DEBUG - Requesting: http://10.0.0.24:8082/virtual-machine-interface/3d191dff-84a3-4ca1-9d67-f0bb31a9fc1b
2024-11-13 08:47:44,345 - 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=2.89 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.476 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 = 0.476/1.685/2.894/1.209 ms')
2024-11-13 08:47:44,346 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-TestRoutersBasic-08750858-42235696 passed
2024-11-13 08:47:44,516 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2024-11-13 08:47:44,516 - DEBUG - Waiting to SSH to VM ctest-TestRoutersBasic-08750858-42235696, IP 80.121.211.3, Port 22
2024-11-13 08:47:44,672 - DEBUG - VM ctest-TestRoutersBasic-08750858-42235696 is ready for SSH connections
2024-11-13 08:47:44,672 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2024-11-13 08:47:44,672 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 80.121.211.3, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.24, gateway password: c0ntrail123
2024-11-13 08:47:44,672 - DEBUG - ping -s 56 -c 3 -W 1 80.121.211.3
2024-11-13 08:47:48,404 - DEBUG - PING 80.121.211.3 (80.121.211.3): 56 data bytes
64 bytes from 80.121.211.3: seq=0 ttl=62 time=11.315 ms
64 bytes from 80.121.211.3: seq=1 ttl=62 time=0.896 ms
64 bytes from 80.121.211.3: seq=2 ttl=62 time=0.847 ms
--- 80.121.211.3 ping statistics ---
3 packets transmitted, 3 packets received, 0% packet loss
round-trip min/avg/max = 0.847/4.352/11.315 ms
2024-11-13 08:47:48,405 - INFO - Ping to IP 80.121.211.3 from VM ctest-TestRoutersBasic-08750858-16049943 passed
2024-11-13 08:47:48,405 - INFO - Deleting interface with subnet_id ec60e7ff-d244-448d-a8e1-d7162134799c, port_id None from router 1586b5e8-eea5-4bb8-a062-625c805ff7af
2024-11-13 08:47:48,640 - INFO - Deleting VM ctest-TestRoutersBasic-08750858-42235696
2024-11-13 08:47:48,727 - INFO - Deleting VM ctest-TestRoutersBasic-08750858-16049943
2024-11-13 08:47:48,811 - INFO - Deleting VN ctest-ext_vn-78812695
2024-11-13 08:47:48,896 - DEBUG - VN 32c048a3-4228-4b5f-adb4-297eb4810a0e still in use: Unable to complete operation on network 32c048a3-4228-4b5f-adb4-297eb4810a0e. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-1578eda2-79e0-4634-8786-4c74d2318b9a']
2024-11-13 08:47:48,896 - WARNING - Deleting VN ctest-ext_vn-78812695 failed..Will retry
2024-11-13 08:47:51,009 - DEBUG - Response for deleting network ()
2024-11-13 08:47:51,009 - INFO - Deleting VN ctest-vn-20792985
2024-11-13 08:47:51,134 - DEBUG - Response for deleting network ()
2024-11-13 08:47:51,390 - DEBUG - No XMPP flaps were noticed during the test
2024-11-13 08:47:51,390 - INFO - END TEST : test_basic_snat_behavior_without_external_connectivity : PASSED[0:00:26]
2024-11-13 08:47:51,390 - INFO - --------------------------------------------------------------------------------
2024-11-13 08:47:52,211 - INFO - Deleted project: ctest-TestRoutersBasic-08750858, ID : 2c3bffe8-d04d-44e9-82df-c4c7fbc78d97