2025-07-24 03:53:09,153 - INFO - Domain Default found not creating
2025-07-24 03:53:09,319 - INFO - Project ctest-TestRP-36637426 not found, creating it
2025-07-24 03:53:09,982 - INFO - Created Project:ctest-TestRP-36637426, ID : c9736af5-cfe9-4e44-bdbe-f339757cf438
2025-07-24 03:53:11,573 - DEBUG - Services list from nova: [, , , ]
2025-07-24 03:53:11,573 - DEBUG - Hosts: {'nova': ['an-jenkins-deploy-platform-ansible-os-3712-1', 'an-jenkins-deploy-platform-ansible-os-3712-2']}
2025-07-24 03:53:11,577 - INFO - ================================================================================
2025-07-24 03:53:11,579 - INFO - STARTING TEST : test_rp_interface
2025-07-24 03:53:11,579 - 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-07-24 03:53:12,901 - DEBUG - Nothing to compare xmpp stats {'10.0.0.57': {'10.20.0.17': '0', '10.20.0.129': '0'}, '10.0.0.23': {'10.20.0.129': '0', '10.20.0.17': '0'}} with
2025-07-24 03:53:12,901 - INFO - Initial checks done. Running the testcase now
2025-07-24 03:53:12,901 - INFO -
2025-07-24 03:53:13,682 - DEBUG - Response for create_network : {'network': {'id': 'd40a1d1a-c932-4019-a56c-e2b933707519', 'name': 'ctest-bgpaas_vn-98968750', 'tenant_id': 'c9736af5cfe94e44bdbef339757cf438', 'project_id': 'c9736af5cfe94e44bdbef339757cf438', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRP-36637426', 'ctest-bgpaas_vn-98968750'], 'port_security_enabled': True, 'description': ''}}
2025-07-24 03:53:14,040 - DEBUG - Response for create_subnet : {'subnet': {'id': '4538ddd6-d7a1-435b-a317-b0483e4eac79', 'name': '', 'tenant_id': 'c9736af5cfe94e44bdbef339757cf438', 'network_id': 'd40a1d1a-c932-4019-a56c-e2b933707519', 'ip_version': 4, 'cidr': '96.62.147.0/26', 'allocation_pools': [{'start': '96.62.147.2', 'end': '96.62.147.62'}], 'gateway_ip': '96.62.147.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '96.62.147.2', 'tags': [], 'project_id': 'c9736af5cfe94e44bdbef339757cf438'}}
2025-07-24 03:53:14,066 - INFO - Created VN ctest-bgpaas_vn-98968750
2025-07-24 03:53:14,085 - DEBUG - VN ctest-bgpaas_vn-98968750 UUID is d40a1d1a-c932-4019-a56c-e2b933707519
2025-07-24 03:53:14,704 - DEBUG - Response for create_network : {'network': {'id': 'c5f32516-e72d-49fe-b414-93256d5a4f26', 'name': 'ctest-bgpaas_vn-98957820', 'tenant_id': 'c9736af5cfe94e44bdbef339757cf438', 'project_id': 'c9736af5cfe94e44bdbef339757cf438', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRP-36637426', 'ctest-bgpaas_vn-98957820'], 'port_security_enabled': True, 'description': ''}}
2025-07-24 03:53:14,957 - DEBUG - Response for create_subnet : {'subnet': {'id': 'a657b71c-5a42-417e-8bac-e8e2761fb40d', 'name': '', 'tenant_id': 'c9736af5cfe94e44bdbef339757cf438', 'network_id': 'c5f32516-e72d-49fe-b414-93256d5a4f26', 'ip_version': 4, 'cidr': '8.111.74.0/26', 'allocation_pools': [{'start': '8.111.74.2', 'end': '8.111.74.62'}], 'gateway_ip': '8.111.74.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '8.111.74.2', 'tags': [], 'project_id': 'c9736af5cfe94e44bdbef339757cf438'}}
2025-07-24 03:53:14,986 - INFO - Created VN ctest-bgpaas_vn-98957820
2025-07-24 03:53:15,001 - DEBUG - VN ctest-bgpaas_vn-98957820 UUID is c5f32516-e72d-49fe-b414-93256d5a4f26
2025-07-24 03:53:16,564 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3712-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3712-1)
2025-07-24 03:53:17,985 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3712-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3712-2)
2025-07-24 03:53:17,986 - INFO - Waiting for VM test_vm to be up..
2025-07-24 03:53:18,089 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2025-07-24 03:53:23,180 - DEBUG - VM is in ACTIVE state now
2025-07-24 03:53:23,180 - INFO - VM name : test_vm
2025-07-24 03:53:23,273 - DEBUG - VM test_vm ID is ed5a5cd6-d4d7-4fc3-a374-f519d01e5f83
2025-07-24 03:53:23,300 - DEBUG - VM test_vm launched on Node an-jenkins-deploy-platform-ansible-os-3712-1
2025-07-24 03:53:23,389 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine/ed5a5cd6-d4d7-4fc3-a374-f519d01e5f83
2025-07-24 03:53:23,713 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine/ed5a5cd6-d4d7-4fc3-a374-f519d01e5f83
2025-07-24 03:53:23,751 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine-interface/381b7f5d-6179-4fbb-936f-8114d18fb424
2025-07-24 03:53:26,977 - 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 1007ms')
2025-07-24 03:53:26,977 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM test_vm failed!
2025-07-24 03:53:26,996 - DEBUG - Gateway for vn default-domain:ctest-TestRP-36637426:ctest-bgpaas_vn-98968750 is 96.62.147.1 and allocation pool is NOT set
2025-07-24 03:53:29,062 - 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=7.26 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.486 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.486/3.873/7.260/3.387 ms')
2025-07-24 03:53:29,062 - INFO - Ping to Metadata IP 169.254.0.3 of VM test_vm passed
2025-07-24 03:53:29,183 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-07-24 03:53:29,183 - DEBUG - Waiting to SSH to VM test_vm, IP 96.62.147.3, Port 22
2025-07-24 03:53:29,339 - DEBUG - VM test_vm is ready for SSH connections
2025-07-24 03:53:29,339 - INFO - Waiting for VM test2_vm to be up..
2025-07-24 03:53:29,434 - DEBUG - VM is in ACTIVE state now
2025-07-24 03:53:29,434 - INFO - VM name : test2_vm
2025-07-24 03:53:29,521 - DEBUG - VM test2_vm ID is 31827206-2194-4f5d-af6a-27453e26f783
2025-07-24 03:53:29,521 - DEBUG - VM test2_vm launched on Node an-jenkins-deploy-platform-ansible-os-3712-2
2025-07-24 03:53:29,614 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine/31827206-2194-4f5d-af6a-27453e26f783
2025-07-24 03:53:29,627 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine-interface/62dfc97f-735a-4083-aff2-d3956a486ca0
2025-07-24 03:53:30,858 - 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.06 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=2.64 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.635/3.846/5.058/1.211 ms')
2025-07-24 03:53:30,858 - INFO - Ping to Metadata IP 169.254.0.3 of VM test2_vm passed
2025-07-24 03:53:30,976 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-07-24 03:53:30,976 - DEBUG - Waiting to SSH to VM test2_vm, IP 8.111.74.3, Port 22
2025-07-24 03:53:31,149 - DEBUG - VM test2_vm is ready for SSH connections
2025-07-24 03:53:31,324 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-07-24 03:53:31,374 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-07-24 03:53:31,374 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 8.111.74.3, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.57, gateway password: c0ntrail123
2025-07-24 03:53:31,375 - DEBUG - ping -s 56 -c 3 -W 1 8.111.74.3
2025-07-24 03:53:35,071 - DEBUG - PING 8.111.74.3 (8.111.74.3): 56 data bytes
64 bytes from 8.111.74.3: seq=0 ttl=63 time=6.252 ms
64 bytes from 8.111.74.3: seq=1 ttl=63 time=1.361 ms
64 bytes from 8.111.74.3: seq=2 ttl=63 time=1.330 ms
--- 8.111.74.3 ping statistics ---
3 packets transmitted, 3 packets received, 0% packet loss
round-trip min/avg/max = 1.330/2.981/6.252 ms
2025-07-24 03:53:35,071 - INFO - Ping to IP 8.111.74.3 from VM test_vm passed
2025-07-24 03:53:35,214 - INFO - Deleting VM test2_vm
2025-07-24 03:53:35,315 - INFO - Deleting VM test_vm
2025-07-24 03:53:35,401 - INFO - Deleting VN ctest-bgpaas_vn-98957820
2025-07-24 03:53:35,460 - DEBUG - VN c5f32516-e72d-49fe-b414-93256d5a4f26 still in use: Unable to complete operation on network c5f32516-e72d-49fe-b414-93256d5a4f26. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-06ced722-769a-4bd3-b3a1-91120dbd6c7a']
2025-07-24 03:53:35,460 - WARNING - Deleting VN ctest-bgpaas_vn-98957820 failed..Will retry
2025-07-24 03:53:37,713 - DEBUG - Response for deleting network ()
2025-07-24 03:53:37,713 - INFO - Deleting VN ctest-bgpaas_vn-98968750
2025-07-24 03:53:37,921 - DEBUG - Response for deleting network ()
2025-07-24 03:53:39,256 - DEBUG - No XMPP flaps were noticed during the test
2025-07-24 03:53:39,256 - INFO - END TEST : test_rp_interface : PASSED[0:00:28]
2025-07-24 03:53:39,257 - INFO - --------------------------------------------------------------------------------
2025-07-24 03:53:39,260 - INFO - ================================================================================
2025-07-24 03:53:39,271 - INFO - STARTING TEST : test_rp_interface_ext_community
2025-07-24 03:53:39,271 - 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-07-24 03:53:40,599 - DEBUG - Nothing to compare xmpp stats {'10.0.0.57': {'10.20.0.17': '0', '10.20.0.129': '0'}, '10.0.0.23': {'10.20.0.129': '0', '10.20.0.17': '0'}} with
2025-07-24 03:53:40,599 - INFO - Initial checks done. Running the testcase now
2025-07-24 03:53:40,599 - INFO -
2025-07-24 03:53:40,881 - DEBUG - Response for create_network : {'network': {'id': '8799c6f7-7a7b-4303-a12a-b8a0907b1295', 'name': 'ctest-bgpaas_vn-31328690', 'tenant_id': 'c9736af5cfe94e44bdbef339757cf438', 'project_id': 'c9736af5cfe94e44bdbef339757cf438', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRP-36637426', 'ctest-bgpaas_vn-31328690'], 'port_security_enabled': True, 'description': ''}}
2025-07-24 03:53:41,126 - DEBUG - Response for create_subnet : {'subnet': {'id': 'ea16f556-3831-4519-b7e2-204f20a10665', 'name': '', 'tenant_id': 'c9736af5cfe94e44bdbef339757cf438', 'network_id': '8799c6f7-7a7b-4303-a12a-b8a0907b1295', 'ip_version': 4, 'cidr': '128.206.21.128/26', 'allocation_pools': [{'start': '128.206.21.130', 'end': '128.206.21.190'}], 'gateway_ip': '128.206.21.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '128.206.21.130', 'tags': [], 'project_id': 'c9736af5cfe94e44bdbef339757cf438'}}
2025-07-24 03:53:41,151 - INFO - Created VN ctest-bgpaas_vn-31328690
2025-07-24 03:53:41,167 - DEBUG - VN ctest-bgpaas_vn-31328690 UUID is 8799c6f7-7a7b-4303-a12a-b8a0907b1295
2025-07-24 03:53:41,752 - DEBUG - Response for create_network : {'network': {'id': '946e3b44-de0b-4957-8cb5-8e97e7116462', 'name': 'ctest-bgpaas_vn-46137737', 'tenant_id': 'c9736af5cfe94e44bdbef339757cf438', 'project_id': 'c9736af5cfe94e44bdbef339757cf438', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRP-36637426', 'ctest-bgpaas_vn-46137737'], 'port_security_enabled': True, 'description': ''}}
2025-07-24 03:53:42,043 - DEBUG - Response for create_subnet : {'subnet': {'id': '2db5042c-5be1-4913-a932-c49a93b313a5', 'name': '', 'tenant_id': 'c9736af5cfe94e44bdbef339757cf438', 'network_id': '946e3b44-de0b-4957-8cb5-8e97e7116462', 'ip_version': 4, 'cidr': '9.139.30.192/26', 'allocation_pools': [{'start': '9.139.30.194', 'end': '9.139.30.254'}], 'gateway_ip': '9.139.30.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '9.139.30.194', 'tags': [], 'project_id': 'c9736af5cfe94e44bdbef339757cf438'}}
2025-07-24 03:53:42,070 - INFO - Created VN ctest-bgpaas_vn-46137737
2025-07-24 03:53:42,088 - DEBUG - VN ctest-bgpaas_vn-46137737 UUID is 946e3b44-de0b-4957-8cb5-8e97e7116462
2025-07-24 03:53:43,533 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3712-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3712-1)
2025-07-24 03:53:45,068 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3712-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3712-2)
2025-07-24 03:53:45,068 - INFO - Waiting for VM test_vm to be up..
2025-07-24 03:53:45,169 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2025-07-24 03:53:50,265 - DEBUG - VM is in ACTIVE state now
2025-07-24 03:53:50,265 - INFO - VM name : test_vm
2025-07-24 03:53:50,362 - DEBUG - VM test_vm ID is b000e4fc-2e2f-4ee0-9917-402ed880f296
2025-07-24 03:53:50,362 - DEBUG - VM test_vm launched on Node an-jenkins-deploy-platform-ansible-os-3712-1
2025-07-24 03:53:50,453 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine/b000e4fc-2e2f-4ee0-9917-402ed880f296
2025-07-24 03:53:50,468 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine-interface/f3e125de-17d3-40d6-842e-94b5d16c2a74
2025-07-24 03:53:53,702 - 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-07-24 03:53:53,702 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM test_vm failed!
2025-07-24 03:53:53,722 - DEBUG - Gateway for vn default-domain:ctest-TestRP-36637426:ctest-bgpaas_vn-31328690 is 128.206.21.129 and allocation pool is NOT set
2025-07-24 03:53:55,790 - 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=10.9 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=2.59 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.593/6.763/10.934/4.170 ms')
2025-07-24 03:53:55,790 - INFO - Ping to Metadata IP 169.254.0.3 of VM test_vm passed
2025-07-24 03:53:55,910 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-07-24 03:53:55,910 - DEBUG - Waiting to SSH to VM test_vm, IP 128.206.21.131, Port 22
2025-07-24 03:53:55,977 - DEBUG - Error on ssh to cirros@169.254.0.3:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2025-07-24 03:53:56,084 - DEBUG - VM test_vm is NOT ready for SSH connections, VM status: ACTIVE
2025-07-24 03:54:01,085 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-07-24 03:54:01,085 - DEBUG - Waiting to SSH to VM test_vm, IP 128.206.21.131, Port 22
2025-07-24 03:54:01,248 - DEBUG - VM test_vm is ready for SSH connections
2025-07-24 03:54:01,249 - INFO - Waiting for VM test2_vm to be up..
2025-07-24 03:54:01,387 - DEBUG - VM is in ACTIVE state now
2025-07-24 03:54:01,387 - INFO - VM name : test2_vm
2025-07-24 03:54:01,546 - DEBUG - VM test2_vm ID is 0024753a-ffcd-421c-a523-f401c6a24c89
2025-07-24 03:54:01,547 - DEBUG - VM test2_vm launched on Node an-jenkins-deploy-platform-ansible-os-3712-2
2025-07-24 03:54:01,697 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine/0024753a-ffcd-421c-a523-f401c6a24c89
2025-07-24 03:54:01,729 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine-interface/e3fa5af7-f258-4a31-aa41-f325b9b3d06b
2025-07-24 03:54:02,972 - 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.12 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.440 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.440/2.279/4.118/1.839 ms')
2025-07-24 03:54:02,972 - INFO - Ping to Metadata IP 169.254.0.3 of VM test2_vm passed
2025-07-24 03:54:03,112 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-07-24 03:54:03,112 - DEBUG - Waiting to SSH to VM test2_vm, IP 9.139.30.195, Port 22
2025-07-24 03:54:03,270 - DEBUG - VM test2_vm is ready for SSH connections
2025-07-24 03:54:03,476 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-07-24 03:54:03,530 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-07-24 03:54:03,530 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 9.139.30.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.57, gateway password: c0ntrail123
2025-07-24 03:54:03,530 - DEBUG - ping -s 56 -c 3 -W 1 9.139.30.195
2025-07-24 03:54:07,560 - DEBUG - PING 9.139.30.195 (9.139.30.195): 56 data bytes
64 bytes from 9.139.30.195: seq=0 ttl=63 time=6.752 ms
64 bytes from 9.139.30.195: seq=1 ttl=63 time=1.496 ms
64 bytes from 9.139.30.195: seq=2 ttl=63 time=1.713 ms
--- 9.139.30.195 ping statistics ---
3 packets transmitted, 3 packets received, 0% packet loss
round-trip min/avg/max = 1.496/3.320/6.752 ms
2025-07-24 03:54:07,560 - INFO - Ping to IP 9.139.30.195 from VM test_vm passed
2025-07-24 03:54:07,740 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-07-24 03:54:08,774 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-07-24 03:54:09,110 - INFO - Deleting VM test2_vm
2025-07-24 03:54:09,188 - INFO - Deleting VM test_vm
2025-07-24 03:54:09,281 - INFO - Deleting VN ctest-bgpaas_vn-46137737
2025-07-24 03:54:09,330 - DEBUG - VN 946e3b44-de0b-4957-8cb5-8e97e7116462 still in use: Unable to complete operation on network 946e3b44-de0b-4957-8cb5-8e97e7116462. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-d63598be-95d6-4260-8029-faa9ee960cc5']
2025-07-24 03:54:09,330 - WARNING - Deleting VN ctest-bgpaas_vn-46137737 failed..Will retry
2025-07-24 03:54:11,514 - DEBUG - Response for deleting network ()
2025-07-24 03:54:11,514 - INFO - Deleting VN ctest-bgpaas_vn-31328690
2025-07-24 03:54:11,719 - DEBUG - Response for deleting network ()
2025-07-24 03:54:13,020 - DEBUG - No XMPP flaps were noticed during the test
2025-07-24 03:54:13,020 - INFO - --------------------------------------------------------------------------------
2025-07-24 03:54:13,024 - INFO - ================================================================================
2025-07-24 03:54:13,024 - INFO - STARTING TEST : test_rp_interface_static
2025-07-24 03:54:13,024 - 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.
2025-07-24 03:54:14,324 - DEBUG - Nothing to compare xmpp stats {'10.0.0.57': {'10.20.0.17': '0', '10.20.0.129': '0'}, '10.0.0.23': {'10.20.0.129': '0', '10.20.0.17': '0'}} with
2025-07-24 03:54:14,324 - INFO - Initial checks done. Running the testcase now
2025-07-24 03:54:14,325 - INFO -
2025-07-24 03:54:14,648 - DEBUG - Response for create_network : {'network': {'id': 'd414d1d1-d646-457c-8c52-6f3cbdd67515', 'name': 'ctest-bgpaas_vn-42556875', 'tenant_id': 'c9736af5cfe94e44bdbef339757cf438', 'project_id': 'c9736af5cfe94e44bdbef339757cf438', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRP-36637426', 'ctest-bgpaas_vn-42556875'], 'port_security_enabled': True, 'description': ''}}
2025-07-24 03:54:14,905 - DEBUG - Response for create_subnet : {'subnet': {'id': 'ff5bb594-bc29-4c9d-b85f-39086dec1a6a', 'name': '', 'tenant_id': 'c9736af5cfe94e44bdbef339757cf438', 'network_id': 'd414d1d1-d646-457c-8c52-6f3cbdd67515', 'ip_version': 4, 'cidr': '52.62.20.0/26', 'allocation_pools': [{'start': '52.62.20.2', 'end': '52.62.20.62'}], 'gateway_ip': '52.62.20.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '52.62.20.2', 'tags': [], 'project_id': 'c9736af5cfe94e44bdbef339757cf438'}}
2025-07-24 03:54:14,930 - INFO - Created VN ctest-bgpaas_vn-42556875
2025-07-24 03:54:14,947 - DEBUG - VN ctest-bgpaas_vn-42556875 UUID is d414d1d1-d646-457c-8c52-6f3cbdd67515
2025-07-24 03:54:15,550 - DEBUG - Response for create_network : {'network': {'id': '34d1ec00-129c-46dd-a532-4248844b8235', 'name': 'ctest-bgpaas_vn-46955510', 'tenant_id': 'c9736af5cfe94e44bdbef339757cf438', 'project_id': 'c9736af5cfe94e44bdbef339757cf438', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRP-36637426', 'ctest-bgpaas_vn-46955510'], 'port_security_enabled': True, 'description': ''}}
2025-07-24 03:54:15,776 - DEBUG - Response for create_subnet : {'subnet': {'id': '59b501f1-6b12-4c48-b319-d92c0feaafde', 'name': '', 'tenant_id': 'c9736af5cfe94e44bdbef339757cf438', 'network_id': '34d1ec00-129c-46dd-a532-4248844b8235', 'ip_version': 4, 'cidr': '106.158.91.0/26', 'allocation_pools': [{'start': '106.158.91.2', 'end': '106.158.91.62'}], 'gateway_ip': '106.158.91.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '106.158.91.2', 'tags': [], 'project_id': 'c9736af5cfe94e44bdbef339757cf438'}}
2025-07-24 03:54:15,809 - INFO - Created VN ctest-bgpaas_vn-46955510
2025-07-24 03:54:15,824 - DEBUG - VN ctest-bgpaas_vn-46955510 UUID is 34d1ec00-129c-46dd-a532-4248844b8235
2025-07-24 03:54:17,353 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3712-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3712-1)
2025-07-24 03:54:18,895 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3712-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3712-2)
2025-07-24 03:54:18,895 - INFO - Waiting for VM test_vm to be up..
2025-07-24 03:54:18,994 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2025-07-24 03:54:24,084 - DEBUG - VM is in ACTIVE state now
2025-07-24 03:54:24,084 - INFO - VM name : test_vm
2025-07-24 03:54:24,169 - DEBUG - VM test_vm ID is 78fd69a9-207e-4c45-a87e-ecf0a2694b9d
2025-07-24 03:54:24,170 - DEBUG - VM test_vm launched on Node an-jenkins-deploy-platform-ansible-os-3712-1
2025-07-24 03:54:24,257 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine/78fd69a9-207e-4c45-a87e-ecf0a2694b9d
2025-07-24 03:54:24,274 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine-interface/be0a856d-eeda-41fd-a31f-d4354c5dcf89
2025-07-24 03:54:27,522 - 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 1032ms')
2025-07-24 03:54:27,522 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM test_vm failed!
2025-07-24 03:54:27,544 - DEBUG - Gateway for vn default-domain:ctest-TestRP-36637426:ctest-bgpaas_vn-42556875 is 52.62.20.1 and allocation pool is NOT set
2025-07-24 03:54:29,616 - 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=10.8 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.424 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.424/5.619/10.815/5.195 ms')
2025-07-24 03:54:29,617 - INFO - Ping to Metadata IP 169.254.0.3 of VM test_vm passed
2025-07-24 03:54:29,738 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-07-24 03:54:29,739 - DEBUG - Waiting to SSH to VM test_vm, IP 52.62.20.3, Port 22
2025-07-24 03:54:29,914 - DEBUG - VM test_vm is ready for SSH connections
2025-07-24 03:54:29,914 - INFO - Waiting for VM test2_vm to be up..
2025-07-24 03:54:30,013 - DEBUG - VM is in ACTIVE state now
2025-07-24 03:54:30,013 - INFO - VM name : test2_vm
2025-07-24 03:54:30,109 - DEBUG - VM test2_vm ID is 347a346d-7e02-4205-9f9a-53843e44657b
2025-07-24 03:54:30,109 - DEBUG - VM test2_vm launched on Node an-jenkins-deploy-platform-ansible-os-3712-2
2025-07-24 03:54:30,198 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine/347a346d-7e02-4205-9f9a-53843e44657b
2025-07-24 03:54:30,213 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine-interface/e12c79a3-6f02-4a0c-8d0a-f6cd61d6c640
2025-07-24 03:54:31,442 - 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=9.38 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=2.14 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.142/5.761/9.381/3.619 ms')
2025-07-24 03:54:31,442 - INFO - Ping to Metadata IP 169.254.0.3 of VM test2_vm passed
2025-07-24 03:54:31,564 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-07-24 03:54:31,564 - DEBUG - Waiting to SSH to VM test2_vm, IP 106.158.91.3, Port 22
2025-07-24 03:54:31,633 - DEBUG - Error on ssh to cirros@169.254.0.3:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2025-07-24 03:54:31,746 - DEBUG - VM test2_vm is NOT ready for SSH connections, VM status: ACTIVE
2025-07-24 03:54:36,747 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-07-24 03:54:36,747 - DEBUG - Waiting to SSH to VM test2_vm, IP 106.158.91.3, Port 22
2025-07-24 03:54:36,906 - DEBUG - VM test2_vm is ready for SSH connections
2025-07-24 03:54:37,016 - INFO - Created InterfaceRouteTable ['default-domain', 'ctest-TestRP-36637426', 'ctest-int_table_right-34179844'](UUID 335dfeae-98ec-44ca-8ce2-0d296926c6fe), prefixes : ['135.40.176.192/26']
2025-07-24 03:54:37,095 - INFO - Added intf route table 335dfeae-98ec-44ca-8ce2-0d296926c6fe to port be0a856d-eeda-41fd-a31f-d4354c5dcf89
2025-07-24 03:54:37,362 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-07-24 03:54:37,362 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 106.158.91.3, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.57, gateway password: c0ntrail123
2025-07-24 03:54:37,362 - DEBUG - ping -s 56 -c 3 -W 1 106.158.91.3
2025-07-24 03:54:41,080 - DEBUG - PING 106.158.91.3 (106.158.91.3): 56 data bytes
64 bytes from 106.158.91.3: seq=0 ttl=63 time=8.742 ms
64 bytes from 106.158.91.3: seq=1 ttl=63 time=1.604 ms
64 bytes from 106.158.91.3: seq=2 ttl=63 time=1.353 ms
--- 106.158.91.3 ping statistics ---
3 packets transmitted, 3 packets received, 0% packet loss
round-trip min/avg/max = 1.353/3.899/8.742 ms
2025-07-24 03:54:41,080 - INFO - Ping to IP 106.158.91.3 from VM test_vm passed
2025-07-24 03:54:41,217 - INFO - Deleting VM test2_vm
2025-07-24 03:54:41,317 - INFO - Deleting VM test_vm
2025-07-24 03:54:41,400 - INFO - Deleting VN ctest-bgpaas_vn-46955510
2025-07-24 03:54:41,452 - DEBUG - VN 34d1ec00-129c-46dd-a532-4248844b8235 still in use: Unable to complete operation on network 34d1ec00-129c-46dd-a532-4248844b8235. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-6008f98f-c026-4a27-b75f-1388fe242c0d']
2025-07-24 03:54:41,452 - WARNING - Deleting VN ctest-bgpaas_vn-46955510 failed..Will retry
2025-07-24 03:54:43,668 - DEBUG - Response for deleting network ()
2025-07-24 03:54:43,668 - INFO - Deleting VN ctest-bgpaas_vn-42556875
2025-07-24 03:54:43,855 - DEBUG - Response for deleting network ()
2025-07-24 03:54:45,162 - DEBUG - No XMPP flaps were noticed during the test
2025-07-24 03:54:45,162 - INFO - END TEST : test_rp_interface_static : PASSED[0:00:32]
2025-07-24 03:54:45,162 - INFO - --------------------------------------------------------------------------------
2025-07-24 03:54:45,965 - INFO - Deleted project: ctest-TestRP-36637426, ID : c9736af5-cfe9-4e44-bdbe-f339757cf438