2025-12-09 01:13:00,873 - INFO - Domain Default found not creating
2025-12-09 01:13:01,043 - INFO - Project ctest-TestRP-09478007 not found, creating it
2025-12-09 01:13:01,523 - INFO - Created Project:ctest-TestRP-09478007, ID : 7d82e27e-0eba-40fc-b184-c0678b097b19
2025-12-09 01:13:03,133 - DEBUG - Services list from nova: [, , ]
2025-12-09 01:13:03,133 - DEBUG - Hosts: {'nova': ['cn-jenkins-deploy-platform-ansible-os-4863-1']}
2025-12-09 01:13:03,136 - INFO - ================================================================================
2025-12-09 01:13:03,136 - INFO - STARTING TEST : test_rp_interface
2025-12-09 01:13:03,136 - 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-12-09 01:13:03,392 - DEBUG - Nothing to compare xmpp stats {'10.0.0.38': {'10.20.0.14': '0'}} with
2025-12-09 01:13:03,392 - INFO - Initial checks done. Running the testcase now
2025-12-09 01:13:03,392 - INFO -
2025-12-09 01:13:03,975 - DEBUG - Response for create_network : {'network': {'id': '717db356-c775-4471-9bae-ccaeabd48326', 'name': 'ctest-bgpaas_vn-92675722', 'tenant_id': '7d82e27e0eba40fcb184c0678b097b19', 'project_id': '7d82e27e0eba40fcb184c0678b097b19', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRP-09478007', 'ctest-bgpaas_vn-92675722'], 'port_security_enabled': True, 'description': ''}}
2025-12-09 01:13:04,156 - DEBUG - Response for create_subnet : {'subnet': {'id': '4fa81a9d-3366-4acc-b088-562f22f98f70', 'name': '', 'tenant_id': '7d82e27e0eba40fcb184c0678b097b19', 'network_id': '717db356-c775-4471-9bae-ccaeabd48326', 'ip_version': 4, 'cidr': '114.120.240.0/26', 'allocation_pools': [{'start': '114.120.240.2', 'end': '114.120.240.62'}], 'gateway_ip': '114.120.240.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '114.120.240.2', 'tags': [], 'project_id': '7d82e27e0eba40fcb184c0678b097b19'}}
2025-12-09 01:13:04,172 - INFO - Created VN ctest-bgpaas_vn-92675722
2025-12-09 01:13:04,227 - DEBUG - VN ctest-bgpaas_vn-92675722 UUID is 717db356-c775-4471-9bae-ccaeabd48326
2025-12-09 01:13:04,729 - DEBUG - Response for create_network : {'network': {'id': '8e936dfa-5018-4920-a5fb-f1575b305ec6', 'name': 'ctest-bgpaas_vn-10512110', 'tenant_id': '7d82e27e0eba40fcb184c0678b097b19', 'project_id': '7d82e27e0eba40fcb184c0678b097b19', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRP-09478007', 'ctest-bgpaas_vn-10512110'], 'port_security_enabled': True, 'description': ''}}
2025-12-09 01:13:04,907 - DEBUG - Response for create_subnet : {'subnet': {'id': 'f5028348-15bf-4490-bcc9-01bb266f7cef', 'name': '', 'tenant_id': '7d82e27e0eba40fcb184c0678b097b19', 'network_id': '8e936dfa-5018-4920-a5fb-f1575b305ec6', 'ip_version': 4, 'cidr': '130.1.138.128/26', 'allocation_pools': [{'start': '130.1.138.130', 'end': '130.1.138.190'}], 'gateway_ip': '130.1.138.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '130.1.138.130', 'tags': [], 'project_id': '7d82e27e0eba40fcb184c0678b097b19'}}
2025-12-09 01:13:04,924 - INFO - Created VN ctest-bgpaas_vn-10512110
2025-12-09 01:13:04,975 - DEBUG - VN ctest-bgpaas_vn-10512110 UUID is 8e936dfa-5018-4920-a5fb-f1575b305ec6
2025-12-09 01:13:06,559 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-4863-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-4863-1)
2025-12-09 01:13:07,803 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-4863-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-4863-1)
2025-12-09 01:13:07,803 - INFO - Waiting for VM test_vm to be up..
2025-12-09 01:13:07,895 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2025-12-09 01:13:12,984 - DEBUG - VM is in ACTIVE state now
2025-12-09 01:13:12,984 - INFO - VM name : test_vm
2025-12-09 01:13:13,063 - DEBUG - VM test_vm ID is 57ffd2f1-a581-45a6-b6e3-1010ee30bfc6
2025-12-09 01:13:13,082 - DEBUG - VM test_vm launched on Node cn-jenkins-deploy-platform-ansible-os-4863-1
2025-12-09 01:13:13,154 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/57ffd2f1-a581-45a6-b6e3-1010ee30bfc6
2025-12-09 01:13:13,491 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/57ffd2f1-a581-45a6-b6e3-1010ee30bfc6
2025-12-09 01:13:13,530 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/a03c4d4f-57c5-4eee-9ec9-4a463633a54c
2025-12-09 01:13:16,790 - 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 1019ms')
2025-12-09 01:13:16,790 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM test_vm failed!
2025-12-09 01:13:16,843 - DEBUG - Gateway for vn default-domain:ctest-TestRP-09478007:ctest-bgpaas_vn-92675722 is 114.120.240.1 and allocation pool is NOT set
2025-12-09 01:13:18,909 - 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.44 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=1.38 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.383/2.911/4.439/1.528 ms')
2025-12-09 01:13:18,910 - INFO - Ping to Metadata IP 169.254.0.3 of VM test_vm passed
2025-12-09 01:13:19,059 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-12-09 01:13:19,059 - DEBUG - Waiting to SSH to VM test_vm, IP 114.120.240.3, Port 22
2025-12-09 01:13:19,214 - DEBUG - VM test_vm is ready for SSH connections
2025-12-09 01:13:19,214 - INFO - Waiting for VM test2_vm to be up..
2025-12-09 01:13:19,283 - DEBUG - VM is in ACTIVE state now
2025-12-09 01:13:19,284 - INFO - VM name : test2_vm
2025-12-09 01:13:19,352 - DEBUG - VM test2_vm ID is 310ca2d5-bce8-48fe-ad0f-f65b4199a44e
2025-12-09 01:13:19,353 - DEBUG - VM test2_vm launched on Node cn-jenkins-deploy-platform-ansible-os-4863-1
2025-12-09 01:13:19,422 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/310ca2d5-bce8-48fe-ad0f-f65b4199a44e
2025-12-09 01:13:19,429 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/03118949-47f6-41d3-877e-9517ed2b1148
2025-12-09 01:13:20,669 - 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=5.37 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=1.60 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 = 1.597/3.485/5.373/1.888 ms')
2025-12-09 01:13:20,669 - INFO - Ping to Metadata IP 169.254.0.4 of VM test2_vm passed
2025-12-09 01:13:20,823 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-12-09 01:13:20,823 - DEBUG - Waiting to SSH to VM test2_vm, IP 130.1.138.131, Port 22
2025-12-09 01:13:20,978 - DEBUG - VM test2_vm is ready for SSH connections
2025-12-09 01:13:21,127 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-12-09 01:13:21,132 - DEBUG - Requesting: http://10.20.0.14:8083/Snh_ShowRouteReq?x=default-domain:ctest-TestRP-09478007:ctest-bgpaas_vn-92675722:ctest-bgpaas_vn-92675722.inet.0
2025-12-09 01:13:22,141 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-12-09 01:13:22,147 - DEBUG - Requesting: http://10.20.0.14:8083/Snh_ShowRouteReq?x=default-domain:ctest-TestRP-09478007:ctest-bgpaas_vn-92675722:ctest-bgpaas_vn-92675722.inet.0
2025-12-09 01:13:22,156 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-12-09 01:13:22,157 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 130.1.138.131, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.38, gateway password: c0ntrail123
2025-12-09 01:13:22,157 - DEBUG - ping -s 56 -c 3 -W 1 130.1.138.131
2025-12-09 01:13:25,875 - DEBUG - PING 130.1.138.131 (130.1.138.131): 56 data bytes
64 bytes from 130.1.138.131: seq=0 ttl=63 time=5.181 ms
64 bytes from 130.1.138.131: seq=1 ttl=63 time=0.912 ms
64 bytes from 130.1.138.131: seq=2 ttl=63 time=0.987 ms
--- 130.1.138.131 ping statistics ---
3 packets transmitted, 3 packets received, 0% packet loss
round-trip min/avg/max = 0.912/2.360/5.181 ms
2025-12-09 01:13:25,875 - INFO - Ping to IP 130.1.138.131 from VM test_vm passed
2025-12-09 01:13:25,955 - INFO - Deleting VM test2_vm
2025-12-09 01:13:26,021 - INFO - Deleting VM test_vm
2025-12-09 01:13:26,090 - INFO - Deleting VN ctest-bgpaas_vn-10512110
2025-12-09 01:13:26,126 - DEBUG - VN 8e936dfa-5018-4920-a5fb-f1575b305ec6 still in use: Unable to complete operation on network 8e936dfa-5018-4920-a5fb-f1575b305ec6. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-f1f3910d-6fa1-41ff-9327-0ee5ee4ede50']
2025-12-09 01:13:26,127 - WARNING - Deleting VN ctest-bgpaas_vn-10512110 failed..Will retry
2025-12-09 01:13:28,222 - DEBUG - Response for deleting network ()
2025-12-09 01:13:28,222 - INFO - Deleting VN ctest-bgpaas_vn-92675722
2025-12-09 01:13:28,316 - DEBUG - Response for deleting network ()
2025-12-09 01:13:28,568 - DEBUG - No XMPP flaps were noticed during the test
2025-12-09 01:13:28,568 - INFO - END TEST : test_rp_interface : PASSED[0:00:25]
2025-12-09 01:13:28,569 - INFO - --------------------------------------------------------------------------------
2025-12-09 01:13:29,278 - INFO - Deleted project: ctest-TestRP-09478007, ID : 7d82e27e-0eba-40fc-b184-c0678b097b19