2026-01-08 02:46:08,062 - INFO - Domain Default found not creating
2026-01-08 02:46:08,255 - INFO - Project ctest-TestRP-24057549 not found, creating it
2026-01-08 02:46:08,858 - INFO - Created Project:ctest-TestRP-24057549, ID : e3fa798d-9e5a-4c3e-8d53-53ea44aaadc2
2026-01-08 02:46:10,322 - DEBUG - Services list from nova: [, , , ]
2026-01-08 02:46:10,322 - DEBUG - Hosts: {'nova': ['an-jenkins-deploy-platform-ansible-os-4976-2', 'an-jenkins-deploy-platform-ansible-os-4976-1']}
2026-01-08 02:46:10,324 - INFO - ================================================================================
2026-01-08 02:46:10,324 - INFO - STARTING TEST : test_rp_interface
2026-01-08 02:46:10,324 - 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.
2026-01-08 02:46:11,620 - DEBUG - Nothing to compare xmpp stats {'10.0.0.57': {'10.20.0.25': '0', '10.20.0.17': '0'}, '10.0.0.56': {'10.20.0.25': '0', '10.20.0.193': '0'}} with
2026-01-08 02:46:11,620 - INFO - Initial checks done. Running the testcase now
2026-01-08 02:46:11,620 - INFO -
2026-01-08 02:46:12,341 - DEBUG - Response for create_network : {'network': {'id': '384757cd-b951-4853-9461-79264a17fec5', 'name': 'ctest-bgpaas_vn-15057566', 'tenant_id': 'e3fa798d9e5a4c3e8d5353ea44aaadc2', 'project_id': 'e3fa798d9e5a4c3e8d5353ea44aaadc2', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRP-24057549', 'ctest-bgpaas_vn-15057566'], 'port_security_enabled': True, 'description': ''}}
2026-01-08 02:46:12,653 - DEBUG - Response for create_subnet : {'subnet': {'id': '85543df5-4b3b-4dd9-9024-66eae1026e85', 'name': '', 'tenant_id': 'e3fa798d9e5a4c3e8d5353ea44aaadc2', 'network_id': '384757cd-b951-4853-9461-79264a17fec5', 'ip_version': 4, 'cidr': '30.44.227.192/26', 'allocation_pools': [{'start': '30.44.227.194', 'end': '30.44.227.254'}], 'gateway_ip': '30.44.227.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '30.44.227.194', 'tags': [], 'project_id': 'e3fa798d9e5a4c3e8d5353ea44aaadc2'}}
2026-01-08 02:46:12,677 - INFO - Created VN ctest-bgpaas_vn-15057566
2026-01-08 02:46:12,690 - DEBUG - VN ctest-bgpaas_vn-15057566 UUID is 384757cd-b951-4853-9461-79264a17fec5
2026-01-08 02:46:13,177 - DEBUG - Response for create_network : {'network': {'id': '4ba8cd5c-7ec7-4b5f-824d-104a1b176369', 'name': 'ctest-bgpaas_vn-27405679', 'tenant_id': 'e3fa798d9e5a4c3e8d5353ea44aaadc2', 'project_id': 'e3fa798d9e5a4c3e8d5353ea44aaadc2', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRP-24057549', 'ctest-bgpaas_vn-27405679'], 'port_security_enabled': True, 'description': ''}}
2026-01-08 02:46:13,465 - DEBUG - Response for create_subnet : {'subnet': {'id': '51c913c0-b669-49a4-b6ec-22c264a0e80f', 'name': '', 'tenant_id': 'e3fa798d9e5a4c3e8d5353ea44aaadc2', 'network_id': '4ba8cd5c-7ec7-4b5f-824d-104a1b176369', 'ip_version': 4, 'cidr': '190.80.199.0/26', 'allocation_pools': [{'start': '190.80.199.2', 'end': '190.80.199.62'}], 'gateway_ip': '190.80.199.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '190.80.199.2', 'tags': [], 'project_id': 'e3fa798d9e5a4c3e8d5353ea44aaadc2'}}
2026-01-08 02:46:13,489 - INFO - Created VN ctest-bgpaas_vn-27405679
2026-01-08 02:46:13,501 - DEBUG - VN ctest-bgpaas_vn-27405679 UUID is 4ba8cd5c-7ec7-4b5f-824d-104a1b176369
2026-01-08 02:46:14,881 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4976-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4976-2)
2026-01-08 02:46:16,796 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4976-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4976-1)
2026-01-08 02:46:16,796 - INFO - Waiting for VM test_vm to be up..
2026-01-08 02:46:16,886 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2026-01-08 02:46:21,972 - DEBUG - VM is in ACTIVE state now
2026-01-08 02:46:21,972 - INFO - VM name : test_vm
2026-01-08 02:46:22,060 - DEBUG - VM test_vm ID is d0fa6433-d24f-45e8-89a9-d9391253903d
2026-01-08 02:46:22,089 - DEBUG - VM test_vm launched on Node an-jenkins-deploy-platform-ansible-os-4976-2
2026-01-08 02:46:22,174 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/d0fa6433-d24f-45e8-89a9-d9391253903d
2026-01-08 02:46:22,476 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/d0fa6433-d24f-45e8-89a9-d9391253903d
2026-01-08 02:46:22,518 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/69705869-8d9e-4755-87c9-fd5abf831091
2026-01-08 02:46:25,703 - 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 1025ms')
2026-01-08 02:46:25,704 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM test_vm failed!
2026-01-08 02:46:25,721 - DEBUG - Gateway for vn default-domain:ctest-TestRP-24057549:ctest-bgpaas_vn-15057566 is 30.44.227.193 and allocation pool is NOT set
2026-01-08 02:46:27,794 - 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=5.00 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=2.48 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.479/3.739/5.000/1.260 ms')
2026-01-08 02:46:27,794 - INFO - Ping to Metadata IP 169.254.0.3 of VM test_vm passed
2026-01-08 02:46:27,863 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-01-08 02:46:27,864 - DEBUG - Waiting to SSH to VM test_vm, IP 30.44.227.195, Port 22
2026-01-08 02:46:28,035 - DEBUG - VM test_vm is ready for SSH connections
2026-01-08 02:46:28,035 - INFO - Waiting for VM test2_vm to be up..
2026-01-08 02:46:28,123 - DEBUG - VM is in ACTIVE state now
2026-01-08 02:46:28,123 - INFO - VM name : test2_vm
2026-01-08 02:46:28,210 - DEBUG - VM test2_vm ID is 7bc31ba3-163e-438f-a8be-049b8c40eec4
2026-01-08 02:46:28,210 - DEBUG - VM test2_vm launched on Node an-jenkins-deploy-platform-ansible-os-4976-1
2026-01-08 02:46:28,303 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/7bc31ba3-163e-438f-a8be-049b8c40eec4
2026-01-08 02:46:28,313 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/14f25a6c-2ea1-4291-8ab7-89aba6e5bbd7
2026-01-08 02:46:29,486 - 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=5.25 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.970 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.970/3.108/5.246/2.138 ms')
2026-01-08 02:46:29,486 - INFO - Ping to Metadata IP 169.254.0.3 of VM test2_vm passed
2026-01-08 02:46:29,562 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-01-08 02:46:29,563 - DEBUG - Waiting to SSH to VM test2_vm, IP 190.80.199.3, Port 22
2026-01-08 02:46:29,735 - DEBUG - VM test2_vm is ready for SSH connections
2026-01-08 02:46:29,899 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-01-08 02:46:29,914 - DEBUG - Requesting: http://10.20.0.25:8083/Snh_ShowRouteReq?x=default-domain:ctest-TestRP-24057549:ctest-bgpaas_vn-15057566:ctest-bgpaas_vn-15057566.inet.0
2026-01-08 02:46:29,934 - DEBUG - Requesting: http://10.20.0.193:8083/Snh_ShowRouteReq?x=default-domain:ctest-TestRP-24057549:ctest-bgpaas_vn-15057566:ctest-bgpaas_vn-15057566.inet.0
2026-01-08 02:46:29,956 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-01-08 02:46:29,957 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 190.80.199.3, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.56, gateway password: c0ntrail123
2026-01-08 02:46:29,957 - DEBUG - ping -s 56 -c 3 -W 1 190.80.199.3
2026-01-08 02:46:33,967 - DEBUG - PING 190.80.199.3 (190.80.199.3): 56 data bytes
64 bytes from 190.80.199.3: seq=0 ttl=63 time=6.551 ms
64 bytes from 190.80.199.3: seq=1 ttl=63 time=1.401 ms
64 bytes from 190.80.199.3: seq=2 ttl=63 time=1.343 ms
--- 190.80.199.3 ping statistics ---
3 packets transmitted, 3 packets received, 0% packet loss
round-trip min/avg/max = 1.343/3.098/6.551 ms
2026-01-08 02:46:33,967 - INFO - Ping to IP 190.80.199.3 from VM test_vm passed
2026-01-08 02:46:34,059 - INFO - Deleting VM test2_vm
2026-01-08 02:46:34,133 - INFO - Deleting VM test_vm
2026-01-08 02:46:34,208 - INFO - Deleting VN ctest-bgpaas_vn-27405679
2026-01-08 02:46:34,259 - DEBUG - VN 4ba8cd5c-7ec7-4b5f-824d-104a1b176369 still in use: Unable to complete operation on network 4ba8cd5c-7ec7-4b5f-824d-104a1b176369. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-5ec1c48a-c13c-4788-a361-85ac0045d93a']
2026-01-08 02:46:34,260 - WARNING - Deleting VN ctest-bgpaas_vn-27405679 failed..Will retry
2026-01-08 02:46:36,416 - DEBUG - Response for deleting network ()
2026-01-08 02:46:36,416 - INFO - Deleting VN ctest-bgpaas_vn-15057566
2026-01-08 02:46:36,620 - DEBUG - Response for deleting network ()
2026-01-08 02:46:37,909 - DEBUG - No XMPP flaps were noticed during the test
2026-01-08 02:46:37,910 - INFO - END TEST : test_rp_interface : PASSED[0:00:27]
2026-01-08 02:46:37,910 - INFO - --------------------------------------------------------------------------------
2026-01-08 02:46:37,913 - INFO - ================================================================================
2026-01-08 02:46:37,913 - INFO - STARTING TEST : test_rp_interface_ext_community
2026-01-08 02:46:37,913 - 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.
2026-01-08 02:46:39,214 - DEBUG - Nothing to compare xmpp stats {'10.0.0.57': {'10.20.0.25': '0', '10.20.0.17': '0'}, '10.0.0.56': {'10.20.0.25': '0', '10.20.0.193': '0'}} with
2026-01-08 02:46:39,214 - INFO - Initial checks done. Running the testcase now
2026-01-08 02:46:39,214 - INFO -
2026-01-08 02:46:39,463 - DEBUG - Response for create_network : {'network': {'id': 'c2d68278-79ef-44ec-98ab-5864f28c380b', 'name': 'ctest-bgpaas_vn-92172468', 'tenant_id': 'e3fa798d9e5a4c3e8d5353ea44aaadc2', 'project_id': 'e3fa798d9e5a4c3e8d5353ea44aaadc2', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRP-24057549', 'ctest-bgpaas_vn-92172468'], 'port_security_enabled': True, 'description': ''}}
2026-01-08 02:46:39,717 - DEBUG - Response for create_subnet : {'subnet': {'id': '00589e4a-062f-4f88-b9fe-a7292bcf6474', 'name': '', 'tenant_id': 'e3fa798d9e5a4c3e8d5353ea44aaadc2', 'network_id': 'c2d68278-79ef-44ec-98ab-5864f28c380b', 'ip_version': 4, 'cidr': '144.233.182.0/26', 'allocation_pools': [{'start': '144.233.182.2', 'end': '144.233.182.62'}], 'gateway_ip': '144.233.182.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '144.233.182.2', 'tags': [], 'project_id': 'e3fa798d9e5a4c3e8d5353ea44aaadc2'}}
2026-01-08 02:46:39,742 - INFO - Created VN ctest-bgpaas_vn-92172468
2026-01-08 02:46:39,756 - DEBUG - VN ctest-bgpaas_vn-92172468 UUID is c2d68278-79ef-44ec-98ab-5864f28c380b
2026-01-08 02:46:40,272 - DEBUG - Response for create_network : {'network': {'id': 'ff608bc3-986b-462e-955c-bd77030bd651', 'name': 'ctest-bgpaas_vn-33727657', 'tenant_id': 'e3fa798d9e5a4c3e8d5353ea44aaadc2', 'project_id': 'e3fa798d9e5a4c3e8d5353ea44aaadc2', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRP-24057549', 'ctest-bgpaas_vn-33727657'], 'port_security_enabled': True, 'description': ''}}
2026-01-08 02:46:40,526 - DEBUG - Response for create_subnet : {'subnet': {'id': 'e45ad93c-d32c-477e-8045-afe9f90d546d', 'name': '', 'tenant_id': 'e3fa798d9e5a4c3e8d5353ea44aaadc2', 'network_id': 'ff608bc3-986b-462e-955c-bd77030bd651', 'ip_version': 4, 'cidr': '176.154.174.64/26', 'allocation_pools': [{'start': '176.154.174.66', 'end': '176.154.174.126'}], 'gateway_ip': '176.154.174.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '176.154.174.66', 'tags': [], 'project_id': 'e3fa798d9e5a4c3e8d5353ea44aaadc2'}}
2026-01-08 02:46:40,546 - INFO - Created VN ctest-bgpaas_vn-33727657
2026-01-08 02:46:40,557 - DEBUG - VN ctest-bgpaas_vn-33727657 UUID is ff608bc3-986b-462e-955c-bd77030bd651
2026-01-08 02:46:41,915 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4976-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4976-2)
2026-01-08 02:46:43,185 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4976-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4976-1)
2026-01-08 02:46:43,185 - INFO - Waiting for VM test_vm to be up..
2026-01-08 02:46:43,281 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2026-01-08 02:46:48,371 - DEBUG - VM is in ACTIVE state now
2026-01-08 02:46:48,371 - INFO - VM name : test_vm
2026-01-08 02:46:48,462 - DEBUG - VM test_vm ID is 0cbde331-603e-4503-8a21-68cc43326bd7
2026-01-08 02:46:48,462 - DEBUG - VM test_vm launched on Node an-jenkins-deploy-platform-ansible-os-4976-2
2026-01-08 02:46:48,546 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/0cbde331-603e-4503-8a21-68cc43326bd7
2026-01-08 02:46:48,558 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/c0b3b39a-3dba-4ae2-88e2-35c5ded86afb
2026-01-08 02:46:51,716 - 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 1005ms')
2026-01-08 02:46:51,716 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM test_vm failed!
2026-01-08 02:46:51,731 - DEBUG - Gateway for vn default-domain:ctest-TestRP-24057549:ctest-bgpaas_vn-92172468 is 144.233.182.1 and allocation pool is NOT set
2026-01-08 02:46:53,791 - 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.07 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.261 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 = 0.261/2.166/4.071/1.905 ms')
2026-01-08 02:46:53,791 - INFO - Ping to Metadata IP 169.254.0.3 of VM test_vm passed
2026-01-08 02:46:53,899 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-01-08 02:46:53,899 - DEBUG - Waiting to SSH to VM test_vm, IP 144.233.182.3, Port 22
2026-01-08 02:46:54,067 - DEBUG - VM test_vm is ready for SSH connections
2026-01-08 02:46:54,067 - INFO - Waiting for VM test2_vm to be up..
2026-01-08 02:46:54,152 - DEBUG - VM is in ACTIVE state now
2026-01-08 02:46:54,152 - INFO - VM name : test2_vm
2026-01-08 02:46:54,240 - DEBUG - VM test2_vm ID is c9e79a53-668d-4f82-9daf-38199741a744
2026-01-08 02:46:54,240 - DEBUG - VM test2_vm launched on Node an-jenkins-deploy-platform-ansible-os-4976-1
2026-01-08 02:46:54,331 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/c9e79a53-668d-4f82-9daf-38199741a744
2026-01-08 02:46:54,342 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/78d27736-5992-4366-b8b2-2b827cb0e5e5
2026-01-08 02:46:55,509 - 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.10 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=4.84 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 = 4.098/4.471/4.844/0.373 ms')
2026-01-08 02:46:55,509 - INFO - Ping to Metadata IP 169.254.0.3 of VM test2_vm passed
2026-01-08 02:46:55,581 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-01-08 02:46:55,581 - DEBUG - Waiting to SSH to VM test2_vm, IP 176.154.174.67, Port 22
2026-01-08 02:46:55,740 - DEBUG - VM test2_vm is ready for SSH connections
2026-01-08 02:46:55,915 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-01-08 02:46:55,928 - DEBUG - Requesting: http://10.20.0.25:8083/Snh_ShowRouteReq?x=default-domain:ctest-TestRP-24057549:ctest-bgpaas_vn-92172468:ctest-bgpaas_vn-92172468.inet.0
2026-01-08 02:46:55,937 - DEBUG - Requesting: http://10.20.0.193:8083/Snh_ShowRouteReq?x=default-domain:ctest-TestRP-24057549:ctest-bgpaas_vn-92172468:ctest-bgpaas_vn-92172468.inet.0
2026-01-08 02:46:55,952 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-01-08 02:46:55,952 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 176.154.174.67, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.56, gateway password: c0ntrail123
2026-01-08 02:46:55,952 - DEBUG - ping -s 56 -c 3 -W 1 176.154.174.67
2026-01-08 02:46:59,669 - DEBUG - PING 176.154.174.67 (176.154.174.67): 56 data bytes
64 bytes from 176.154.174.67: seq=0 ttl=63 time=6.547 ms
64 bytes from 176.154.174.67: seq=1 ttl=63 time=1.614 ms
64 bytes from 176.154.174.67: seq=2 ttl=63 time=1.701 ms
--- 176.154.174.67 ping statistics ---
3 packets transmitted, 3 packets received, 0% packet loss
round-trip min/avg/max = 1.614/3.287/6.547 ms
2026-01-08 02:46:59,670 - INFO - Ping to IP 176.154.174.67 from VM test_vm passed
2026-01-08 02:46:59,819 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-01-08 02:46:59,834 - DEBUG - Requesting: http://10.20.0.25:8083/Snh_ShowRouteReq?x=default-domain:ctest-TestRP-24057549:ctest-bgpaas_vn-92172468:ctest-bgpaas_vn-92172468.inet.0
2026-01-08 02:46:59,849 - DEBUG - Requesting: http://10.20.0.193:8083/Snh_ShowRouteReq?x=default-domain:ctest-TestRP-24057549:ctest-bgpaas_vn-92172468:ctest-bgpaas_vn-92172468.inet.0
2026-01-08 02:47:00,112 - INFO - Deleting VM test2_vm
2026-01-08 02:47:00,219 - INFO - Deleting VM test_vm
2026-01-08 02:47:00,320 - INFO - Deleting VN ctest-bgpaas_vn-33727657
2026-01-08 02:47:00,365 - DEBUG - VN ff608bc3-986b-462e-955c-bd77030bd651 still in use: Unable to complete operation on network ff608bc3-986b-462e-955c-bd77030bd651. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-10ac3553-f1c4-464d-891c-0ba9e90627bc']
2026-01-08 02:47:00,365 - WARNING - Deleting VN ctest-bgpaas_vn-33727657 failed..Will retry
2026-01-08 02:47:02,515 - DEBUG - Response for deleting network ()
2026-01-08 02:47:02,516 - INFO - Deleting VN ctest-bgpaas_vn-92172468
2026-01-08 02:47:02,701 - DEBUG - Response for deleting network ()
2026-01-08 02:47:03,984 - DEBUG - No XMPP flaps were noticed during the test
2026-01-08 02:47:03,984 - INFO - --------------------------------------------------------------------------------
2026-01-08 02:47:03,987 - INFO - ================================================================================
2026-01-08 02:47:03,987 - INFO - STARTING TEST : test_rp_interface_static
2026-01-08 02:47:03,987 - INFO - TEST DESCRIPTION :
1. Create a routing policy with interface-static match.
2. Launch VMs.
3. Attach policy to VN and confirm if policy takes hold.
2026-01-08 02:47:05,291 - DEBUG - Nothing to compare xmpp stats {'10.0.0.57': {'10.20.0.25': '0', '10.20.0.17': '0'}, '10.0.0.56': {'10.20.0.25': '0', '10.20.0.193': '0'}} with
2026-01-08 02:47:05,291 - INFO - Initial checks done. Running the testcase now
2026-01-08 02:47:05,291 - INFO -
2026-01-08 02:47:05,568 - DEBUG - Response for create_network : {'network': {'id': '8e144e00-c6ee-4c5a-8e7e-cf4629888c0c', 'name': 'ctest-bgpaas_vn-93962276', 'tenant_id': 'e3fa798d9e5a4c3e8d5353ea44aaadc2', 'project_id': 'e3fa798d9e5a4c3e8d5353ea44aaadc2', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRP-24057549', 'ctest-bgpaas_vn-93962276'], 'port_security_enabled': True, 'description': ''}}
2026-01-08 02:47:05,824 - DEBUG - Response for create_subnet : {'subnet': {'id': 'a29dc6d4-6187-4236-9257-67a7553ba0e5', 'name': '', 'tenant_id': 'e3fa798d9e5a4c3e8d5353ea44aaadc2', 'network_id': '8e144e00-c6ee-4c5a-8e7e-cf4629888c0c', 'ip_version': 4, 'cidr': '146.28.98.0/26', 'allocation_pools': [{'start': '146.28.98.2', 'end': '146.28.98.62'}], 'gateway_ip': '146.28.98.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '146.28.98.2', 'tags': [], 'project_id': 'e3fa798d9e5a4c3e8d5353ea44aaadc2'}}
2026-01-08 02:47:05,855 - INFO - Created VN ctest-bgpaas_vn-93962276
2026-01-08 02:47:05,870 - DEBUG - VN ctest-bgpaas_vn-93962276 UUID is 8e144e00-c6ee-4c5a-8e7e-cf4629888c0c
2026-01-08 02:47:06,422 - DEBUG - Response for create_network : {'network': {'id': '83219ddb-fcc7-48d9-bad6-2d5c7c09c5bf', 'name': 'ctest-bgpaas_vn-08227494', 'tenant_id': 'e3fa798d9e5a4c3e8d5353ea44aaadc2', 'project_id': 'e3fa798d9e5a4c3e8d5353ea44aaadc2', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRP-24057549', 'ctest-bgpaas_vn-08227494'], 'port_security_enabled': True, 'description': ''}}
2026-01-08 02:47:06,705 - DEBUG - Response for create_subnet : {'subnet': {'id': 'c3d5af98-e00f-4eb4-83d0-a9cfc656c32c', 'name': '', 'tenant_id': 'e3fa798d9e5a4c3e8d5353ea44aaadc2', 'network_id': '83219ddb-fcc7-48d9-bad6-2d5c7c09c5bf', 'ip_version': 4, 'cidr': '61.137.67.0/26', 'allocation_pools': [{'start': '61.137.67.2', 'end': '61.137.67.62'}], 'gateway_ip': '61.137.67.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '61.137.67.2', 'tags': [], 'project_id': 'e3fa798d9e5a4c3e8d5353ea44aaadc2'}}
2026-01-08 02:47:06,734 - INFO - Created VN ctest-bgpaas_vn-08227494
2026-01-08 02:47:06,751 - DEBUG - VN ctest-bgpaas_vn-08227494 UUID is 83219ddb-fcc7-48d9-bad6-2d5c7c09c5bf
2026-01-08 02:47:08,168 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4976-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4976-2)
2026-01-08 02:47:09,641 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4976-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4976-1)
2026-01-08 02:47:09,643 - INFO - Waiting for VM test_vm to be up..
2026-01-08 02:47:09,743 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2026-01-08 02:47:14,836 - DEBUG - VM is in ACTIVE state now
2026-01-08 02:47:14,836 - INFO - VM name : test_vm
2026-01-08 02:47:14,917 - DEBUG - VM test_vm ID is 9c8777f0-d2f3-49c6-8afb-57e2619ff54f
2026-01-08 02:47:14,917 - DEBUG - VM test_vm launched on Node an-jenkins-deploy-platform-ansible-os-4976-2
2026-01-08 02:47:15,020 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/9c8777f0-d2f3-49c6-8afb-57e2619ff54f
2026-01-08 02:47:15,030 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/c450c17b-8f7c-4e1b-8764-c09967cf531d
2026-01-08 02:47:18,210 - 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 1023ms')
2026-01-08 02:47:18,210 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM test_vm failed!
2026-01-08 02:47:18,224 - DEBUG - Gateway for vn default-domain:ctest-TestRP-24057549:ctest-bgpaas_vn-93962276 is 146.28.98.1 and allocation pool is NOT set
2026-01-08 02:47:20,287 - 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=8.72 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.406 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.406/4.561/8.716/4.155 ms')
2026-01-08 02:47:20,287 - INFO - Ping to Metadata IP 169.254.0.3 of VM test_vm passed
2026-01-08 02:47:20,356 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-01-08 02:47:20,356 - DEBUG - Waiting to SSH to VM test_vm, IP 146.28.98.3, Port 22
2026-01-08 02:47:20,516 - DEBUG - VM test_vm is ready for SSH connections
2026-01-08 02:47:20,517 - INFO - Waiting for VM test2_vm to be up..
2026-01-08 02:47:20,612 - DEBUG - VM is in ACTIVE state now
2026-01-08 02:47:20,613 - INFO - VM name : test2_vm
2026-01-08 02:47:20,702 - DEBUG - VM test2_vm ID is 77e5b002-88ac-4601-b700-ece9e217e5b8
2026-01-08 02:47:20,702 - DEBUG - VM test2_vm launched on Node an-jenkins-deploy-platform-ansible-os-4976-1
2026-01-08 02:47:20,789 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/77e5b002-88ac-4601-b700-ece9e217e5b8
2026-01-08 02:47:20,799 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/ccda0fda-d302-474b-b26b-0c581aa39713
2026-01-08 02:47:21,968 - 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=3.80 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=3.02 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 = 3.020/3.412/3.804/0.392 ms')
2026-01-08 02:47:21,968 - INFO - Ping to Metadata IP 169.254.0.3 of VM test2_vm passed
2026-01-08 02:47:22,035 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-01-08 02:47:22,035 - DEBUG - Waiting to SSH to VM test2_vm, IP 61.137.67.3, Port 22
2026-01-08 02:47:22,203 - DEBUG - VM test2_vm is ready for SSH connections
2026-01-08 02:47:22,303 - INFO - Created InterfaceRouteTable ['default-domain', 'ctest-TestRP-24057549', 'ctest-int_table_right-85581712'](UUID 7c78ec93-5b34-4edb-97a6-424efd6ce638), prefixes : ['110.117.204.64/26']
2026-01-08 02:47:22,371 - INFO - Added intf route table 7c78ec93-5b34-4edb-97a6-424efd6ce638 to port c450c17b-8f7c-4e1b-8764-c09967cf531d
2026-01-08 02:47:22,580 - DEBUG - Requesting: http://10.20.0.25:8083/Snh_ShowRouteReq?x=default-domain:ctest-TestRP-24057549:ctest-bgpaas_vn-93962276:ctest-bgpaas_vn-93962276.inet.0
2026-01-08 02:47:22,595 - DEBUG - Requesting: http://10.20.0.193:8083/Snh_ShowRouteReq?x=default-domain:ctest-TestRP-24057549:ctest-bgpaas_vn-93962276:ctest-bgpaas_vn-93962276.inet.0
2026-01-08 02:47:22,614 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-01-08 02:47:22,614 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 61.137.67.3, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.56, gateway password: c0ntrail123
2026-01-08 02:47:22,614 - DEBUG - ping -s 56 -c 3 -W 1 61.137.67.3
2026-01-08 02:47:26,297 - DEBUG - PING 61.137.67.3 (61.137.67.3): 56 data bytes
64 bytes from 61.137.67.3: seq=0 ttl=63 time=6.543 ms
64 bytes from 61.137.67.3: seq=1 ttl=63 time=1.747 ms
64 bytes from 61.137.67.3: seq=2 ttl=63 time=1.349 ms
--- 61.137.67.3 ping statistics ---
3 packets transmitted, 3 packets received, 0% packet loss
round-trip min/avg/max = 1.349/3.213/6.543 ms
2026-01-08 02:47:26,298 - INFO - Ping to IP 61.137.67.3 from VM test_vm passed
2026-01-08 02:47:26,405 - INFO - Deleting VM test2_vm
2026-01-08 02:47:26,637 - INFO - Deleting VM test_vm
2026-01-08 02:47:26,708 - INFO - Deleting VN ctest-bgpaas_vn-08227494
2026-01-08 02:47:26,763 - DEBUG - VN 83219ddb-fcc7-48d9-bad6-2d5c7c09c5bf still in use: Unable to complete operation on network 83219ddb-fcc7-48d9-bad6-2d5c7c09c5bf. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-a9e112ea-a709-48d2-a944-ef4713d3b4fd']
2026-01-08 02:47:26,763 - WARNING - Deleting VN ctest-bgpaas_vn-08227494 failed..Will retry
2026-01-08 02:47:28,976 - DEBUG - Response for deleting network ()
2026-01-08 02:47:28,976 - INFO - Deleting VN ctest-bgpaas_vn-93962276
2026-01-08 02:47:29,164 - DEBUG - Response for deleting network ()
2026-01-08 02:47:30,448 - DEBUG - No XMPP flaps were noticed during the test
2026-01-08 02:47:30,448 - INFO - END TEST : test_rp_interface_static : PASSED[0:00:27]
2026-01-08 02:47:30,448 - INFO - --------------------------------------------------------------------------------
2026-01-08 02:47:31,192 - INFO - Deleted project: ctest-TestRP-24057549, ID : e3fa798d-9e5a-4c3e-8d53-53ea44aaadc2