2026-06-19 11:00:32,777 - INFO - Domain Default found not creating
2026-06-19 11:00:32,940 - INFO - Project ctest-TestRoutersBasic-07965850 not found, creating it
2026-06-19 11:00:33,553 - INFO - Created Project:ctest-TestRoutersBasic-07965850, ID : b38b94e8-244d-4374-9e67-41657ed688b6
2026-06-19 11:00:34,963 - INFO - ================================================================================
2026-06-19 11:00:34,963 - INFO - STARTING TEST : test_basic_snat_behavior_without_external_connectivity
2026-06-19 11:00:34,963 - 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
2026-06-19 11:00:35,222 - DEBUG - Nothing to compare xmpp stats {'10.0.0.38': {'10.20.0.17': '0'}} with
2026-06-19 11:00:35,222 - INFO - Initial checks done. Running the testcase now
2026-06-19 11:00:35,222 - INFO -
2026-06-19 11:00:35,943 - INFO - Default SG to be edited for allow all on project: ctest-TestRoutersBasic-07965850
2026-06-19 11:00:36,039 - INFO - Adding rules to the default security group in Project ctest-TestRoutersBasic-07965850
2026-06-19 11:00:36,359 - DEBUG - Reading firewall policy {'fq_name': ['default-domain', 'ctest-TestRoutersBasic-07965850', 'default']}
2026-06-19 11:00:36,625 - DEBUG - Response for create_network : {'network': {'id': '18118a79-d4bc-40a5-89c4-b614013bfaad', 'name': 'ctest-vn-24997637', 'tenant_id': 'b38b94e8244d43749e6741657ed688b6', 'project_id': 'b38b94e8244d43749e6741657ed688b6', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRoutersBasic-07965850', 'ctest-vn-24997637'], 'port_security_enabled': True, 'description': ''}}
2026-06-19 11:00:36,798 - DEBUG - Response for create_subnet : {'subnet': {'id': '2aaa3294-103c-42d3-9d6a-b339bb26f4ed', 'name': '', 'tenant_id': 'b38b94e8244d43749e6741657ed688b6', 'network_id': '18118a79-d4bc-40a5-89c4-b614013bfaad', 'ip_version': 4, 'cidr': '146.155.29.128/26', 'allocation_pools': [{'start': '146.155.29.130', 'end': '146.155.29.190'}], 'gateway_ip': '146.155.29.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '146.155.29.130', 'tags': [], 'project_id': 'b38b94e8244d43749e6741657ed688b6'}}
2026-06-19 11:00:36,811 - INFO - Created VN ctest-vn-24997637
2026-06-19 11:00:36,863 - DEBUG - VN ctest-vn-24997637 UUID is 18118a79-d4bc-40a5-89c4-b614013bfaad
2026-06-19 11:00:37,172 - DEBUG - Response for create_network : {'network': {'id': '91b1aa1d-af26-4132-a803-6d33940c517c', 'name': 'ctest-ext_vn-16853480', 'tenant_id': 'b38b94e8244d43749e6741657ed688b6', 'project_id': 'b38b94e8244d43749e6741657ed688b6', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': True, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRoutersBasic-07965850', 'ctest-ext_vn-16853480'], 'port_security_enabled': True, 'description': ''}}
2026-06-19 11:00:37,319 - DEBUG - Response for create_subnet : {'subnet': {'id': '81978ff8-cc61-41c0-a161-a0d77cf78f5d', 'name': '', 'tenant_id': 'b38b94e8244d43749e6741657ed688b6', 'network_id': '91b1aa1d-af26-4132-a803-6d33940c517c', 'ip_version': 4, 'cidr': '20.88.60.0/26', 'allocation_pools': [{'start': '20.88.60.2', 'end': '20.88.60.62'}], 'gateway_ip': '20.88.60.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '20.88.60.2', 'tags': [], 'project_id': 'b38b94e8244d43749e6741657ed688b6'}}
2026-06-19 11:00:37,332 - INFO - Created VN ctest-ext_vn-16853480
2026-06-19 11:00:37,383 - DEBUG - VN ctest-ext_vn-16853480 UUID is 91b1aa1d-af26-4132-a803-6d33940c517c
2026-06-19 11:00:37,557 - DEBUG - Services list from nova: [, , ]
2026-06-19 11:00:38,541 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-5992-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-5992-1)
2026-06-19 11:00:39,867 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-5992-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-5992-1)
2026-06-19 11:00:39,920 - INFO - Setting gateway for router e456eb2d-936e-4e59-a4c0-1bed1df8c5a0 to network 91b1aa1d-af26-4132-a803-6d33940c517c
2026-06-19 11:00:39,966 - INFO - Adding interface with subnet_id 2aaa3294-103c-42d3-9d6a-b339bb26f4ed, port_id None to router e456eb2d-936e-4e59-a4c0-1bed1df8c5a0
2026-06-19 11:00:40,144 - INFO - Waiting for VM ctest-TestRoutersBasic-07965850-36057639 to be up..
2026-06-19 11:00:40,190 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2026-06-19 11:00:45,253 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2026-06-19 11:00:50,327 - DEBUG - VM is in ACTIVE state now
2026-06-19 11:00:50,327 - INFO - VM name : ctest-TestRoutersBasic-07965850-36057639
2026-06-19 11:00:50,399 - DEBUG - VM ctest-TestRoutersBasic-07965850-36057639 ID is 2cac07b1-9fdd-4f12-9d20-c376c62e69c5
2026-06-19 11:00:50,421 - DEBUG - VM ctest-TestRoutersBasic-07965850-36057639 launched on Node cn-jenkins-deploy-platform-ansible-os-5992-1
2026-06-19 11:00:50,485 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/2cac07b1-9fdd-4f12-9d20-c376c62e69c5
2026-06-19 11:00:50,802 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/2cac07b1-9fdd-4f12-9d20-c376c62e69c5
2026-06-19 11:00:50,826 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/5b532ac3-5fab-410d-80e3-08fcfdf0f5c3
2026-06-19 11:00:54,064 - DEBUG - (True, 'PING 169.254.0.5 (169.254.0.5) 56(84) bytes of data.\r\n\r\n--- 169.254.0.5 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1008ms')
2026-06-19 11:00:54,064 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-TestRoutersBasic-07965850-36057639 failed!
2026-06-19 11:00:54,115 - DEBUG - Gateway for vn default-domain:ctest-TestRoutersBasic-07965850:ctest-vn-24997637 is 146.155.29.129 and allocation pool is NOT set
2026-06-19 11:00:56,179 - DEBUG - (True, 'PING 169.254.0.5 (169.254.0.5) 56(84) bytes of data.\r\n64 bytes from 169.254.0.5: icmp_seq=1 ttl=63 time=8.36 ms\r\n64 bytes from 169.254.0.5: icmp_seq=2 ttl=63 time=0.364 ms\r\n\r\n--- 169.254.0.5 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 0.364/4.361/8.359/3.997 ms')
2026-06-19 11:00:56,179 - INFO - Ping to Metadata IP 169.254.0.5 of VM ctest-TestRoutersBasic-07965850-36057639 passed
2026-06-19 11:00:56,327 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-19 11:00:56,327 - DEBUG - Waiting to SSH to VM ctest-TestRoutersBasic-07965850-36057639, IP 146.155.29.131, Port 22
2026-06-19 11:00:56,482 - DEBUG - VM ctest-TestRoutersBasic-07965850-36057639 is ready for SSH connections
2026-06-19 11:00:56,482 - INFO - Waiting for VM ctest-TestRoutersBasic-07965850-41632693 to be up..
2026-06-19 11:00:56,543 - DEBUG - VM is in ACTIVE state now
2026-06-19 11:00:56,543 - INFO - VM name : ctest-TestRoutersBasic-07965850-41632693
2026-06-19 11:00:56,602 - DEBUG - VM ctest-TestRoutersBasic-07965850-41632693 ID is 1d258232-9aa2-49b2-b232-2e016ac98d65
2026-06-19 11:00:56,602 - DEBUG - VM ctest-TestRoutersBasic-07965850-41632693 launched on Node cn-jenkins-deploy-platform-ansible-os-5992-1
2026-06-19 11:00:56,664 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/1d258232-9aa2-49b2-b232-2e016ac98d65
2026-06-19 11:00:56,671 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/05660311-b3ac-402e-87bd-316869da06f2
2026-06-19 11:00:57,897 - DEBUG - (True, 'PING 169.254.0.6 (169.254.0.6) 56(84) bytes of data.\r\n64 bytes from 169.254.0.6: icmp_seq=1 ttl=63 time=6.85 ms\r\n64 bytes from 169.254.0.6: icmp_seq=2 ttl=63 time=0.405 ms\r\n\r\n--- 169.254.0.6 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 0.405/3.626/6.848/3.221 ms')
2026-06-19 11:00:57,897 - INFO - Ping to Metadata IP 169.254.0.6 of VM ctest-TestRoutersBasic-07965850-41632693 passed
2026-06-19 11:00:58,043 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-19 11:00:58,043 - DEBUG - Waiting to SSH to VM ctest-TestRoutersBasic-07965850-41632693, IP 20.88.60.4, Port 22
2026-06-19 11:00:58,198 - DEBUG - VM ctest-TestRoutersBasic-07965850-41632693 is ready for SSH connections
2026-06-19 11:00:58,199 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-19 11:00:58,199 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 20.88.60.4, host_string: cirros@169.254.0.5, password: cubswin:)gateway: ubuntu@10.0.0.38, gateway password: c0ntrail123
2026-06-19 11:00:58,199 - DEBUG - ping -s 56 -c 3 -W 1 20.88.60.4
2026-06-19 11:01:00,755 - DEBUG - PING 20.88.60.4 (20.88.60.4): 56 data bytes
64 bytes from 20.88.60.4: seq=0 ttl=62 time=4.820 ms
64 bytes from 20.88.60.4: seq=1 ttl=62 time=0.888 ms
64 bytes from 20.88.60.4: seq=2 ttl=62 time=0.779 ms
--- 20.88.60.4 ping statistics ---
3 packets transmitted, 3 packets received, 0% packet loss
round-trip min/avg/max = 0.779/2.162/4.820 ms
2026-06-19 11:01:00,755 - INFO - Ping to IP 20.88.60.4 from VM ctest-TestRoutersBasic-07965850-36057639 passed
2026-06-19 11:01:00,755 - INFO - Deleting interface with subnet_id 2aaa3294-103c-42d3-9d6a-b339bb26f4ed, port_id None from router e456eb2d-936e-4e59-a4c0-1bed1df8c5a0
2026-06-19 11:01:00,924 - INFO - Deleting VM ctest-TestRoutersBasic-07965850-41632693
2026-06-19 11:01:00,991 - INFO - Deleting VM ctest-TestRoutersBasic-07965850-36057639
2026-06-19 11:01:01,057 - INFO - Deleting VN ctest-ext_vn-16853480
2026-06-19 11:01:01,109 - DEBUG - VN 91b1aa1d-af26-4132-a803-6d33940c517c still in use: Unable to complete operation on network 91b1aa1d-af26-4132-a803-6d33940c517c. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-481e0648-3a0d-451a-be68-aa11e409e169']
2026-06-19 11:01:01,109 - WARNING - Deleting VN ctest-ext_vn-16853480 failed..Will retry
2026-06-19 11:01:03,187 - DEBUG - Response for deleting network ()
2026-06-19 11:01:03,187 - INFO - Deleting VN ctest-vn-24997637
2026-06-19 11:01:03,275 - DEBUG - Response for deleting network ()
2026-06-19 11:01:03,524 - DEBUG - No XMPP flaps were noticed during the test
2026-06-19 11:01:03,524 - INFO - END TEST : test_basic_snat_behavior_without_external_connectivity : PASSED[0:00:29]
2026-06-19 11:01:03,524 - INFO - --------------------------------------------------------------------------------
2026-06-19 11:01:04,194 - INFO - Deleted project: ctest-TestRoutersBasic-07965850, ID : b38b94e8-244d-4374-9e67-41657ed688b6