2025-06-14 19:43:20,773 - INFO - Domain Default found not creating
2025-06-14 19:43:20,917 - INFO - Project ctest-TestRoutersBasic-54160039 not found, creating it
2025-06-14 19:43:21,376 - INFO - Created Project:ctest-TestRoutersBasic-54160039, ID : bd954d23-aed0-426b-bd7f-b77a33d059b0
2025-06-14 19:43:22,916 - INFO - ================================================================================
2025-06-14 19:43:22,916 - INFO - STARTING TEST : test_basic_snat_behavior_without_external_connectivity
2025-06-14 19:43:22,916 - 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-06-14 19:43:23,173 - DEBUG - Nothing to compare xmpp stats {'10.0.0.49': {'10.20.0.17': '0'}} with
2025-06-14 19:43:23,173 - INFO - Initial checks done. Running the testcase now
2025-06-14 19:43:23,173 - INFO -
2025-06-14 19:43:23,965 - INFO - Default SG to be edited for allow all on project: ctest-TestRoutersBasic-54160039
2025-06-14 19:43:24,059 - INFO - Adding rules to the default security group in Project ctest-TestRoutersBasic-54160039
2025-06-14 19:43:24,394 - DEBUG - Reading firewall policy {'fq_name': ['default-domain', 'ctest-TestRoutersBasic-54160039', 'default']}
2025-06-14 19:43:24,679 - DEBUG - Response for create_network : {'network': {'id': '5bb5833c-10cd-4cc3-ae6a-d17f15ef7efd', 'name': 'ctest-vn-78808125', 'tenant_id': 'bd954d23aed0426bbd7fb77a33d059b0', 'project_id': 'bd954d23aed0426bbd7fb77a33d059b0', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRoutersBasic-54160039', 'ctest-vn-78808125'], 'port_security_enabled': True, 'description': ''}}
2025-06-14 19:43:24,850 - DEBUG - Response for create_subnet : {'subnet': {'id': '527eb51c-e520-4f26-babe-97c8e24673bd', 'name': '', 'tenant_id': 'bd954d23aed0426bbd7fb77a33d059b0', 'network_id': '5bb5833c-10cd-4cc3-ae6a-d17f15ef7efd', 'ip_version': 4, 'cidr': '152.5.29.0/26', 'allocation_pools': [{'start': '152.5.29.2', 'end': '152.5.29.62'}], 'gateway_ip': '152.5.29.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '152.5.29.2', 'tags': [], 'project_id': 'bd954d23aed0426bbd7fb77a33d059b0'}}
2025-06-14 19:43:24,868 - INFO - Created VN ctest-vn-78808125
2025-06-14 19:43:24,918 - DEBUG - VN ctest-vn-78808125 UUID is 5bb5833c-10cd-4cc3-ae6a-d17f15ef7efd
2025-06-14 19:43:25,246 - DEBUG - Response for create_network : {'network': {'id': '2bc85596-9d70-47d4-9728-b5a5bcc1b7f4', 'name': 'ctest-ext_vn-36423939', 'tenant_id': 'bd954d23aed0426bbd7fb77a33d059b0', 'project_id': 'bd954d23aed0426bbd7fb77a33d059b0', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': True, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRoutersBasic-54160039', 'ctest-ext_vn-36423939'], 'port_security_enabled': True, 'description': ''}}
2025-06-14 19:43:25,447 - DEBUG - Response for create_subnet : {'subnet': {'id': '50ed37d5-31d1-4d76-acb6-94b9aef3c1a3', 'name': '', 'tenant_id': 'bd954d23aed0426bbd7fb77a33d059b0', 'network_id': '2bc85596-9d70-47d4-9728-b5a5bcc1b7f4', 'ip_version': 4, 'cidr': '181.95.213.192/26', 'allocation_pools': [{'start': '181.95.213.194', 'end': '181.95.213.254'}], 'gateway_ip': '181.95.213.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '181.95.213.194', 'tags': [], 'project_id': 'bd954d23aed0426bbd7fb77a33d059b0'}}
2025-06-14 19:43:25,465 - INFO - Created VN ctest-ext_vn-36423939
2025-06-14 19:43:25,518 - DEBUG - VN ctest-ext_vn-36423939 UUID is 2bc85596-9d70-47d4-9728-b5a5bcc1b7f4
2025-06-14 19:43:25,679 - DEBUG - Services list from nova: [, , ]
2025-06-14 19:43:26,755 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-3451-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-3451-1)
2025-06-14 19:43:27,965 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-3451-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-3451-1)
2025-06-14 19:43:28,054 - INFO - Setting gateway for router b3fd231a-6124-4ef7-9219-b6f702184cf8 to network 2bc85596-9d70-47d4-9728-b5a5bcc1b7f4
2025-06-14 19:43:28,118 - INFO - Adding interface with subnet_id 527eb51c-e520-4f26-babe-97c8e24673bd, port_id None to router b3fd231a-6124-4ef7-9219-b6f702184cf8
2025-06-14 19:43:28,472 - INFO - Waiting for VM ctest-TestRoutersBasic-54160039-93241064 to be up..
2025-06-14 19:43:28,651 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2025-06-14 19:43:33,728 - DEBUG - VM is in ACTIVE state now
2025-06-14 19:43:33,729 - INFO - VM name : ctest-TestRoutersBasic-54160039-93241064
2025-06-14 19:43:33,800 - DEBUG - VM ctest-TestRoutersBasic-54160039-93241064 ID is 0b0d08d8-834e-4484-999c-77126233ad95
2025-06-14 19:43:33,819 - DEBUG - VM ctest-TestRoutersBasic-54160039-93241064 launched on Node cn-jenkins-deploy-platform-ansible-os-3451-1
2025-06-14 19:43:33,893 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-machine/0b0d08d8-834e-4484-999c-77126233ad95
2025-06-14 19:43:34,224 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-machine/0b0d08d8-834e-4484-999c-77126233ad95
2025-06-14 19:43:34,259 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-machine-interface/2b987b32-8cf5-43f5-8836-5d68116fafbb
2025-06-14 19:43:37,516 - 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 1011ms')
2025-06-14 19:43:37,516 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestRoutersBasic-54160039-93241064 failed!
2025-06-14 19:43:37,582 - DEBUG - Gateway for vn default-domain:ctest-TestRoutersBasic-54160039:ctest-vn-78808125 is 152.5.29.1 and allocation pool is NOT set
2025-06-14 19:43:39,646 - 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.93 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=1.41 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.408/3.668/5.928/2.260 ms')
2025-06-14 19:43:39,646 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestRoutersBasic-54160039-93241064 passed
2025-06-14 19:43:39,802 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-06-14 19:43:39,803 - DEBUG - Waiting to SSH to VM ctest-TestRoutersBasic-54160039-93241064, IP 152.5.29.3, Port 22
2025-06-14 19:43:39,966 - DEBUG - VM ctest-TestRoutersBasic-54160039-93241064 is ready for SSH connections
2025-06-14 19:43:39,966 - INFO - Waiting for VM ctest-TestRoutersBasic-54160039-50616820 to be up..
2025-06-14 19:43:40,046 - DEBUG - VM is in ACTIVE state now
2025-06-14 19:43:40,046 - INFO - VM name : ctest-TestRoutersBasic-54160039-50616820
2025-06-14 19:43:40,124 - DEBUG - VM ctest-TestRoutersBasic-54160039-50616820 ID is 2f409345-3056-4c34-a75d-f251b40ab7a4
2025-06-14 19:43:40,124 - DEBUG - VM ctest-TestRoutersBasic-54160039-50616820 launched on Node cn-jenkins-deploy-platform-ansible-os-3451-1
2025-06-14 19:43:40,197 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-machine/2f409345-3056-4c34-a75d-f251b40ab7a4
2025-06-14 19:43:40,206 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-machine-interface/eb2c9a91-8f2e-466c-a377-c8a3079e4575
2025-06-14 19:43:41,451 - 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.91 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.567 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.567/1.737/2.908/1.170 ms')
2025-06-14 19:43:41,451 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-TestRoutersBasic-54160039-50616820 passed
2025-06-14 19:43:41,607 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-06-14 19:43:41,607 - DEBUG - Waiting to SSH to VM ctest-TestRoutersBasic-54160039-50616820, IP 181.95.213.195, Port 22
2025-06-14 19:43:41,762 - DEBUG - VM ctest-TestRoutersBasic-54160039-50616820 is ready for SSH connections
2025-06-14 19:43:41,762 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-06-14 19:43:41,762 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 181.95.213.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.49, gateway password: c0ntrail123
2025-06-14 19:43:41,762 - DEBUG - ping -s 56 -c 3 -W 1 181.95.213.195
2025-06-14 19:43:45,872 - DEBUG - PING 181.95.213.195 (181.95.213.195): 56 data bytes
64 bytes from 181.95.213.195: seq=0 ttl=62 time=4.927 ms
64 bytes from 181.95.213.195: seq=1 ttl=62 time=0.827 ms
64 bytes from 181.95.213.195: seq=2 ttl=62 time=0.785 ms
--- 181.95.213.195 ping statistics ---
3 packets transmitted, 3 packets received, 0% packet loss
round-trip min/avg/max = 0.785/2.179/4.927 ms
2025-06-14 19:43:45,872 - INFO - Ping to IP 181.95.213.195 from VM ctest-TestRoutersBasic-54160039-93241064 passed
2025-06-14 19:43:45,872 - INFO - Deleting interface with subnet_id 527eb51c-e520-4f26-babe-97c8e24673bd, port_id None from router b3fd231a-6124-4ef7-9219-b6f702184cf8
2025-06-14 19:43:46,089 - INFO - Deleting VM ctest-TestRoutersBasic-54160039-50616820
2025-06-14 19:43:46,157 - INFO - Deleting VM ctest-TestRoutersBasic-54160039-93241064
2025-06-14 19:43:46,255 - INFO - Deleting VN ctest-ext_vn-36423939
2025-06-14 19:43:46,347 - DEBUG - VN 2bc85596-9d70-47d4-9728-b5a5bcc1b7f4 still in use: Unable to complete operation on network 2bc85596-9d70-47d4-9728-b5a5bcc1b7f4. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-b9153713-6abd-4281-976f-a9f0d429edf5']
2025-06-14 19:43:46,347 - WARNING - Deleting VN ctest-ext_vn-36423939 failed..Will retry
2025-06-14 19:43:48,454 - DEBUG - Response for deleting network ()
2025-06-14 19:43:48,454 - INFO - Deleting VN ctest-vn-78808125
2025-06-14 19:43:48,555 - DEBUG - Response for deleting network ()
2025-06-14 19:43:48,808 - DEBUG - No XMPP flaps were noticed during the test
2025-06-14 19:43:48,808 - INFO - END TEST : test_basic_snat_behavior_without_external_connectivity : PASSED[0:00:26]
2025-06-14 19:43:48,808 - INFO - --------------------------------------------------------------------------------
2025-06-14 19:43:49,519 - INFO - Deleted project: ctest-TestRoutersBasic-54160039, ID : bd954d23-aed0-426b-bd7f-b77a33d059b0