2025-10-21 21:26:09,415 - INFO - Domain Default found not creating
2025-10-21 21:26:09,569 - INFO - Project ctest-TestPorts-60222111 not found, creating it
2025-10-21 21:26:10,024 - INFO - Created Project:ctest-TestPorts-60222111, ID : d9e42fd6-9ca3-49d5-a515-5e5bd0bca06e
2025-10-21 21:26:11,443 - INFO - ================================================================================
2025-10-21 21:26:11,443 - INFO - STARTING TEST : test_ports_bindings
2025-10-21 21:26:11,443 - 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-10-21 21:26:11,700 - DEBUG - Nothing to compare xmpp stats {'10.0.0.254': {'10.20.0.254': '0'}} with
2025-10-21 21:26:11,700 - INFO - Initial checks done. Running the testcase now
2025-10-21 21:26:11,700 - INFO -
2025-10-21 21:26:12,262 - DEBUG - Response for create_network : {'network': {'id': '5e7a5bc3-e211-4d91-b69c-2f158cba0d15', 'name': 'ctest-vn-06130797', 'tenant_id': 'd9e42fd69ca349d5a5155e5bd0bca06e', 'project_id': 'd9e42fd69ca349d5a5155e5bd0bca06e', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestPorts-60222111', 'ctest-vn-06130797'], 'port_security_enabled': True, 'description': ''}}
2025-10-21 21:26:12,445 - DEBUG - Response for create_subnet : {'subnet': {'id': 'eecfd7c0-3364-4d57-8db8-861f8e64557a', 'name': '', 'tenant_id': 'd9e42fd69ca349d5a5155e5bd0bca06e', 'network_id': '5e7a5bc3-e211-4d91-b69c-2f158cba0d15', 'ip_version': 4, 'cidr': '59.140.226.64/26', 'allocation_pools': [{'start': '59.140.226.66', 'end': '59.140.226.126'}], 'gateway_ip': '59.140.226.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '59.140.226.66', 'tags': [], 'project_id': 'd9e42fd69ca349d5a5155e5bd0bca06e'}}
2025-10-21 21:26:12,462 - INFO - Created VN ctest-vn-06130797
2025-10-21 21:26:12,515 - DEBUG - VN ctest-vn-06130797 UUID is 5e7a5bc3-e211-4d91-b69c-2f158cba0d15
2025-10-21 21:26:12,621 - DEBUG - Read virtual machine interface {'fq_name': ['default-domain', 'ctest-TestPorts-60222111', 'ctest-vmi-71807998']}
2025-10-21 21:26:12,883 - DEBUG - Response for create_port : {'port': {'name': 'ctest-vmi-71807998', 'id': '69aa76dc-485b-4f5d-8959-a44cecfc3192', 'tenant_id': 'd9e42fd69ca349d5a5155e5bd0bca06e', 'network_id': '5e7a5bc3-e211-4d91-b69c-2f158cba0d15', 'mac_address': '02:69:aa:76:dc:48', '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': '59.140.226.67', 'subnet_id': 'eecfd7c0-3364-4d57-8db8-861f8e64557a'}], 'security_groups': ['04f9d01b-4493-44ee-a520-687ef2912dc2'], 'admin_state_up': True, 'device_id': '', 'device_owner': '', 'status': 'DOWN', 'port_security_enabled': True, 'description': '', 'project_id': 'd9e42fd69ca349d5a5155e5bd0bca06e'}}
2025-10-21 21:26:12,883 - DEBUG - Created port 69aa76dc-485b-4f5d-8959-a44cecfc3192
2025-10-21 21:26:12,991 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-machine-interface/69aa76dc-485b-4f5d-8959-a44cecfc3192
2025-10-21 21:26:13,300 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-machine-interface/69aa76dc-485b-4f5d-8959-a44cecfc3192
2025-10-21 21:26:13,338 - INFO - VMI 69aa76dc-485b-4f5d-8959-a44cecfc3192 verification in API Server passed
2025-10-21 21:26:13,437 - DEBUG - Response for delete_port : ()
2025-10-21 21:26:13,437 - INFO - Deleted port 69aa76dc-485b-4f5d-8959-a44cecfc3192
2025-10-21 21:26:13,437 - INFO - Deleting VN ctest-vn-06130797
2025-10-21 21:26:13,537 - DEBUG - Response for deleting network ()
2025-10-21 21:26:13,797 - DEBUG - No XMPP flaps were noticed during the test
2025-10-21 21:26:13,797 - INFO - END TEST : test_ports_bindings : PASSED[0:00:02]
2025-10-21 21:26:13,797 - INFO - --------------------------------------------------------------------------------
2025-10-21 21:26:13,801 - INFO - ================================================================================
2025-10-21 21:26:13,801 - INFO - STARTING TEST : test_ports_specific_subnet_ip
2025-10-21 21:26:13,801 - 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-10-21 21:26:14,085 - DEBUG - Nothing to compare xmpp stats {'10.0.0.254': {'10.20.0.254': '0'}} with
2025-10-21 21:26:14,085 - INFO - Initial checks done. Running the testcase now
2025-10-21 21:26:14,085 - INFO -
2025-10-21 21:26:14,271 - DEBUG - Response for create_network : {'network': {'id': '876e649e-02ab-41d9-97d9-1a660c1934e3', 'name': 'ctest-vn1-10525691', 'tenant_id': 'd9e42fd69ca349d5a5155e5bd0bca06e', 'project_id': 'd9e42fd69ca349d5a5155e5bd0bca06e', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestPorts-60222111', 'ctest-vn1-10525691'], 'port_security_enabled': True, 'description': ''}}
2025-10-21 21:26:14,501 - DEBUG - Response for create_subnet : {'subnet': {'id': 'e1becace-0182-4b5e-b371-1b1ac1e88cf0', 'name': '', 'tenant_id': 'd9e42fd69ca349d5a5155e5bd0bca06e', 'network_id': '876e649e-02ab-41d9-97d9-1a660c1934e3', 'ip_version': 4, 'cidr': '196.66.239.64/26', 'allocation_pools': [{'start': '196.66.239.66', 'end': '196.66.239.126'}], 'gateway_ip': '196.66.239.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '196.66.239.66', 'tags': [], 'project_id': 'd9e42fd69ca349d5a5155e5bd0bca06e'}}
2025-10-21 21:26:14,643 - DEBUG - Response for create_subnet : {'subnet': {'id': 'a9594f09-89b8-4bef-a015-0a4a7df577d9', 'name': '', 'tenant_id': 'd9e42fd69ca349d5a5155e5bd0bca06e', 'network_id': '876e649e-02ab-41d9-97d9-1a660c1934e3', 'ip_version': 4, 'cidr': '192.209.55.64/26', 'allocation_pools': [{'start': '192.209.55.66', 'end': '192.209.55.126'}], 'gateway_ip': '192.209.55.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '192.209.55.66', 'tags': [], 'project_id': 'd9e42fd69ca349d5a5155e5bd0bca06e'}}
2025-10-21 21:26:14,666 - INFO - Created VN ctest-vn1-10525691
2025-10-21 21:26:14,719 - DEBUG - VN ctest-vn1-10525691 UUID is 876e649e-02ab-41d9-97d9-1a660c1934e3
2025-10-21 21:26:15,022 - DEBUG - Response for create_port : {'port': {'name': 'ed6c6fc4-3def-480a-bf1e-84d5fd1e0e96', 'id': 'ed6c6fc4-3def-480a-bf1e-84d5fd1e0e96', 'tenant_id': 'd9e42fd69ca349d5a5155e5bd0bca06e', 'network_id': '876e649e-02ab-41d9-97d9-1a660c1934e3', 'mac_address': '02:ed:6c:6f:c4:3d', '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.66.239.69', 'subnet_id': 'e1becace-0182-4b5e-b371-1b1ac1e88cf0'}], 'security_groups': ['04f9d01b-4493-44ee-a520-687ef2912dc2'], 'admin_state_up': True, 'device_id': '', 'device_owner': '', 'status': 'DOWN', 'port_security_enabled': True, 'description': '', 'project_id': 'd9e42fd69ca349d5a5155e5bd0bca06e'}}
2025-10-21 21:26:15,185 - DEBUG - Response for create_port : {'port': {'name': 'faa4b3c8-d272-4539-8ccf-718db988ac06', 'id': 'faa4b3c8-d272-4539-8ccf-718db988ac06', 'tenant_id': 'd9e42fd69ca349d5a5155e5bd0bca06e', 'network_id': '876e649e-02ab-41d9-97d9-1a660c1934e3', 'mac_address': '02:fa:a4:b3:c8:d2', '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': '192.209.55.69', 'subnet_id': 'a9594f09-89b8-4bef-a015-0a4a7df577d9'}], 'security_groups': ['04f9d01b-4493-44ee-a520-687ef2912dc2'], 'admin_state_up': True, 'device_id': '', 'device_owner': '', 'status': 'DOWN', 'port_security_enabled': True, 'description': '', 'project_id': 'd9e42fd69ca349d5a5155e5bd0bca06e'}}
2025-10-21 21:26:15,258 - DEBUG - Services list from nova: [, , ]
2025-10-21 21:26:15,724 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-4408-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-4408-1)
2025-10-21 21:26:16,131 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-4408-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-4408-1)
2025-10-21 21:26:16,131 - INFO - Waiting for VM ctest-vn1-vm1-50934693 to be up..
2025-10-21 21:26:16,191 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2025-10-21 21:26:21,273 - DEBUG - VM is in ACTIVE state now
2025-10-21 21:26:21,273 - INFO - VM name : ctest-vn1-vm1-50934693
2025-10-21 21:26:21,350 - DEBUG - VM ctest-vn1-vm1-50934693 ID is 5aa68395-180a-4cf6-858c-49a525143f63
2025-10-21 21:26:21,372 - DEBUG - VM ctest-vn1-vm1-50934693 launched on Node cn-jenkins-deploy-platform-ansible-os-4408-1
2025-10-21 21:26:21,446 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-machine/5aa68395-180a-4cf6-858c-49a525143f63
2025-10-21 21:26:21,456 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-machine-interface/ed6c6fc4-3def-480a-bf1e-84d5fd1e0e96
2025-10-21 21:26:24,720 - 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 1027ms')
2025-10-21 21:26:24,720 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1-vm1-50934693 failed!
2025-10-21 21:26:24,779 - DEBUG - Gateway for vn default-domain:ctest-TestPorts-60222111:ctest-vn1-10525691 is 196.66.239.65 and allocation pool is NOT set
2025-10-21 21:26:24,779 - DEBUG - Gateway for vn default-domain:ctest-TestPorts-60222111:ctest-vn1-10525691 is 192.209.55.65 and allocation pool is NOT set
2025-10-21 21:26:26,837 - 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.0 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.374 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.374/5.200/10.027/4.826 ms')
2025-10-21 21:26:26,837 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1-vm1-50934693 passed
2025-10-21 21:26:26,987 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-21 21:26:26,987 - DEBUG - Waiting to SSH to VM ctest-vn1-vm1-50934693, IP 196.66.239.69, Port 22
2025-10-21 21:26:27,151 - DEBUG - VM ctest-vn1-vm1-50934693 is ready for SSH connections
2025-10-21 21:26:27,151 - INFO - Waiting for VM ctest-vn1-vm2-96613433 to be up..
2025-10-21 21:26:27,234 - DEBUG - VM is in ACTIVE state now
2025-10-21 21:26:27,234 - INFO - VM name : ctest-vn1-vm2-96613433
2025-10-21 21:26:27,305 - DEBUG - VM ctest-vn1-vm2-96613433 ID is a49e6428-2aa0-4908-a7ea-021e12ee5f47
2025-10-21 21:26:27,305 - DEBUG - VM ctest-vn1-vm2-96613433 launched on Node cn-jenkins-deploy-platform-ansible-os-4408-1
2025-10-21 21:26:27,387 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-machine/a49e6428-2aa0-4908-a7ea-021e12ee5f47
2025-10-21 21:26:27,394 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-machine-interface/faa4b3c8-d272-4539-8ccf-718db988ac06
2025-10-21 21:26:28,626 - 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.33 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.436 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.436/1.881/3.327/1.445 ms')
2025-10-21 21:26:28,626 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-vn1-vm2-96613433 passed
2025-10-21 21:26:28,775 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-21 21:26:28,775 - DEBUG - Waiting to SSH to VM ctest-vn1-vm2-96613433, IP 192.209.55.69, Port 22
2025-10-21 21:26:28,930 - DEBUG - VM ctest-vn1-vm2-96613433 is ready for SSH connections
2025-10-21 21:26:28,930 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-21 21:26:28,930 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-21 21:26:28,930 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-21 21:26:28,930 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 196.66.239.69, host_string: cirros@169.254.0.4, password: cubswin:)gateway: ubuntu@10.0.0.254, gateway password: c0ntrail123
2025-10-21 21:26:28,930 - DEBUG - ping -s 56 -c 3 -W 1 196.66.239.69
2025-10-21 21:26:31,965 - DEBUG - PING 196.66.239.69 (196.66.239.69): 56 data bytes
64 bytes from 196.66.239.69: seq=0 ttl=63 time=3.801 ms
64 bytes from 196.66.239.69: seq=1 ttl=63 time=0.887 ms
64 bytes from 196.66.239.69: seq=2 ttl=63 time=0.871 ms
--- 196.66.239.69 ping statistics ---
3 packets transmitted, 3 packets received, 0% packet loss
round-trip min/avg/max = 0.871/1.853/3.801 ms
2025-10-21 21:26:31,965 - INFO - Ping to IP 196.66.239.69 from VM ctest-vn1-vm2-96613433 passed
2025-10-21 21:26:31,965 - INFO - Detaching port faa4b3c8-d272-4539-8ccf-718db988ac06 from VM ctest-vn1-vm2-96613433
2025-10-21 21:26:32,018 - INFO - Deleting VM ctest-vn1-vm2-96613433
2025-10-21 21:26:32,091 - INFO - Detaching port ed6c6fc4-3def-480a-bf1e-84d5fd1e0e96 from VM ctest-vn1-vm1-50934693
2025-10-21 21:26:32,128 - INFO - Deleting VM ctest-vn1-vm1-50934693
2025-10-21 21:26:32,364 - DEBUG - Response for delete_port : ()
2025-10-21 21:26:32,523 - DEBUG - Response for delete_port : ()
2025-10-21 21:26:32,524 - INFO - Deleting VN ctest-vn1-10525691
2025-10-21 21:26:32,662 - DEBUG - Response for deleting network ()
2025-10-21 21:26:32,921 - DEBUG - No XMPP flaps were noticed during the test
2025-10-21 21:26:32,921 - INFO - END TEST : test_ports_specific_subnet_ip : PASSED[0:00:19]
2025-10-21 21:26:32,921 - INFO - --------------------------------------------------------------------------------
2025-10-21 21:26:33,641 - INFO - Deleted project: ctest-TestPorts-60222111, ID : d9e42fd6-9ca3-49d5-a515-5e5bd0bca06e