2025-01-02 02:26:16,201 - INFO - Domain Default found not creating
2025-01-02 02:26:16,344 - INFO - Project ctest-TestRoutersBasic-74571032 not found, creating it
2025-01-02 02:26:16,834 - INFO - Created Project:ctest-TestRoutersBasic-74571032, ID : 08e0f3fb-ce4f-4af9-a4a6-ae32cab4fab2
2025-01-02 02:26:19,269 - INFO - ================================================================================
2025-01-02 02:26:19,269 - INFO - STARTING TEST : test_basic_snat_behavior_without_external_connectivity
2025-01-02 02:26:19,270 - 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-01-02 02:26:19,535 - DEBUG - Nothing to compare xmpp stats {'10.0.0.35': {'10.0.0.35': '0'}} with
2025-01-02 02:26:19,535 - INFO - Initial checks done. Running the testcase now
2025-01-02 02:26:19,535 - INFO -
2025-01-02 02:26:20,348 - INFO - Default SG to be edited for allow all on project: ctest-TestRoutersBasic-74571032
2025-01-02 02:26:20,449 - INFO - Adding rules to the default security group in Project ctest-TestRoutersBasic-74571032
2025-01-02 02:26:20,783 - DEBUG - Reading firewall policy {'fq_name': ['default-domain', 'ctest-TestRoutersBasic-74571032', 'default']}
2025-01-02 02:26:21,117 - DEBUG - Response for create_network : {'network': {'id': 'a788f4f6-f804-476e-8620-6b7b4c210049', 'name': 'ctest-vn-68248582', 'tenant_id': '08e0f3fbce4f4af9a4a6ae32cab4fab2', 'project_id': '08e0f3fbce4f4af9a4a6ae32cab4fab2', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRoutersBasic-74571032', 'ctest-vn-68248582'], 'port_security_enabled': True, 'description': ''}}
2025-01-02 02:26:21,341 - DEBUG - Response for create_subnet : {'subnet': {'id': '4b228e76-aafb-486b-8a7e-87c060e93398', 'name': '', 'tenant_id': '08e0f3fbce4f4af9a4a6ae32cab4fab2', 'network_id': 'a788f4f6-f804-476e-8620-6b7b4c210049', 'ip_version': 4, 'cidr': '46.65.248.64/26', 'allocation_pools': [{'start': '46.65.248.66', 'end': '46.65.248.126'}], 'gateway_ip': '46.65.248.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '46.65.248.66', 'tags': [], 'project_id': '08e0f3fbce4f4af9a4a6ae32cab4fab2'}}
2025-01-02 02:26:21,362 - INFO - Created VN ctest-vn-68248582
2025-01-02 02:26:21,419 - DEBUG - VN ctest-vn-68248582 UUID is a788f4f6-f804-476e-8620-6b7b4c210049
2025-01-02 02:26:21,783 - DEBUG - Response for create_network : {'network': {'id': 'c98e90bc-0d88-431f-8e9c-95bd3ec8e514', 'name': 'ctest-ext_vn-15766046', 'tenant_id': '08e0f3fbce4f4af9a4a6ae32cab4fab2', 'project_id': '08e0f3fbce4f4af9a4a6ae32cab4fab2', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': True, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRoutersBasic-74571032', 'ctest-ext_vn-15766046'], 'port_security_enabled': True, 'description': ''}}
2025-01-02 02:26:22,018 - DEBUG - Response for create_subnet : {'subnet': {'id': 'c4910327-76b6-46c6-b677-9116f080fa90', 'name': '', 'tenant_id': '08e0f3fbce4f4af9a4a6ae32cab4fab2', 'network_id': 'c98e90bc-0d88-431f-8e9c-95bd3ec8e514', 'ip_version': 4, 'cidr': '7.46.26.0/26', 'allocation_pools': [{'start': '7.46.26.2', 'end': '7.46.26.62'}], 'gateway_ip': '7.46.26.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '7.46.26.2', 'tags': [], 'project_id': '08e0f3fbce4f4af9a4a6ae32cab4fab2'}}
2025-01-02 02:26:22,040 - INFO - Created VN ctest-ext_vn-15766046
2025-01-02 02:26:22,095 - DEBUG - VN ctest-ext_vn-15766046 UUID is c98e90bc-0d88-431f-8e9c-95bd3ec8e514
2025-01-02 02:26:22,274 - DEBUG - Services list from nova: [, , ]
2025-01-02 02:26:23,391 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-2509-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-2509-1)
2025-01-02 02:26:24,666 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-2509-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-2509-1)
2025-01-02 02:26:24,734 - INFO - Setting gateway for router 7140c8ec-0944-4ea6-8e56-1e5788e88d64 to network c98e90bc-0d88-431f-8e9c-95bd3ec8e514
2025-01-02 02:26:24,800 - INFO - Adding interface with subnet_id 4b228e76-aafb-486b-8a7e-87c060e93398, port_id None to router 7140c8ec-0944-4ea6-8e56-1e5788e88d64
2025-01-02 02:26:25,287 - INFO - Waiting for VM ctest-TestRoutersBasic-74571032-60324693 to be up..
2025-01-02 02:26:25,474 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2025-01-02 02:26:30,564 - DEBUG - VM is in ACTIVE state now
2025-01-02 02:26:30,564 - INFO - VM name : ctest-TestRoutersBasic-74571032-60324693
2025-01-02 02:26:30,655 - DEBUG - VM ctest-TestRoutersBasic-74571032-60324693 ID is 8ffeb209-8bfe-4c7e-b874-c9acb479da75
2025-01-02 02:26:30,677 - DEBUG - VM ctest-TestRoutersBasic-74571032-60324693 launched on Node cn-jenkins-deploy-platform-ansible-os-2509-1.
2025-01-02 02:26:30,763 - DEBUG - Requesting: http://10.0.0.35:8082/virtual-machine/8ffeb209-8bfe-4c7e-b874-c9acb479da75
2025-01-02 02:26:31,103 - DEBUG - Requesting: http://10.0.0.35:8082/virtual-machine/8ffeb209-8bfe-4c7e-b874-c9acb479da75
2025-01-02 02:26:31,140 - DEBUG - Requesting: http://10.0.0.35:8082/virtual-machine-interface/d10ceb41-d5ef-4e17-82f1-1ff6c3ea5967
2025-01-02 02:26:34,395 - 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')
2025-01-02 02:26:34,396 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestRoutersBasic-74571032-60324693 failed!
2025-01-02 02:26:34,455 - DEBUG - Gateway for vn default-domain:ctest-TestRoutersBasic-74571032:ctest-vn-68248582 is 46.65.248.65 and allocation pool is NOT set
2025-01-02 02:26:36,519 - 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=5.63 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=2.49 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.494/4.064/5.634/1.570 ms')
2025-01-02 02:26:36,519 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestRoutersBasic-74571032-60324693 passed
2025-01-02 02:26:36,679 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-01-02 02:26:36,680 - DEBUG - Waiting to SSH to VM ctest-TestRoutersBasic-74571032-60324693, IP 46.65.248.67, Port 22
2025-01-02 02:26:36,845 - DEBUG - VM ctest-TestRoutersBasic-74571032-60324693 is ready for SSH connections
2025-01-02 02:26:36,845 - INFO - Waiting for VM ctest-TestRoutersBasic-74571032-08378424 to be up..
2025-01-02 02:26:36,935 - DEBUG - VM is in ACTIVE state now
2025-01-02 02:26:36,935 - INFO - VM name : ctest-TestRoutersBasic-74571032-08378424
2025-01-02 02:26:37,024 - DEBUG - VM ctest-TestRoutersBasic-74571032-08378424 ID is 3f3b1843-8996-4e3a-835b-4ba8aa6ca32b
2025-01-02 02:26:37,024 - DEBUG - VM ctest-TestRoutersBasic-74571032-08378424 launched on Node cn-jenkins-deploy-platform-ansible-os-2509-1.
2025-01-02 02:26:37,109 - DEBUG - Requesting: http://10.0.0.35:8082/virtual-machine/3f3b1843-8996-4e3a-835b-4ba8aa6ca32b
2025-01-02 02:26:37,119 - DEBUG - Requesting: http://10.0.0.35:8082/virtual-machine-interface/9f110853-45c2-457c-b65a-2e33dfffaad1
2025-01-02 02:26:38,360 - 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.75 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.462 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.462/2.106/3.750/1.644 ms')
2025-01-02 02:26:38,360 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-TestRoutersBasic-74571032-08378424 passed
2025-01-02 02:26:38,519 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-01-02 02:26:38,520 - DEBUG - Waiting to SSH to VM ctest-TestRoutersBasic-74571032-08378424, IP 7.46.26.3, Port 22
2025-01-02 02:26:38,679 - DEBUG - VM ctest-TestRoutersBasic-74571032-08378424 is ready for SSH connections
2025-01-02 02:26:38,679 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-01-02 02:26:38,679 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 7.46.26.3, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.35, gateway password: c0ntrail123
2025-01-02 02:26:38,679 - DEBUG - ping -s 56 -c 3 -W 1 7.46.26.3
2025-01-02 02:26:42,424 - DEBUG - PING 7.46.26.3 (7.46.26.3): 56 data bytes
64 bytes from 7.46.26.3: seq=0 ttl=62 time=7.117 ms
64 bytes from 7.46.26.3: seq=1 ttl=62 time=1.022 ms
64 bytes from 7.46.26.3: seq=2 ttl=62 time=1.028 ms
--- 7.46.26.3 ping statistics ---
3 packets transmitted, 3 packets received, 0% packet loss
round-trip min/avg/max = 1.022/3.055/7.117 ms
2025-01-02 02:26:42,424 - INFO - Ping to IP 7.46.26.3 from VM ctest-TestRoutersBasic-74571032-60324693 passed
2025-01-02 02:26:42,424 - INFO - Deleting interface with subnet_id 4b228e76-aafb-486b-8a7e-87c060e93398, port_id None from router 7140c8ec-0944-4ea6-8e56-1e5788e88d64
2025-01-02 02:26:42,633 - INFO - Deleting VM ctest-TestRoutersBasic-74571032-08378424
2025-01-02 02:26:42,706 - INFO - Deleting VM ctest-TestRoutersBasic-74571032-60324693
2025-01-02 02:26:42,817 - INFO - Deleting VN ctest-ext_vn-15766046
2025-01-02 02:26:42,903 - DEBUG - VN c98e90bc-0d88-431f-8e9c-95bd3ec8e514 still in use: Unable to complete operation on network c98e90bc-0d88-431f-8e9c-95bd3ec8e514. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-ca07c728-eb45-4d2f-b298-0efab148598f']
2025-01-02 02:26:42,903 - WARNING - Deleting VN ctest-ext_vn-15766046 failed..Will retry
2025-01-02 02:26:45,049 - DEBUG - Response for deleting network ()
2025-01-02 02:26:45,049 - INFO - Deleting VN ctest-vn-68248582
2025-01-02 02:26:45,182 - DEBUG - Response for deleting network ()
2025-01-02 02:26:45,441 - DEBUG - No XMPP flaps were noticed during the test
2025-01-02 02:26:45,442 - INFO - END TEST : test_basic_snat_behavior_without_external_connectivity : PASSED[0:00:26]
2025-01-02 02:26:45,442 - INFO - --------------------------------------------------------------------------------
2025-01-02 02:26:46,208 - INFO - Deleted project: ctest-TestRoutersBasic-74571032, ID : 08e0f3fb-ce4f-4af9-a4a6-ae32cab4fab2