2025-10-22 15:12:32,180 - INFO - Domain Default found not creating
2025-10-22 15:12:32,496 - INFO - Project ctest-TestRP-30192770 not found, creating it
2025-10-22 15:12:33,266 - INFO - Created Project:ctest-TestRP-30192770, ID : 5963b30b-2da0-4179-b68f-2f799e25dca9 
2025-10-22 15:12:35,216 - DEBUG - Services list from nova: [, , ]
2025-10-22 15:12:35,216 - DEBUG - Hosts: {'nova': ['cn-jenkins-deploy-platform-ansible-os-4433-1']}
2025-10-22 15:12:35,219 - INFO - ================================================================================
2025-10-22 15:12:35,220 - INFO - STARTING TEST    : test_rp_interface
2025-10-22 15:12:35,220 - 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 15:12:35,508 - DEBUG - Nothing to compare xmpp stats {'10.0.0.141': {'10.20.0.17': '0'}} with
2025-10-22 15:12:35,508 - INFO - Initial checks done. Running the testcase now
2025-10-22 15:12:35,508 - INFO - 
2025-10-22 15:12:36,306 - DEBUG - Response for create_network : {'network': {'id': '891cc711-7ac6-417a-8f73-a326e3fe587c', 'name': 'ctest-bgpaas_vn-61933054', 'tenant_id': '5963b30b2da04179b68f2f799e25dca9', 'project_id': '5963b30b2da04179b68f2f799e25dca9', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRP-30192770', 'ctest-bgpaas_vn-61933054'], 'port_security_enabled': True, 'description': ''}}
2025-10-22 15:12:36,534 - DEBUG - Response for create_subnet : {'subnet': {'id': 'eead584a-b3b0-459f-9ea1-d3421060de02', 'name': '', 'tenant_id': '5963b30b2da04179b68f2f799e25dca9', 'network_id': '891cc711-7ac6-417a-8f73-a326e3fe587c', 'ip_version': 4, 'cidr': '99.90.113.192/26', 'allocation_pools': [{'start': '99.90.113.194', 'end': '99.90.113.254'}], 'gateway_ip': '99.90.113.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '99.90.113.194', 'tags': [], 'project_id': '5963b30b2da04179b68f2f799e25dca9'}}
2025-10-22 15:12:36,560 - INFO - Created VN ctest-bgpaas_vn-61933054
2025-10-22 15:12:36,618 - DEBUG - VN ctest-bgpaas_vn-61933054 UUID is 891cc711-7ac6-417a-8f73-a326e3fe587c
2025-10-22 15:12:37,200 - DEBUG - Response for create_network : {'network': {'id': '51618e13-79a1-448d-9021-9df1a45061ea', 'name': 'ctest-bgpaas_vn-01432107', 'tenant_id': '5963b30b2da04179b68f2f799e25dca9', 'project_id': '5963b30b2da04179b68f2f799e25dca9', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRP-30192770', 'ctest-bgpaas_vn-01432107'], 'port_security_enabled': True, 'description': ''}}
2025-10-22 15:12:37,422 - DEBUG - Response for create_subnet : {'subnet': {'id': '29ac4059-3cd6-4b5e-8678-dc7911e31888', 'name': '', 'tenant_id': '5963b30b2da04179b68f2f799e25dca9', 'network_id': '51618e13-79a1-448d-9021-9df1a45061ea', 'ip_version': 4, 'cidr': '52.52.99.128/26', 'allocation_pools': [{'start': '52.52.99.130', 'end': '52.52.99.190'}], 'gateway_ip': '52.52.99.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '52.52.99.130', 'tags': [], 'project_id': '5963b30b2da04179b68f2f799e25dca9'}}
2025-10-22 15:12:37,445 - INFO - Created VN ctest-bgpaas_vn-01432107
2025-10-22 15:12:37,501 - DEBUG - VN ctest-bgpaas_vn-01432107 UUID is 51618e13-79a1-448d-9021-9df1a45061ea
2025-10-22 15:12:39,167 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-4433-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-4433-1)
2025-10-22 15:12:40,513 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-4433-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-4433-1)
2025-10-22 15:12:40,514 - INFO - Waiting for VM test_vm to be up..
2025-10-22 15:12:40,643 - DEBUG - VM  is still in BUILD state, Expected: ACTIVE
2025-10-22 15:12:45,773 - DEBUG - VM  is in ACTIVE state now
2025-10-22 15:12:45,774 - INFO - VM name : test_vm
2025-10-22 15:12:45,878 - DEBUG - VM test_vm ID is 176e06c7-a414-4b8b-94e5-6d0ff2395b72
2025-10-22 15:12:45,903 - DEBUG - VM test_vm launched on Node cn-jenkins-deploy-platform-ansible-os-4433-1
2025-10-22 15:12:46,003 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine/176e06c7-a414-4b8b-94e5-6d0ff2395b72
2025-10-22 15:12:46,320 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine/176e06c7-a414-4b8b-94e5-6d0ff2395b72
2025-10-22 15:12:46,364 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine-interface/5a3f6eba-301a-4fa3-9720-78654b106686
2025-10-22 15:12:49,669 - 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 1009ms')
2025-10-22 15:12:49,670 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM test_vm failed!
2025-10-22 15:12:49,733 - DEBUG - Gateway for vn default-domain:ctest-TestRP-30192770:ctest-bgpaas_vn-61933054 is 99.90.113.193 and allocation pool is NOT set
2025-10-22 15:12:53,830 - 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=2 ttl=63 time=8.38 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 1 received, 50% packet loss, time 1027ms\r\nrtt min/avg/max/mdev = 8.378/8.378/8.378/0.000 ms')
2025-10-22 15:12:53,830 - INFO - Ping to Metadata IP 169.254.0.3 of VM test_vm passed
2025-10-22 15:12:54,017 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-22 15:12:54,017 - DEBUG - Waiting to SSH to VM test_vm, IP 99.90.113.195, Port 22
2025-10-22 15:12:54,187 - DEBUG - VM test_vm is ready for SSH connections
2025-10-22 15:12:54,187 - INFO - Waiting for VM test2_vm to be up..
2025-10-22 15:12:54,285 - DEBUG - VM  is in ACTIVE state now
2025-10-22 15:12:54,285 - INFO - VM name : test2_vm
2025-10-22 15:12:54,386 - DEBUG - VM test2_vm ID is f39d951c-a1a2-49fe-8f54-425a0cc62d1d
2025-10-22 15:12:54,386 - DEBUG - VM test2_vm launched on Node cn-jenkins-deploy-platform-ansible-os-4433-1
2025-10-22 15:12:54,485 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine/f39d951c-a1a2-49fe-8f54-425a0cc62d1d
2025-10-22 15:12:54,498 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine-interface/aa45f987-fb24-4fd9-b2fc-54a5d8f7230f
2025-10-22 15:12:55,770 - 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=3.86 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.802 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 = 0.802/2.333/3.864/1.531 ms')
2025-10-22 15:12:55,771 - INFO - Ping to Metadata IP 169.254.0.4 of VM test2_vm passed
2025-10-22 15:12:55,958 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-22 15:12:55,958 - DEBUG - Waiting to SSH to VM test2_vm, IP 52.52.99.131, Port 22
2025-10-22 15:12:56,157 - DEBUG - VM test2_vm is ready for SSH connections
2025-10-22 15:12:56,357 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-22 15:12:56,365 - DEBUG - Requesting: http://10.20.0.17:8083/Snh_ShowRouteReq?x=default-domain:ctest-TestRP-30192770:ctest-bgpaas_vn-61933054:ctest-bgpaas_vn-61933054.inet.0
2025-10-22 15:12:56,379 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-22 15:12:56,379 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1  52.52.99.131, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.141, gateway password: c0ntrail123
2025-10-22 15:12:56,379 - DEBUG - ping -s 56 -c 3 -W 1  52.52.99.131
2025-10-22 15:13:00,342 - DEBUG - PING 52.52.99.131 (52.52.99.131): 56 data bytes
64 bytes from 52.52.99.131: seq=0 ttl=63 time=5.041 ms
64 bytes from 52.52.99.131: seq=1 ttl=63 time=1.188 ms
64 bytes from 52.52.99.131: seq=2 ttl=63 time=1.692 ms
--- 52.52.99.131 ping statistics ---
3 packets transmitted, 3 packets received, 0% packet loss
round-trip min/avg/max = 1.188/2.640/5.041 ms
2025-10-22 15:13:00,342 - INFO - Ping to IP 52.52.99.131 from VM test_vm passed
2025-10-22 15:13:00,455 - INFO - Deleting VM test2_vm
2025-10-22 15:13:00,563 - INFO - Deleting VM test_vm
2025-10-22 15:13:00,699 - INFO - Deleting VN ctest-bgpaas_vn-01432107 
2025-10-22 15:13:00,754 - DEBUG - VN 51618e13-79a1-448d-9021-9df1a45061ea still in use: Unable to complete operation on network 51618e13-79a1-448d-9021-9df1a45061ea. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-ccefdb2e-ac6e-42ab-9d1b-275593b1dcad']
2025-10-22 15:13:00,754 - WARNING - Deleting VN ctest-bgpaas_vn-01432107 failed..Will retry
2025-10-22 15:13:02,932 - DEBUG - Response for deleting network ()
2025-10-22 15:13:02,932 - INFO - Deleting VN ctest-bgpaas_vn-61933054 
2025-10-22 15:13:03,048 - DEBUG - Response for deleting network ()
2025-10-22 15:13:03,344 - DEBUG - No XMPP flaps were noticed during the test
2025-10-22 15:13:03,344 - INFO - END TEST : test_rp_interface : PASSED[0:00:28]
2025-10-22 15:13:03,344 - INFO - --------------------------------------------------------------------------------
2025-10-22 15:13:04,170 - INFO - Deleted project: ctest-TestRP-30192770, ID : 5963b30b-2da0-4179-b68f-2f799e25dca9