2025-09-16 12:21:00,321 - INFO - Domain Default found not creating
2025-09-16 12:21:00,488 - INFO - Project ctest-TestPorts-74509689 not found, creating it
2025-09-16 12:21:00,956 - INFO - Created Project:ctest-TestPorts-74509689, ID : d847471a-a8c3-498d-9dcf-95ae1589617b
2025-09-16 12:21:02,458 - INFO - ================================================================================
2025-09-16 12:21:02,459 - INFO - STARTING TEST : test_ports_bindings
2025-09-16 12:21:02,459 - INFO - TEST DESCRIPTION :
Verify that we are able to create a port with custom port bindings
Steps:
1) Create Port with Port bindings profile set to {'foo': 'bar'}
2) Retrieve and verify the same is set
2025-09-16 12:21:02,713 - DEBUG - Nothing to compare xmpp stats {'10.0.0.38': {'10.20.0.17': '0'}} with
2025-09-16 12:21:02,713 - INFO - Initial checks done. Running the testcase now
2025-09-16 12:21:02,713 - INFO -
2025-09-16 12:21:03,277 - DEBUG - Response for create_network : {'network': {'id': '5997ac13-3345-49f2-ade0-bcbf0ffa20d4', 'name': 'ctest-vn-17456680', 'tenant_id': 'd847471aa8c3498d9dcf95ae1589617b', 'project_id': 'd847471aa8c3498d9dcf95ae1589617b', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestPorts-74509689', 'ctest-vn-17456680'], 'port_security_enabled': True, 'description': ''}}
2025-09-16 12:21:03,448 - DEBUG - Response for create_subnet : {'subnet': {'id': '0e260406-a349-43d0-b07b-05f5078af747', 'name': '', 'tenant_id': 'd847471aa8c3498d9dcf95ae1589617b', 'network_id': '5997ac13-3345-49f2-ade0-bcbf0ffa20d4', 'ip_version': 4, 'cidr': '109.199.62.192/26', 'allocation_pools': [{'start': '109.199.62.194', 'end': '109.199.62.254'}], 'gateway_ip': '109.199.62.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '109.199.62.194', 'tags': [], 'project_id': 'd847471aa8c3498d9dcf95ae1589617b'}}
2025-09-16 12:21:03,465 - INFO - Created VN ctest-vn-17456680
2025-09-16 12:21:03,523 - DEBUG - VN ctest-vn-17456680 UUID is 5997ac13-3345-49f2-ade0-bcbf0ffa20d4
2025-09-16 12:21:03,632 - DEBUG - Read virtual machine interface {'fq_name': ['default-domain', 'ctest-TestPorts-74509689', 'ctest-vmi-54665916']}
2025-09-16 12:21:03,882 - DEBUG - Response for create_port : {'port': {'name': 'ctest-vmi-54665916', 'id': '80669829-15df-4200-9c6a-d276025b0310', 'tenant_id': 'd847471aa8c3498d9dcf95ae1589617b', 'network_id': '5997ac13-3345-49f2-ade0-bcbf0ffa20d4', 'mac_address': '02:80:66:98:29:15', 'binding:profile': {'foo': 'bar'}, 'binding:vnic_type': 'normal', 'binding:vif_details': {'port_filter': True}, 'binding:vif_type': 'unbound', 'binding:host_id': None, 'allowed_address_pairs': [], 'fixed_ips': [{'ip_address': '109.199.62.195', 'subnet_id': '0e260406-a349-43d0-b07b-05f5078af747'}], 'security_groups': ['669e96ff-cfbf-4ae0-8a88-bf7b3bf0b6a7'], 'admin_state_up': True, 'device_id': '', 'device_owner': '', 'status': 'DOWN', 'port_security_enabled': True, 'description': '', 'project_id': 'd847471aa8c3498d9dcf95ae1589617b'}}
2025-09-16 12:21:03,882 - DEBUG - Created port 80669829-15df-4200-9c6a-d276025b0310
2025-09-16 12:21:03,987 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/80669829-15df-4200-9c6a-d276025b0310
2025-09-16 12:21:04,287 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/80669829-15df-4200-9c6a-d276025b0310
2025-09-16 12:21:04,317 - INFO - VMI 80669829-15df-4200-9c6a-d276025b0310 verification in API Server passed
2025-09-16 12:21:04,411 - DEBUG - Response for delete_port : ()
2025-09-16 12:21:04,412 - INFO - Deleted port 80669829-15df-4200-9c6a-d276025b0310
2025-09-16 12:21:04,412 - INFO - Deleting VN ctest-vn-17456680
2025-09-16 12:21:04,510 - DEBUG - Response for deleting network ()
2025-09-16 12:21:04,768 - DEBUG - No XMPP flaps were noticed during the test
2025-09-16 12:21:04,768 - INFO - END TEST : test_ports_bindings : PASSED[0:00:02]
2025-09-16 12:21:04,768 - INFO - --------------------------------------------------------------------------------
2025-09-16 12:21:04,770 - INFO - ================================================================================
2025-09-16 12:21:04,770 - INFO - STARTING TEST : test_ports_specific_subnet_ip
2025-09-16 12:21:04,770 - INFO - TEST DESCRIPTION : Create ports with specific Subnet and IP
Create two ports in a VN with 2 subnets and specific IPs
Attach to two VMs
Ping between them should pass
2025-09-16 12:21:05,056 - DEBUG - Nothing to compare xmpp stats {'10.0.0.38': {'10.20.0.17': '0'}} with
2025-09-16 12:21:05,056 - INFO - Initial checks done. Running the testcase now
2025-09-16 12:21:05,056 - INFO -
2025-09-16 12:21:05,226 - DEBUG - Response for create_network : {'network': {'id': 'be4b386a-f430-4efa-a8ac-d1b1cd08742f', 'name': 'ctest-vn1-28371116', 'tenant_id': 'd847471aa8c3498d9dcf95ae1589617b', 'project_id': 'd847471aa8c3498d9dcf95ae1589617b', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestPorts-74509689', 'ctest-vn1-28371116'], 'port_security_enabled': True, 'description': ''}}
2025-09-16 12:21:05,411 - DEBUG - Response for create_subnet : {'subnet': {'id': '53406825-f43a-479f-b366-fa2da15baa1a', 'name': '', 'tenant_id': 'd847471aa8c3498d9dcf95ae1589617b', 'network_id': 'be4b386a-f430-4efa-a8ac-d1b1cd08742f', 'ip_version': 4, 'cidr': '220.201.253.128/26', 'allocation_pools': [{'start': '220.201.253.130', 'end': '220.201.253.190'}], 'gateway_ip': '220.201.253.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '220.201.253.130', 'tags': [], 'project_id': 'd847471aa8c3498d9dcf95ae1589617b'}}
2025-09-16 12:21:05,565 - DEBUG - Response for create_subnet : {'subnet': {'id': 'cd216135-9b47-4142-b81c-827e30e68b49', 'name': '', 'tenant_id': 'd847471aa8c3498d9dcf95ae1589617b', 'network_id': 'be4b386a-f430-4efa-a8ac-d1b1cd08742f', 'ip_version': 4, 'cidr': '196.240.79.64/26', 'allocation_pools': [{'start': '196.240.79.66', 'end': '196.240.79.126'}], 'gateway_ip': '196.240.79.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '196.240.79.66', 'tags': [], 'project_id': 'd847471aa8c3498d9dcf95ae1589617b'}}
2025-09-16 12:21:05,586 - INFO - Created VN ctest-vn1-28371116
2025-09-16 12:21:05,639 - DEBUG - VN ctest-vn1-28371116 UUID is be4b386a-f430-4efa-a8ac-d1b1cd08742f
2025-09-16 12:21:05,984 - DEBUG - Response for create_port : {'port': {'name': 'b8377860-81c6-4d46-a63c-df8d6f888e1b', 'id': 'b8377860-81c6-4d46-a63c-df8d6f888e1b', 'tenant_id': 'd847471aa8c3498d9dcf95ae1589617b', 'network_id': 'be4b386a-f430-4efa-a8ac-d1b1cd08742f', 'mac_address': '02:b8:37:78:60:81', 'binding:vnic_type': 'normal', 'binding:vif_details': {'port_filter': True}, 'binding:vif_type': 'unbound', 'binding:host_id': None, 'allowed_address_pairs': [], 'fixed_ips': [{'ip_address': '220.201.253.133', 'subnet_id': '53406825-f43a-479f-b366-fa2da15baa1a'}], 'security_groups': ['669e96ff-cfbf-4ae0-8a88-bf7b3bf0b6a7'], 'admin_state_up': True, 'device_id': '', 'device_owner': '', 'status': 'DOWN', 'port_security_enabled': True, 'description': '', 'project_id': 'd847471aa8c3498d9dcf95ae1589617b'}}
2025-09-16 12:21:06,196 - DEBUG - Response for create_port : {'port': {'name': 'cc9912f7-0e8b-407f-a04e-ae0f0db26123', 'id': 'cc9912f7-0e8b-407f-a04e-ae0f0db26123', 'tenant_id': 'd847471aa8c3498d9dcf95ae1589617b', 'network_id': 'be4b386a-f430-4efa-a8ac-d1b1cd08742f', 'mac_address': '02:cc:99:12:f7:0e', 'binding:vnic_type': 'normal', 'binding:vif_details': {'port_filter': True}, 'binding:vif_type': 'unbound', 'binding:host_id': None, 'allowed_address_pairs': [], 'fixed_ips': [{'ip_address': '196.240.79.69', 'subnet_id': 'cd216135-9b47-4142-b81c-827e30e68b49'}], 'security_groups': ['669e96ff-cfbf-4ae0-8a88-bf7b3bf0b6a7'], 'admin_state_up': True, 'device_id': '', 'device_owner': '', 'status': 'DOWN', 'port_security_enabled': True, 'description': '', 'project_id': 'd847471aa8c3498d9dcf95ae1589617b'}}
2025-09-16 12:21:06,261 - DEBUG - Services list from nova: [, , ]
2025-09-16 12:21:06,613 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-4123-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-4123-1)
2025-09-16 12:21:07,071 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-4123-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-4123-1)
2025-09-16 12:21:07,071 - INFO - Waiting for VM ctest-vn1-vm1-30698875 to be up..
2025-09-16 12:21:07,113 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2025-09-16 12:21:12,195 - DEBUG - VM is in ACTIVE state now
2025-09-16 12:21:12,196 - INFO - VM name : ctest-vn1-vm1-30698875
2025-09-16 12:21:12,269 - DEBUG - VM ctest-vn1-vm1-30698875 ID is 9c6cdc76-03c0-48d3-b0c9-9dd18ff2cf59
2025-09-16 12:21:12,288 - DEBUG - VM ctest-vn1-vm1-30698875 launched on Node cn-jenkins-deploy-platform-ansible-os-4123-1
2025-09-16 12:21:12,357 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/9c6cdc76-03c0-48d3-b0c9-9dd18ff2cf59
2025-09-16 12:21:12,366 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/b8377860-81c6-4d46-a63c-df8d6f888e1b
2025-09-16 12:21:15,613 - 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-09-16 12:21:15,613 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1-vm1-30698875 failed!
2025-09-16 12:21:15,667 - DEBUG - Gateway for vn default-domain:ctest-TestPorts-74509689:ctest-vn1-28371116 is 220.201.253.129 and allocation pool is NOT set
2025-09-16 12:21:15,667 - DEBUG - Gateway for vn default-domain:ctest-TestPorts-74509689:ctest-vn1-28371116 is 196.240.79.65 and allocation pool is NOT set
2025-09-16 12:21:19,741 - 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=6.60 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 1 received, 50% packet loss, time 1015ms\r\nrtt min/avg/max/mdev = 6.602/6.602/6.602/0.000 ms')
2025-09-16 12:21:19,741 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1-vm1-30698875 passed
2025-09-16 12:21:19,891 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-09-16 12:21:19,891 - DEBUG - Waiting to SSH to VM ctest-vn1-vm1-30698875, IP 220.201.253.133, Port 22
2025-09-16 12:21:20,056 - DEBUG - VM ctest-vn1-vm1-30698875 is ready for SSH connections
2025-09-16 12:21:20,056 - INFO - Waiting for VM ctest-vn1-vm2-28830117 to be up..
2025-09-16 12:21:20,135 - DEBUG - VM is in ACTIVE state now
2025-09-16 12:21:20,135 - INFO - VM name : ctest-vn1-vm2-28830117
2025-09-16 12:21:20,335 - DEBUG - VM ctest-vn1-vm2-28830117 ID is 743ab13a-ab49-4c23-9813-edd6030e169a
2025-09-16 12:21:20,335 - DEBUG - VM ctest-vn1-vm2-28830117 launched on Node cn-jenkins-deploy-platform-ansible-os-4123-1
2025-09-16 12:21:20,409 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/743ab13a-ab49-4c23-9813-edd6030e169a
2025-09-16 12:21:20,419 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/cc9912f7-0e8b-407f-a04e-ae0f0db26123
2025-09-16 12:21:21,657 - 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.03 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.350 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.350/1.689/3.029/1.339 ms')
2025-09-16 12:21:21,657 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-vn1-vm2-28830117 passed
2025-09-16 12:21:21,811 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-09-16 12:21:21,811 - DEBUG - Waiting to SSH to VM ctest-vn1-vm2-28830117, IP 196.240.79.69, Port 22
2025-09-16 12:21:21,966 - DEBUG - VM ctest-vn1-vm2-28830117 is ready for SSH connections
2025-09-16 12:21:21,966 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-09-16 12:21:21,966 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-09-16 12:21:21,966 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-09-16 12:21:21,966 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 220.201.253.133, host_string: cirros@169.254.0.4, password: cubswin:)gateway: ubuntu@10.0.0.38, gateway password: c0ntrail123
2025-09-16 12:21:21,966 - DEBUG - ping -s 56 -c 3 -W 1 220.201.253.133
2025-09-16 12:21:24,964 - DEBUG - PING 220.201.253.133 (220.201.253.133): 56 data bytes
64 bytes from 220.201.253.133: seq=0 ttl=63 time=3.760 ms
64 bytes from 220.201.253.133: seq=1 ttl=63 time=1.044 ms
64 bytes from 220.201.253.133: seq=2 ttl=63 time=1.137 ms
--- 220.201.253.133 ping statistics ---
3 packets transmitted, 3 packets received, 0% packet loss
round-trip min/avg/max = 1.044/1.980/3.760 ms
2025-09-16 12:21:24,964 - INFO - Ping to IP 220.201.253.133 from VM ctest-vn1-vm2-28830117 passed
2025-09-16 12:21:24,964 - INFO - Detaching port cc9912f7-0e8b-407f-a04e-ae0f0db26123 from VM ctest-vn1-vm2-28830117
2025-09-16 12:21:25,021 - INFO - Deleting VM ctest-vn1-vm2-28830117
2025-09-16 12:21:25,094 - INFO - Detaching port b8377860-81c6-4d46-a63c-df8d6f888e1b from VM ctest-vn1-vm1-30698875
2025-09-16 12:21:25,129 - INFO - Deleting VM ctest-vn1-vm1-30698875
2025-09-16 12:21:25,372 - DEBUG - Response for delete_port : ()
2025-09-16 12:21:25,535 - DEBUG - Response for delete_port : ()
2025-09-16 12:21:25,535 - INFO - Deleting VN ctest-vn1-28371116
2025-09-16 12:21:25,707 - DEBUG - Response for deleting network ()
2025-09-16 12:21:25,965 - DEBUG - No XMPP flaps were noticed during the test
2025-09-16 12:21:25,965 - INFO - END TEST : test_ports_specific_subnet_ip : PASSED[0:00:21]
2025-09-16 12:21:25,965 - INFO - --------------------------------------------------------------------------------
2025-09-16 12:21:26,700 - INFO - Deleted project: ctest-TestPorts-74509689, ID : d847471a-a8c3-498d-9dcf-95ae1589617b