2025-08-25 02:14:26,955 - INFO - Domain Default found not creating
2025-08-25 02:14:27,085 - INFO - Project ctest-TestPorts-25592083 not found, creating it
2025-08-25 02:14:27,532 - INFO - Created Project:ctest-TestPorts-25592083, ID : e455fc2c-da17-4ce4-9cc1-c03210aa8d16
2025-08-25 02:14:28,938 - INFO - ================================================================================
2025-08-25 02:14:28,938 - INFO - STARTING TEST : test_ports_bindings
2025-08-25 02:14:28,938 - 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-08-25 02:14:29,195 - DEBUG - Nothing to compare xmpp stats {'10.0.0.50': {'10.20.0.14': '0'}} with
2025-08-25 02:14:29,196 - INFO - Initial checks done. Running the testcase now
2025-08-25 02:14:29,196 - INFO -
2025-08-25 02:14:29,724 - DEBUG - Response for create_network : {'network': {'id': 'd8e2d98a-0b99-43fb-926d-834e616246cc', 'name': 'ctest-vn-62248658', 'tenant_id': 'e455fc2cda174ce49cc1c03210aa8d16', 'project_id': 'e455fc2cda174ce49cc1c03210aa8d16', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestPorts-25592083', 'ctest-vn-62248658'], 'port_security_enabled': True, 'description': ''}}
2025-08-25 02:14:29,888 - DEBUG - Response for create_subnet : {'subnet': {'id': '078d93d6-2b2b-4eb5-85b8-6916e9903424', 'name': '', 'tenant_id': 'e455fc2cda174ce49cc1c03210aa8d16', 'network_id': 'd8e2d98a-0b99-43fb-926d-834e616246cc', 'ip_version': 4, 'cidr': '12.187.20.128/26', 'allocation_pools': [{'start': '12.187.20.130', 'end': '12.187.20.190'}], 'gateway_ip': '12.187.20.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '12.187.20.130', 'tags': [], 'project_id': 'e455fc2cda174ce49cc1c03210aa8d16'}}
2025-08-25 02:14:29,906 - INFO - Created VN ctest-vn-62248658
2025-08-25 02:14:29,958 - DEBUG - VN ctest-vn-62248658 UUID is d8e2d98a-0b99-43fb-926d-834e616246cc
2025-08-25 02:14:30,059 - DEBUG - Read virtual machine interface {'fq_name': ['default-domain', 'ctest-TestPorts-25592083', 'ctest-vmi-21868426']}
2025-08-25 02:14:30,348 - DEBUG - Response for create_port : {'port': {'name': 'ctest-vmi-21868426', 'id': '07b17bf7-994f-4bfe-8380-6e4c8e032a87', 'tenant_id': 'e455fc2cda174ce49cc1c03210aa8d16', 'network_id': 'd8e2d98a-0b99-43fb-926d-834e616246cc', 'mac_address': '02:07:b1:7b:f7:99', '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': '12.187.20.131', 'subnet_id': '078d93d6-2b2b-4eb5-85b8-6916e9903424'}], 'security_groups': ['10ab762b-f5d4-4a30-a48f-b2ffa27e8b8d'], 'admin_state_up': True, 'device_id': '', 'device_owner': '', 'status': 'DOWN', 'port_security_enabled': True, 'description': '', 'project_id': 'e455fc2cda174ce49cc1c03210aa8d16'}}
2025-08-25 02:14:30,348 - DEBUG - Created port 07b17bf7-994f-4bfe-8380-6e4c8e032a87
2025-08-25 02:14:30,454 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine-interface/07b17bf7-994f-4bfe-8380-6e4c8e032a87
2025-08-25 02:14:30,742 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine-interface/07b17bf7-994f-4bfe-8380-6e4c8e032a87
2025-08-25 02:14:30,771 - INFO - VMI 07b17bf7-994f-4bfe-8380-6e4c8e032a87 verification in API Server passed
2025-08-25 02:14:30,853 - DEBUG - Response for delete_port : ()
2025-08-25 02:14:30,853 - INFO - Deleted port 07b17bf7-994f-4bfe-8380-6e4c8e032a87
2025-08-25 02:14:30,853 - INFO - Deleting VN ctest-vn-62248658
2025-08-25 02:14:30,956 - DEBUG - Response for deleting network ()
2025-08-25 02:14:31,212 - DEBUG - No XMPP flaps were noticed during the test
2025-08-25 02:14:31,212 - INFO - END TEST : test_ports_bindings : PASSED[0:00:03]
2025-08-25 02:14:31,212 - INFO - --------------------------------------------------------------------------------
2025-08-25 02:14:31,215 - INFO - ================================================================================
2025-08-25 02:14:31,215 - INFO - STARTING TEST : test_ports_specific_subnet_ip
2025-08-25 02:14:31,215 - 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-08-25 02:14:31,511 - DEBUG - Nothing to compare xmpp stats {'10.0.0.50': {'10.20.0.14': '0'}} with
2025-08-25 02:14:31,511 - INFO - Initial checks done. Running the testcase now
2025-08-25 02:14:31,511 - INFO -
2025-08-25 02:14:31,682 - DEBUG - Response for create_network : {'network': {'id': 'd02acef4-36dc-4c2f-8383-3fd490e9dc9f', 'name': 'ctest-vn1-37817941', 'tenant_id': 'e455fc2cda174ce49cc1c03210aa8d16', 'project_id': 'e455fc2cda174ce49cc1c03210aa8d16', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestPorts-25592083', 'ctest-vn1-37817941'], 'port_security_enabled': True, 'description': ''}}
2025-08-25 02:14:31,834 - DEBUG - Response for create_subnet : {'subnet': {'id': '22c41bb7-4579-409b-9807-a86aad6a8a76', 'name': '', 'tenant_id': 'e455fc2cda174ce49cc1c03210aa8d16', 'network_id': 'd02acef4-36dc-4c2f-8383-3fd490e9dc9f', 'ip_version': 4, 'cidr': '55.208.109.0/26', 'allocation_pools': [{'start': '55.208.109.2', 'end': '55.208.109.62'}], 'gateway_ip': '55.208.109.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '55.208.109.2', 'tags': [], 'project_id': 'e455fc2cda174ce49cc1c03210aa8d16'}}
2025-08-25 02:14:32,019 - DEBUG - Response for create_subnet : {'subnet': {'id': 'f585f62b-67c2-4296-8cfa-b9692ad72e5c', 'name': '', 'tenant_id': 'e455fc2cda174ce49cc1c03210aa8d16', 'network_id': 'd02acef4-36dc-4c2f-8383-3fd490e9dc9f', 'ip_version': 4, 'cidr': '48.121.24.128/26', 'allocation_pools': [{'start': '48.121.24.130', 'end': '48.121.24.190'}], 'gateway_ip': '48.121.24.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '48.121.24.130', 'tags': [], 'project_id': 'e455fc2cda174ce49cc1c03210aa8d16'}}
2025-08-25 02:14:32,038 - INFO - Created VN ctest-vn1-37817941
2025-08-25 02:14:32,090 - DEBUG - VN ctest-vn1-37817941 UUID is d02acef4-36dc-4c2f-8383-3fd490e9dc9f
2025-08-25 02:14:32,406 - DEBUG - Response for create_port : {'port': {'name': 'be27f2a2-9fed-4185-9979-ffa8a4ba2195', 'id': 'be27f2a2-9fed-4185-9979-ffa8a4ba2195', 'tenant_id': 'e455fc2cda174ce49cc1c03210aa8d16', 'network_id': 'd02acef4-36dc-4c2f-8383-3fd490e9dc9f', 'mac_address': '02:be:27:f2:a2:9f', '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': '55.208.109.5', 'subnet_id': '22c41bb7-4579-409b-9807-a86aad6a8a76'}], 'security_groups': ['10ab762b-f5d4-4a30-a48f-b2ffa27e8b8d'], 'admin_state_up': True, 'device_id': '', 'device_owner': '', 'status': 'DOWN', 'port_security_enabled': True, 'description': '', 'project_id': 'e455fc2cda174ce49cc1c03210aa8d16'}}
2025-08-25 02:14:32,578 - DEBUG - Response for create_port : {'port': {'name': '8131d907-fad3-421c-a706-178ddcae825f', 'id': '8131d907-fad3-421c-a706-178ddcae825f', 'tenant_id': 'e455fc2cda174ce49cc1c03210aa8d16', 'network_id': 'd02acef4-36dc-4c2f-8383-3fd490e9dc9f', 'mac_address': '02:81:31:d9:07:fa', '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': '48.121.24.133', 'subnet_id': 'f585f62b-67c2-4296-8cfa-b9692ad72e5c'}], 'security_groups': ['10ab762b-f5d4-4a30-a48f-b2ffa27e8b8d'], 'admin_state_up': True, 'device_id': '', 'device_owner': '', 'status': 'DOWN', 'port_security_enabled': True, 'description': '', 'project_id': 'e455fc2cda174ce49cc1c03210aa8d16'}}
2025-08-25 02:14:32,633 - DEBUG - Services list from nova: [, , ]
2025-08-25 02:14:32,924 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-3918-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-3918-1)
2025-08-25 02:14:33,287 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-3918-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-3918-1)
2025-08-25 02:14:33,287 - INFO - Waiting for VM ctest-vn1-vm1-16394777 to be up..
2025-08-25 02:14:33,321 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2025-08-25 02:14:38,406 - DEBUG - VM is in ACTIVE state now
2025-08-25 02:14:38,406 - INFO - VM name : ctest-vn1-vm1-16394777
2025-08-25 02:14:38,481 - DEBUG - VM ctest-vn1-vm1-16394777 ID is d54d6152-14ad-4ea3-a77b-807394388c7f
2025-08-25 02:14:38,505 - DEBUG - VM ctest-vn1-vm1-16394777 launched on Node cn-jenkins-deploy-platform-ansible-os-3918-1
2025-08-25 02:14:38,568 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine/d54d6152-14ad-4ea3-a77b-807394388c7f
2025-08-25 02:14:38,576 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine-interface/be27f2a2-9fed-4185-9979-ffa8a4ba2195
2025-08-25 02:14:41,827 - 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 1024ms')
2025-08-25 02:14:41,827 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1-vm1-16394777 failed!
2025-08-25 02:14:41,882 - DEBUG - Gateway for vn default-domain:ctest-TestPorts-25592083:ctest-vn1-37817941 is 55.208.109.1 and allocation pool is NOT set
2025-08-25 02:14:41,882 - DEBUG - Gateway for vn default-domain:ctest-TestPorts-25592083:ctest-vn1-37817941 is 48.121.24.129 and allocation pool is NOT set
2025-08-25 02:14:43,940 - 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.62 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.438 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.438/2.027/3.617/1.589 ms')
2025-08-25 02:14:43,941 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1-vm1-16394777 passed
2025-08-25 02:14:44,090 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-25 02:14:44,090 - DEBUG - Waiting to SSH to VM ctest-vn1-vm1-16394777, IP 55.208.109.5, Port 22
2025-08-25 02:14:44,265 - DEBUG - VM ctest-vn1-vm1-16394777 is ready for SSH connections
2025-08-25 02:14:44,265 - INFO - Waiting for VM ctest-vn1-vm2-09259782 to be up..
2025-08-25 02:14:44,341 - DEBUG - VM is in ACTIVE state now
2025-08-25 02:14:44,341 - INFO - VM name : ctest-vn1-vm2-09259782
2025-08-25 02:14:44,404 - DEBUG - VM ctest-vn1-vm2-09259782 ID is 516db5be-9ae6-4a7c-a8bb-1401fe7b7bf6
2025-08-25 02:14:44,404 - DEBUG - VM ctest-vn1-vm2-09259782 launched on Node cn-jenkins-deploy-platform-ansible-os-3918-1
2025-08-25 02:14:44,464 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine/516db5be-9ae6-4a7c-a8bb-1401fe7b7bf6
2025-08-25 02:14:44,471 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine-interface/8131d907-fad3-421c-a706-178ddcae825f
2025-08-25 02:14:45,698 - 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=2.52 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.362 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.362/1.441/2.520/1.079 ms')
2025-08-25 02:14:45,698 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-vn1-vm2-09259782 passed
2025-08-25 02:14:45,854 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-25 02:14:45,855 - DEBUG - Waiting to SSH to VM ctest-vn1-vm2-09259782, IP 48.121.24.133, Port 22
2025-08-25 02:14:46,018 - DEBUG - VM ctest-vn1-vm2-09259782 is ready for SSH connections
2025-08-25 02:14:46,019 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-25 02:14:46,019 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-25 02:14:46,019 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-25 02:14:46,019 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 55.208.109.5, host_string: cirros@169.254.0.4, password: cubswin:)gateway: ubuntu@10.0.0.50, gateway password: c0ntrail123
2025-08-25 02:14:46,019 - DEBUG - ping -s 56 -c 3 -W 1 55.208.109.5
2025-08-25 02:14:48,898 - DEBUG - PING 55.208.109.5 (55.208.109.5): 56 data bytes
64 bytes from 55.208.109.5: seq=0 ttl=63 time=3.969 ms
64 bytes from 55.208.109.5: seq=1 ttl=63 time=0.713 ms
64 bytes from 55.208.109.5: seq=2 ttl=63 time=0.814 ms
--- 55.208.109.5 ping statistics ---
3 packets transmitted, 3 packets received, 0% packet loss
round-trip min/avg/max = 0.713/1.832/3.969 ms
2025-08-25 02:14:48,899 - INFO - Ping to IP 55.208.109.5 from VM ctest-vn1-vm2-09259782 passed
2025-08-25 02:14:48,899 - INFO - Detaching port 8131d907-fad3-421c-a706-178ddcae825f from VM ctest-vn1-vm2-09259782
2025-08-25 02:14:48,945 - INFO - Deleting VM ctest-vn1-vm2-09259782
2025-08-25 02:14:49,011 - INFO - Detaching port be27f2a2-9fed-4185-9979-ffa8a4ba2195 from VM ctest-vn1-vm1-16394777
2025-08-25 02:14:49,049 - INFO - Deleting VM ctest-vn1-vm1-16394777
2025-08-25 02:14:49,333 - DEBUG - Response for delete_port : ()
2025-08-25 02:14:49,537 - DEBUG - Response for delete_port : ()
2025-08-25 02:14:49,537 - INFO - Deleting VN ctest-vn1-37817941
2025-08-25 02:14:49,694 - DEBUG - Response for deleting network ()
2025-08-25 02:14:49,967 - DEBUG - No XMPP flaps were noticed during the test
2025-08-25 02:14:49,967 - INFO - END TEST : test_ports_specific_subnet_ip : PASSED[0:00:18]
2025-08-25 02:14:49,967 - INFO - --------------------------------------------------------------------------------
2025-08-25 02:14:50,627 - INFO - Deleted project: ctest-TestPorts-25592083, ID : e455fc2c-da17-4ce4-9cc1-c03210aa8d16