2025-10-20 12:12:59,990 - INFO - Domain Default found not creating
2025-10-20 12:13:00,156 - INFO - Project ctest-TestRP-59425275 not found, creating it
2025-10-20 12:13:00,624 - INFO - Created Project:ctest-TestRP-59425275, ID : 296cf2d7-48b6-42a2-a299-c0b75e15e8a2
2025-10-20 12:13:02,090 - DEBUG - Services list from nova: [, , ]
2025-10-20 12:13:02,090 - DEBUG - Hosts: {'nova': ['cn-jenkins-deploy-platform-ansible-os-4375-1']}
2025-10-20 12:13:02,093 - INFO - ================================================================================
2025-10-20 12:13:02,093 - INFO - STARTING TEST : test_rp_interface
2025-10-20 12:13:02,093 - INFO - TEST DESCRIPTION :
1. Create a routing policy with interface match.
2. Launch VMs.
3. Attach policy to VN and confirm if policy takes hold.
2025-10-20 12:13:02,351 - DEBUG - Nothing to compare xmpp stats {'10.0.0.38': {'10.20.0.18': '0'}} with
2025-10-20 12:13:02,351 - INFO - Initial checks done. Running the testcase now
2025-10-20 12:13:02,351 - INFO -
2025-10-20 12:13:02,871 - DEBUG - Response for create_network : {'network': {'id': '3930aa0c-4b2f-4091-850f-a24b169aa17e', 'name': 'ctest-bgpaas_vn-96709810', 'tenant_id': '296cf2d748b642a2a299c0b75e15e8a2', 'project_id': '296cf2d748b642a2a299c0b75e15e8a2', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRP-59425275', 'ctest-bgpaas_vn-96709810'], 'port_security_enabled': True, 'description': ''}}
2025-10-20 12:13:03,083 - DEBUG - Response for create_subnet : {'subnet': {'id': 'e411b8b1-33c4-4a95-b5b5-841bdc3c18e4', 'name': '', 'tenant_id': '296cf2d748b642a2a299c0b75e15e8a2', 'network_id': '3930aa0c-4b2f-4091-850f-a24b169aa17e', 'ip_version': 4, 'cidr': '40.223.15.128/26', 'allocation_pools': [{'start': '40.223.15.130', 'end': '40.223.15.190'}], 'gateway_ip': '40.223.15.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '40.223.15.130', 'tags': [], 'project_id': '296cf2d748b642a2a299c0b75e15e8a2'}}
2025-10-20 12:13:03,100 - INFO - Created VN ctest-bgpaas_vn-96709810
2025-10-20 12:13:03,154 - DEBUG - VN ctest-bgpaas_vn-96709810 UUID is 3930aa0c-4b2f-4091-850f-a24b169aa17e
2025-10-20 12:13:03,657 - DEBUG - Response for create_network : {'network': {'id': 'ce2219c2-97de-48cc-b94b-a973f07659a3', 'name': 'ctest-bgpaas_vn-79773214', 'tenant_id': '296cf2d748b642a2a299c0b75e15e8a2', 'project_id': '296cf2d748b642a2a299c0b75e15e8a2', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRP-59425275', 'ctest-bgpaas_vn-79773214'], 'port_security_enabled': True, 'description': ''}}
2025-10-20 12:13:03,843 - DEBUG - Response for create_subnet : {'subnet': {'id': 'bebb7d8c-4ae8-490d-95d6-3469e5564b5e', 'name': '', 'tenant_id': '296cf2d748b642a2a299c0b75e15e8a2', 'network_id': 'ce2219c2-97de-48cc-b94b-a973f07659a3', 'ip_version': 4, 'cidr': '52.231.246.64/26', 'allocation_pools': [{'start': '52.231.246.66', 'end': '52.231.246.126'}], 'gateway_ip': '52.231.246.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '52.231.246.66', 'tags': [], 'project_id': '296cf2d748b642a2a299c0b75e15e8a2'}}
2025-10-20 12:13:03,861 - INFO - Created VN ctest-bgpaas_vn-79773214
2025-10-20 12:13:03,913 - DEBUG - VN ctest-bgpaas_vn-79773214 UUID is ce2219c2-97de-48cc-b94b-a973f07659a3
2025-10-20 12:13:05,278 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-4375-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-4375-1)
2025-10-20 12:13:06,440 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-4375-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-4375-1)
2025-10-20 12:13:06,440 - INFO - Waiting for VM test_vm to be up..
2025-10-20 12:13:06,515 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2025-10-20 12:13:11,604 - DEBUG - VM is in ACTIVE state now
2025-10-20 12:13:11,605 - INFO - VM name : test_vm
2025-10-20 12:13:11,684 - DEBUG - VM test_vm ID is 81937f88-6062-48ae-a788-e11f2d1419a0
2025-10-20 12:13:11,706 - DEBUG - VM test_vm launched on Node cn-jenkins-deploy-platform-ansible-os-4375-1
2025-10-20 12:13:11,779 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/81937f88-6062-48ae-a788-e11f2d1419a0
2025-10-20 12:13:12,074 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/81937f88-6062-48ae-a788-e11f2d1419a0
2025-10-20 12:13:12,106 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/6e7c8b64-4ccf-4530-ac85-72b8dd9b1673
2025-10-20 12:13:15,380 - 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 1026ms')
2025-10-20 12:13:15,380 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM test_vm failed!
2025-10-20 12:13:15,434 - DEBUG - Gateway for vn default-domain:ctest-TestRP-59425275:ctest-bgpaas_vn-96709810 is 40.223.15.129 and allocation pool is NOT set
2025-10-20 12:13:17,501 - 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=4.08 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=2.83 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.825/3.451/4.078/0.626 ms')
2025-10-20 12:13:17,501 - INFO - Ping to Metadata IP 169.254.0.3 of VM test_vm passed
2025-10-20 12:13:17,654 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-20 12:13:17,654 - DEBUG - Waiting to SSH to VM test_vm, IP 40.223.15.131, Port 22
2025-10-20 12:13:17,809 - DEBUG - VM test_vm is ready for SSH connections
2025-10-20 12:13:17,809 - INFO - Waiting for VM test2_vm to be up..
2025-10-20 12:13:17,882 - DEBUG - VM is in ACTIVE state now
2025-10-20 12:13:17,882 - INFO - VM name : test2_vm
2025-10-20 12:13:17,962 - DEBUG - VM test2_vm ID is 06d593b4-e764-43f7-8d48-4e40f6e77aeb
2025-10-20 12:13:17,963 - DEBUG - VM test2_vm launched on Node cn-jenkins-deploy-platform-ansible-os-4375-1
2025-10-20 12:13:18,046 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/06d593b4-e764-43f7-8d48-4e40f6e77aeb
2025-10-20 12:13:18,055 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/7516cd90-ad47-44bd-9cbb-84284afabf6d
2025-10-20 12:13:19,308 - 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.95 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.553 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1000ms\r\nrtt min/avg/max/mdev = 0.553/1.752/2.951/1.199 ms')
2025-10-20 12:13:19,308 - INFO - Ping to Metadata IP 169.254.0.4 of VM test2_vm passed
2025-10-20 12:13:19,462 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-20 12:13:19,462 - DEBUG - Waiting to SSH to VM test2_vm, IP 52.231.246.67, Port 22
2025-10-20 12:13:19,521 - DEBUG - Error on ssh to cirros@169.254.0.4:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.4/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.4/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.4/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2025-10-20 12:13:19,597 - DEBUG - VM test2_vm is NOT ready for SSH connections, VM status: ACTIVE
2025-10-20 12:13:24,597 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-20 12:13:24,597 - DEBUG - Waiting to SSH to VM test2_vm, IP 52.231.246.67, Port 22
2025-10-20 12:13:24,753 - DEBUG - VM test2_vm is ready for SSH connections
2025-10-20 12:13:24,897 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-20 12:13:24,903 - DEBUG - Requesting: http://10.20.0.18:8083/Snh_ShowRouteReq?x=default-domain:ctest-TestRP-59425275:ctest-bgpaas_vn-96709810:ctest-bgpaas_vn-96709810.inet.0
2025-10-20 12:13:25,914 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-20 12:13:25,920 - DEBUG - Requesting: http://10.20.0.18:8083/Snh_ShowRouteReq?x=default-domain:ctest-TestRP-59425275:ctest-bgpaas_vn-96709810:ctest-bgpaas_vn-96709810.inet.0
2025-10-20 12:13:25,930 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-20 12:13:25,930 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 52.231.246.67, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.38, gateway password: c0ntrail123
2025-10-20 12:13:25,930 - DEBUG - ping -s 56 -c 3 -W 1 52.231.246.67
2025-10-20 12:13:29,639 - DEBUG - PING 52.231.246.67 (52.231.246.67): 56 data bytes
64 bytes from 52.231.246.67: seq=0 ttl=63 time=3.962 ms
64 bytes from 52.231.246.67: seq=1 ttl=63 time=0.757 ms
64 bytes from 52.231.246.67: seq=2 ttl=63 time=1.046 ms
--- 52.231.246.67 ping statistics ---
3 packets transmitted, 3 packets received, 0% packet loss
round-trip min/avg/max = 0.757/1.921/3.962 ms
2025-10-20 12:13:29,639 - INFO - Ping to IP 52.231.246.67 from VM test_vm passed
2025-10-20 12:13:29,696 - INFO - Deleting VM test2_vm
2025-10-20 12:13:29,780 - INFO - Deleting VM test_vm
2025-10-20 12:13:29,850 - INFO - Deleting VN ctest-bgpaas_vn-79773214
2025-10-20 12:13:29,885 - DEBUG - VN ce2219c2-97de-48cc-b94b-a973f07659a3 still in use: Unable to complete operation on network ce2219c2-97de-48cc-b94b-a973f07659a3. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-95444bff-589d-413b-b6c1-4d09642cd172']
2025-10-20 12:13:29,886 - WARNING - Deleting VN ctest-bgpaas_vn-79773214 failed..Will retry
2025-10-20 12:13:31,985 - DEBUG - Response for deleting network ()
2025-10-20 12:13:31,985 - INFO - Deleting VN ctest-bgpaas_vn-96709810
2025-10-20 12:13:32,092 - DEBUG - Response for deleting network ()
2025-10-20 12:13:32,347 - DEBUG - No XMPP flaps were noticed during the test
2025-10-20 12:13:32,348 - INFO - END TEST : test_rp_interface : PASSED[0:00:30]
2025-10-20 12:13:32,348 - INFO - --------------------------------------------------------------------------------
2025-10-20 12:13:33,031 - INFO - Deleted project: ctest-TestRP-59425275, ID : 296cf2d7-48b6-42a2-a299-c0b75e15e8a2