2025-10-22 10:44:56,932 - INFO - Domain Default found not creating
2025-10-22 10:44:57,064 - INFO - Project ctest-TestRP-10918471 not found, creating it
2025-10-22 10:44:57,488 - INFO - Created Project:ctest-TestRP-10918471, ID : daba49d8-7fac-47a5-884b-580b51f6c183 
2025-10-22 10:44:58,843 - DEBUG - Services list from nova: [, , ]
2025-10-22 10:44:58,843 - DEBUG - Hosts: {'nova': ['cn-jenkins-deploy-platform-ansible-os-4420-1']}
2025-10-22 10:44:58,845 - INFO - ================================================================================
2025-10-22 10:44:58,845 - INFO - STARTING TEST    : test_rp_interface
2025-10-22 10:44:58,845 - 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-22 10:44:59,102 - DEBUG - Nothing to compare xmpp stats {'10.0.0.242': {'10.20.0.17': '0'}} with
2025-10-22 10:44:59,102 - INFO - Initial checks done. Running the testcase now
2025-10-22 10:44:59,102 - INFO - 
2025-10-22 10:44:59,622 - DEBUG - Response for create_network : {'network': {'id': '15c5c179-70d0-4b65-8430-15eedcf61c1f', 'name': 'ctest-bgpaas_vn-55808831', 'tenant_id': 'daba49d87fac47a5884b580b51f6c183', 'project_id': 'daba49d87fac47a5884b580b51f6c183', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRP-10918471', 'ctest-bgpaas_vn-55808831'], 'port_security_enabled': True, 'description': ''}}
2025-10-22 10:44:59,773 - DEBUG - Response for create_subnet : {'subnet': {'id': 'b59e7a19-055a-41d2-8d88-262deca18d61', 'name': '', 'tenant_id': 'daba49d87fac47a5884b580b51f6c183', 'network_id': '15c5c179-70d0-4b65-8430-15eedcf61c1f', 'ip_version': 4, 'cidr': '136.5.193.192/26', 'allocation_pools': [{'start': '136.5.193.194', 'end': '136.5.193.254'}], 'gateway_ip': '136.5.193.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '136.5.193.194', 'tags': [], 'project_id': 'daba49d87fac47a5884b580b51f6c183'}}
2025-10-22 10:44:59,790 - INFO - Created VN ctest-bgpaas_vn-55808831
2025-10-22 10:44:59,840 - DEBUG - VN ctest-bgpaas_vn-55808831 UUID is 15c5c179-70d0-4b65-8430-15eedcf61c1f
2025-10-22 10:45:00,321 - DEBUG - Response for create_network : {'network': {'id': 'a9fab8f1-9fa6-4e3b-95ef-bd534468e8f8', 'name': 'ctest-bgpaas_vn-11904116', 'tenant_id': 'daba49d87fac47a5884b580b51f6c183', 'project_id': 'daba49d87fac47a5884b580b51f6c183', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRP-10918471', 'ctest-bgpaas_vn-11904116'], 'port_security_enabled': True, 'description': ''}}
2025-10-22 10:45:00,496 - DEBUG - Response for create_subnet : {'subnet': {'id': '21dfe9ca-1427-4b9b-9eb9-748749fb2659', 'name': '', 'tenant_id': 'daba49d87fac47a5884b580b51f6c183', 'network_id': 'a9fab8f1-9fa6-4e3b-95ef-bd534468e8f8', 'ip_version': 4, 'cidr': '198.47.75.64/26', 'allocation_pools': [{'start': '198.47.75.66', 'end': '198.47.75.126'}], 'gateway_ip': '198.47.75.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '198.47.75.66', 'tags': [], 'project_id': 'daba49d87fac47a5884b580b51f6c183'}}
2025-10-22 10:45:00,511 - INFO - Created VN ctest-bgpaas_vn-11904116
2025-10-22 10:45:00,564 - DEBUG - VN ctest-bgpaas_vn-11904116 UUID is a9fab8f1-9fa6-4e3b-95ef-bd534468e8f8
2025-10-22 10:45:01,863 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-4420-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-4420-1)
2025-10-22 10:45:02,963 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-4420-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-4420-1)
2025-10-22 10:45:02,963 - INFO - Waiting for VM test_vm to be up..
2025-10-22 10:45:03,047 - DEBUG - VM  is still in BUILD state, Expected: ACTIVE
2025-10-22 10:45:08,114 - DEBUG - VM  is in ACTIVE state now
2025-10-22 10:45:08,114 - INFO - VM name : test_vm
2025-10-22 10:45:08,176 - DEBUG - VM test_vm ID is 0304e33c-a775-4ecb-892b-0d7f4aeae0b9
2025-10-22 10:45:08,193 - DEBUG - VM test_vm launched on Node cn-jenkins-deploy-platform-ansible-os-4420-1
2025-10-22 10:45:08,264 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-machine/0304e33c-a775-4ecb-892b-0d7f4aeae0b9
2025-10-22 10:45:08,552 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-machine/0304e33c-a775-4ecb-892b-0d7f4aeae0b9
2025-10-22 10:45:08,581 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-machine-interface/b909a4b1-8c71-4e70-8cf0-9080035ec33d
2025-10-22 10:45:11,821 - 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-10-22 10:45:11,821 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM test_vm failed!
2025-10-22 10:45:11,876 - DEBUG - Gateway for vn default-domain:ctest-TestRP-10918471:ctest-bgpaas_vn-55808831 is 136.5.193.193 and allocation pool is NOT set
2025-10-22 10:45:13,932 - 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=2.72 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.411 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 = 0.411/1.565/2.719/1.154 ms')
2025-10-22 10:45:13,932 - INFO - Ping to Metadata IP 169.254.0.3 of VM test_vm passed
2025-10-22 10:45:14,080 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-22 10:45:14,080 - DEBUG - Waiting to SSH to VM test_vm, IP 136.5.193.195, Port 22
2025-10-22 10:45:14,235 - DEBUG - VM test_vm is ready for SSH connections
2025-10-22 10:45:14,236 - INFO - Waiting for VM test2_vm to be up..
2025-10-22 10:45:14,309 - DEBUG - VM  is in ACTIVE state now
2025-10-22 10:45:14,310 - INFO - VM name : test2_vm
2025-10-22 10:45:14,380 - DEBUG - VM test2_vm ID is bb694340-75d9-42f5-bf7a-4a95249bb47e
2025-10-22 10:45:14,380 - DEBUG - VM test2_vm launched on Node cn-jenkins-deploy-platform-ansible-os-4420-1
2025-10-22 10:45:14,448 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-machine/bb694340-75d9-42f5-bf7a-4a95249bb47e
2025-10-22 10:45:14,455 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-machine-interface/a6b7b71d-7068-4800-81e4-e65ac2071a28
2025-10-22 10:45:15,689 - 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=4.53 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=1.29 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.294/2.911/4.528/1.617 ms')
2025-10-22 10:45:15,689 - INFO - Ping to Metadata IP 169.254.0.4 of VM test2_vm passed
2025-10-22 10:45:15,836 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-22 10:45:15,836 - DEBUG - Waiting to SSH to VM test2_vm, IP 198.47.75.67, Port 22
2025-10-22 10:45:15,991 - DEBUG - VM test2_vm is ready for SSH connections
2025-10-22 10:45:16,132 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-22 10:45:16,136 - DEBUG - Requesting: http://10.20.0.17:8083/Snh_ShowRouteReq?x=default-domain:ctest-TestRP-10918471:ctest-bgpaas_vn-55808831:ctest-bgpaas_vn-55808831.inet.0
2025-10-22 10:45:16,145 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-22 10:45:16,145 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1  198.47.75.67, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.242, gateway password: c0ntrail123
2025-10-22 10:45:16,145 - DEBUG - ping -s 56 -c 3 -W 1  198.47.75.67
2025-10-22 10:45:19,778 - DEBUG - PING 198.47.75.67 (198.47.75.67): 56 data bytes
64 bytes from 198.47.75.67: seq=0 ttl=63 time=3.749 ms
64 bytes from 198.47.75.67: seq=1 ttl=63 time=1.040 ms
64 bytes from 198.47.75.67: seq=2 ttl=63 time=0.740 ms
--- 198.47.75.67 ping statistics ---
3 packets transmitted, 3 packets received, 0% packet loss
round-trip min/avg/max = 0.740/1.843/3.749 ms
2025-10-22 10:45:19,779 - INFO - Ping to IP 198.47.75.67 from VM test_vm passed
2025-10-22 10:45:19,835 - INFO - Deleting VM test2_vm
2025-10-22 10:45:19,893 - INFO - Deleting VM test_vm
2025-10-22 10:45:19,954 - INFO - Deleting VN ctest-bgpaas_vn-11904116 
2025-10-22 10:45:19,987 - DEBUG - VN a9fab8f1-9fa6-4e3b-95ef-bd534468e8f8 still in use: Unable to complete operation on network a9fab8f1-9fa6-4e3b-95ef-bd534468e8f8. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-36576089-29e6-44bc-bd92-e9586352196e']
2025-10-22 10:45:19,987 - WARNING - Deleting VN ctest-bgpaas_vn-11904116 failed..Will retry
2025-10-22 10:45:22,078 - DEBUG - Response for deleting network ()
2025-10-22 10:45:22,078 - INFO - Deleting VN ctest-bgpaas_vn-55808831 
2025-10-22 10:45:22,165 - DEBUG - Response for deleting network ()
2025-10-22 10:45:22,420 - DEBUG - No XMPP flaps were noticed during the test
2025-10-22 10:45:22,420 - INFO - END TEST : test_rp_interface : PASSED[0:00:24]
2025-10-22 10:45:22,420 - INFO - --------------------------------------------------------------------------------
2025-10-22 10:45:23,057 - INFO - Deleted project: ctest-TestRP-10918471, ID : daba49d8-7fac-47a5-884b-580b51f6c183