2025-10-08 16:46:50,461 - INFO - Domain Default found not creating
2025-10-08 16:46:50,630 - INFO - Project ctest-TestPorts-63072309 not found, creating it
2025-10-08 16:46:51,105 - INFO - Created Project:ctest-TestPorts-63072309, ID : bc299345-365c-4502-970d-3feacd46b5e9
2025-10-08 16:46:52,635 - INFO - ================================================================================
2025-10-08 16:46:52,635 - INFO - STARTING TEST : test_ports_bindings
2025-10-08 16:46:52,635 - 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-08 16:46:52,893 - DEBUG - Nothing to compare xmpp stats {'10.0.0.22': {'10.20.0.209': '0'}} with
2025-10-08 16:46:52,893 - INFO - Initial checks done. Running the testcase now
2025-10-08 16:46:52,893 - INFO -
2025-10-08 16:46:53,439 - DEBUG - Response for create_network : {'network': {'id': '5aac7c7c-3eb8-407f-8a3d-9f1b3fae2338', 'name': 'ctest-vn-97676662', 'tenant_id': 'bc299345365c4502970d3feacd46b5e9', 'project_id': 'bc299345365c4502970d3feacd46b5e9', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestPorts-63072309', 'ctest-vn-97676662'], 'port_security_enabled': True, 'description': ''}}
2025-10-08 16:46:53,634 - DEBUG - Response for create_subnet : {'subnet': {'id': 'b4b32a96-39ca-4506-a35a-b2dc4bc61b8d', 'name': '', 'tenant_id': 'bc299345365c4502970d3feacd46b5e9', 'network_id': '5aac7c7c-3eb8-407f-8a3d-9f1b3fae2338', 'ip_version': 4, 'cidr': '76.91.160.192/26', 'allocation_pools': [{'start': '76.91.160.194', 'end': '76.91.160.254'}], 'gateway_ip': '76.91.160.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '76.91.160.194', 'tags': [], 'project_id': 'bc299345365c4502970d3feacd46b5e9'}}
2025-10-08 16:46:53,652 - INFO - Created VN ctest-vn-97676662
2025-10-08 16:46:53,706 - DEBUG - VN ctest-vn-97676662 UUID is 5aac7c7c-3eb8-407f-8a3d-9f1b3fae2338
2025-10-08 16:46:53,818 - DEBUG - Read virtual machine interface {'fq_name': ['default-domain', 'ctest-TestPorts-63072309', 'ctest-vmi-94140789']}
2025-10-08 16:46:54,070 - DEBUG - Response for create_port : {'port': {'name': 'ctest-vmi-94140789', 'id': 'a6194db5-5e8d-44d1-ae68-3e27b77fdef3', 'tenant_id': 'bc299345365c4502970d3feacd46b5e9', 'network_id': '5aac7c7c-3eb8-407f-8a3d-9f1b3fae2338', 'mac_address': '02:a6:19:4d:b5:5e', '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': '76.91.160.195', 'subnet_id': 'b4b32a96-39ca-4506-a35a-b2dc4bc61b8d'}], 'security_groups': ['9be225b2-5129-41a9-95aa-471e52398acf'], 'admin_state_up': True, 'device_id': '', 'device_owner': '', 'status': 'DOWN', 'port_security_enabled': True, 'description': '', 'project_id': 'bc299345365c4502970d3feacd46b5e9'}}
2025-10-08 16:46:54,070 - DEBUG - Created port a6194db5-5e8d-44d1-ae68-3e27b77fdef3
2025-10-08 16:46:54,182 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-machine-interface/a6194db5-5e8d-44d1-ae68-3e27b77fdef3
2025-10-08 16:46:54,474 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-machine-interface/a6194db5-5e8d-44d1-ae68-3e27b77fdef3
2025-10-08 16:46:54,508 - INFO - VMI a6194db5-5e8d-44d1-ae68-3e27b77fdef3 verification in API Server passed
2025-10-08 16:46:54,599 - DEBUG - Response for delete_port : ()
2025-10-08 16:46:54,599 - INFO - Deleted port a6194db5-5e8d-44d1-ae68-3e27b77fdef3
2025-10-08 16:46:54,600 - INFO - Deleting VN ctest-vn-97676662
2025-10-08 16:46:54,695 - DEBUG - Response for deleting network ()
2025-10-08 16:46:54,947 - DEBUG - No XMPP flaps were noticed during the test
2025-10-08 16:46:54,947 - INFO - END TEST : test_ports_bindings : PASSED[0:00:02]
2025-10-08 16:46:54,947 - INFO - --------------------------------------------------------------------------------
2025-10-08 16:46:54,950 - INFO - ================================================================================
2025-10-08 16:46:54,950 - INFO - STARTING TEST : test_ports_specific_subnet_ip
2025-10-08 16:46:54,950 - 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-08 16:46:55,235 - DEBUG - Nothing to compare xmpp stats {'10.0.0.22': {'10.20.0.209': '0'}} with
2025-10-08 16:46:55,235 - INFO - Initial checks done. Running the testcase now
2025-10-08 16:46:55,235 - INFO -
2025-10-08 16:46:55,406 - DEBUG - Response for create_network : {'network': {'id': 'ed45015b-21ed-4807-bbda-17c8e9f1931a', 'name': 'ctest-vn1-61696856', 'tenant_id': 'bc299345365c4502970d3feacd46b5e9', 'project_id': 'bc299345365c4502970d3feacd46b5e9', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestPorts-63072309', 'ctest-vn1-61696856'], 'port_security_enabled': True, 'description': ''}}
2025-10-08 16:46:55,600 - DEBUG - Response for create_subnet : {'subnet': {'id': '58480a6e-3715-466c-9a75-b775c224ea84', 'name': '', 'tenant_id': 'bc299345365c4502970d3feacd46b5e9', 'network_id': 'ed45015b-21ed-4807-bbda-17c8e9f1931a', 'ip_version': 4, 'cidr': '215.72.223.64/26', 'allocation_pools': [{'start': '215.72.223.66', 'end': '215.72.223.126'}], 'gateway_ip': '215.72.223.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '215.72.223.66', 'tags': [], 'project_id': 'bc299345365c4502970d3feacd46b5e9'}}
2025-10-08 16:46:55,767 - DEBUG - Response for create_subnet : {'subnet': {'id': '078ef4e6-92ac-4913-a3b5-e242b46f249d', 'name': '', 'tenant_id': 'bc299345365c4502970d3feacd46b5e9', 'network_id': 'ed45015b-21ed-4807-bbda-17c8e9f1931a', 'ip_version': 4, 'cidr': '148.73.133.128/26', 'allocation_pools': [{'start': '148.73.133.130', 'end': '148.73.133.190'}], 'gateway_ip': '148.73.133.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '148.73.133.130', 'tags': [], 'project_id': 'bc299345365c4502970d3feacd46b5e9'}}
2025-10-08 16:46:55,788 - INFO - Created VN ctest-vn1-61696856
2025-10-08 16:46:55,842 - DEBUG - VN ctest-vn1-61696856 UUID is ed45015b-21ed-4807-bbda-17c8e9f1931a
2025-10-08 16:46:56,160 - DEBUG - Response for create_port : {'port': {'name': 'b344d747-d0c9-492f-b860-b6c92362e6e5', 'id': 'b344d747-d0c9-492f-b860-b6c92362e6e5', 'tenant_id': 'bc299345365c4502970d3feacd46b5e9', 'network_id': 'ed45015b-21ed-4807-bbda-17c8e9f1931a', 'mac_address': '02:b3:44:d7:47:d0', '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': '215.72.223.69', 'subnet_id': '58480a6e-3715-466c-9a75-b775c224ea84'}], 'security_groups': ['9be225b2-5129-41a9-95aa-471e52398acf'], 'admin_state_up': True, 'device_id': '', 'device_owner': '', 'status': 'DOWN', 'port_security_enabled': True, 'description': '', 'project_id': 'bc299345365c4502970d3feacd46b5e9'}}
2025-10-08 16:46:56,329 - DEBUG - Response for create_port : {'port': {'name': '17c4a3b0-ad5b-40af-9b6f-df1c1db5fbf7', 'id': '17c4a3b0-ad5b-40af-9b6f-df1c1db5fbf7', 'tenant_id': 'bc299345365c4502970d3feacd46b5e9', 'network_id': 'ed45015b-21ed-4807-bbda-17c8e9f1931a', 'mac_address': '02:17:c4:a3:b0:ad', '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': '148.73.133.133', 'subnet_id': '078ef4e6-92ac-4913-a3b5-e242b46f249d'}], 'security_groups': ['9be225b2-5129-41a9-95aa-471e52398acf'], 'admin_state_up': True, 'device_id': '', 'device_owner': '', 'status': 'DOWN', 'port_security_enabled': True, 'description': '', 'project_id': 'bc299345365c4502970d3feacd46b5e9'}}
2025-10-08 16:46:56,388 - DEBUG - Services list from nova: [, , ]
2025-10-08 16:46:56,744 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-4240-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-4240-1)
2025-10-08 16:46:57,146 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-4240-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-4240-1)
2025-10-08 16:46:57,146 - INFO - Waiting for VM ctest-vn1-vm1-32230766 to be up..
2025-10-08 16:46:57,190 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2025-10-08 16:47:02,279 - DEBUG - VM is in ACTIVE state now
2025-10-08 16:47:02,279 - INFO - VM name : ctest-vn1-vm1-32230766
2025-10-08 16:47:02,361 - DEBUG - VM ctest-vn1-vm1-32230766 ID is fbf2c378-6afb-455a-8352-d07392c64bbf
2025-10-08 16:47:02,383 - DEBUG - VM ctest-vn1-vm1-32230766 launched on Node cn-jenkins-deploy-platform-ansible-os-4240-1
2025-10-08 16:47:02,473 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-machine/fbf2c378-6afb-455a-8352-d07392c64bbf
2025-10-08 16:47:02,484 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-machine-interface/b344d747-d0c9-492f-b860-b6c92362e6e5
2025-10-08 16:47:05,746 - 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-10-08 16:47:05,746 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1-vm1-32230766 failed!
2025-10-08 16:47:05,802 - DEBUG - Gateway for vn default-domain:ctest-TestPorts-63072309:ctest-vn1-61696856 is 215.72.223.65 and allocation pool is NOT set
2025-10-08 16:47:05,802 - DEBUG - Gateway for vn default-domain:ctest-TestPorts-63072309:ctest-vn1-61696856 is 148.73.133.129 and allocation pool is NOT set
2025-10-08 16:47:09,873 - 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=7.69 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 1 received, 50% packet loss, time 1007ms\r\nrtt min/avg/max/mdev = 7.689/7.689/7.689/0.000 ms')
2025-10-08 16:47:09,873 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1-vm1-32230766 passed
2025-10-08 16:47:10,022 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-08 16:47:10,022 - DEBUG - Waiting to SSH to VM ctest-vn1-vm1-32230766, IP 215.72.223.69, Port 22
2025-10-08 16:47:10,174 - DEBUG - VM ctest-vn1-vm1-32230766 is ready for SSH connections
2025-10-08 16:47:10,174 - INFO - Waiting for VM ctest-vn1-vm2-07025339 to be up..
2025-10-08 16:47:10,257 - DEBUG - VM is in ACTIVE state now
2025-10-08 16:47:10,257 - INFO - VM name : ctest-vn1-vm2-07025339
2025-10-08 16:47:10,331 - DEBUG - VM ctest-vn1-vm2-07025339 ID is 560cbcc1-0918-4cdc-ba35-1d972eb3581f
2025-10-08 16:47:10,332 - DEBUG - VM ctest-vn1-vm2-07025339 launched on Node cn-jenkins-deploy-platform-ansible-os-4240-1
2025-10-08 16:47:10,407 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-machine/560cbcc1-0918-4cdc-ba35-1d972eb3581f
2025-10-08 16:47:10,416 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-machine-interface/17c4a3b0-ad5b-40af-9b6f-df1c1db5fbf7
2025-10-08 16:47:11,656 - 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.10 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.557 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.557/1.830/3.103/1.273 ms')
2025-10-08 16:47:11,656 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-vn1-vm2-07025339 passed
2025-10-08 16:47:11,810 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-08 16:47:11,810 - DEBUG - Waiting to SSH to VM ctest-vn1-vm2-07025339, IP 148.73.133.133, Port 22
2025-10-08 16:47:11,969 - DEBUG - VM ctest-vn1-vm2-07025339 is ready for SSH connections
2025-10-08 16:47:11,970 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-08 16:47:11,970 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-08 16:47:11,970 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-08 16:47:11,970 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 215.72.223.69, host_string: cirros@169.254.0.4, password: cubswin:)gateway: ubuntu@10.0.0.22, gateway password: c0ntrail123
2025-10-08 16:47:11,970 - DEBUG - ping -s 56 -c 3 -W 1 215.72.223.69
2025-10-08 16:47:14,633 - DEBUG - PING 215.72.223.69 (215.72.223.69): 56 data bytes
64 bytes from 215.72.223.69: seq=0 ttl=63 time=3.840 ms
64 bytes from 215.72.223.69: seq=1 ttl=63 time=1.181 ms
64 bytes from 215.72.223.69: seq=2 ttl=63 time=0.903 ms
--- 215.72.223.69 ping statistics ---
3 packets transmitted, 3 packets received, 0% packet loss
round-trip min/avg/max = 0.903/1.974/3.840 ms
2025-10-08 16:47:14,633 - INFO - Ping to IP 215.72.223.69 from VM ctest-vn1-vm2-07025339 passed
2025-10-08 16:47:14,633 - INFO - Detaching port 17c4a3b0-ad5b-40af-9b6f-df1c1db5fbf7 from VM ctest-vn1-vm2-07025339
2025-10-08 16:47:14,681 - INFO - Deleting VM ctest-vn1-vm2-07025339
2025-10-08 16:47:14,750 - INFO - Detaching port b344d747-d0c9-492f-b860-b6c92362e6e5 from VM ctest-vn1-vm1-32230766
2025-10-08 16:47:14,784 - INFO - Deleting VM ctest-vn1-vm1-32230766
2025-10-08 16:47:15,037 - DEBUG - Response for delete_port : ()
2025-10-08 16:47:15,222 - DEBUG - Response for delete_port : ()
2025-10-08 16:47:15,222 - INFO - Deleting VN ctest-vn1-61696856
2025-10-08 16:47:15,354 - DEBUG - Response for deleting network ()
2025-10-08 16:47:15,615 - DEBUG - No XMPP flaps were noticed during the test
2025-10-08 16:47:15,616 - INFO - END TEST : test_ports_specific_subnet_ip : PASSED[0:00:21]
2025-10-08 16:47:15,616 - INFO - --------------------------------------------------------------------------------
2025-10-08 16:47:16,340 - INFO - Deleted project: ctest-TestPorts-63072309, ID : bc299345-365c-4502-970d-3feacd46b5e9