2025-08-26 07:59:52,388 - INFO - Domain Default found not creating
2025-08-26 07:59:52,531 - INFO - Project ctest-TestPorts-75750440 not found, creating it
2025-08-26 07:59:53,000 - INFO - Created Project:ctest-TestPorts-75750440, ID : 692ea75d-f021-4763-bf86-dc12b7fd4f9d
2025-08-26 07:59:54,565 - INFO - ================================================================================
2025-08-26 07:59:54,565 - INFO - STARTING TEST : test_ports_bindings
2025-08-26 07:59:54,565 - 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-26 07:59:54,830 - DEBUG - Nothing to compare xmpp stats {'10.0.0.38': {'10.20.0.17': '0'}} with
2025-08-26 07:59:54,830 - INFO - Initial checks done. Running the testcase now
2025-08-26 07:59:54,830 - INFO -
2025-08-26 07:59:55,440 - DEBUG - Response for create_network : {'network': {'id': 'e4297b77-c0ff-497a-affa-364e1de010c3', 'name': 'ctest-vn-27856548', 'tenant_id': '692ea75df0214763bf86dc12b7fd4f9d', 'project_id': '692ea75df0214763bf86dc12b7fd4f9d', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestPorts-75750440', 'ctest-vn-27856548'], 'port_security_enabled': True, 'description': ''}}
2025-08-26 07:59:55,691 - DEBUG - Response for create_subnet : {'subnet': {'id': 'de91c2a0-6184-411f-928e-ba2639e8b5d8', 'name': '', 'tenant_id': '692ea75df0214763bf86dc12b7fd4f9d', 'network_id': 'e4297b77-c0ff-497a-affa-364e1de010c3', 'ip_version': 4, 'cidr': '37.203.30.128/26', 'allocation_pools': [{'start': '37.203.30.130', 'end': '37.203.30.190'}], 'gateway_ip': '37.203.30.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '37.203.30.130', 'tags': [], 'project_id': '692ea75df0214763bf86dc12b7fd4f9d'}}
2025-08-26 07:59:55,712 - INFO - Created VN ctest-vn-27856548
2025-08-26 07:59:55,767 - DEBUG - VN ctest-vn-27856548 UUID is e4297b77-c0ff-497a-affa-364e1de010c3
2025-08-26 07:59:55,875 - DEBUG - Read virtual machine interface {'fq_name': ['default-domain', 'ctest-TestPorts-75750440', 'ctest-vmi-96786262']}
2025-08-26 07:59:56,143 - DEBUG - Response for create_port : {'port': {'name': 'ctest-vmi-96786262', 'id': '25fe281b-cf98-4272-a1bd-3b3bb752c591', 'tenant_id': '692ea75df0214763bf86dc12b7fd4f9d', 'network_id': 'e4297b77-c0ff-497a-affa-364e1de010c3', 'mac_address': '02:25:fe:28:1b:cf', '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': '37.203.30.131', 'subnet_id': 'de91c2a0-6184-411f-928e-ba2639e8b5d8'}], 'security_groups': ['cb510d1b-35ec-4a0c-9bbf-615011a83297'], 'admin_state_up': True, 'device_id': '', 'device_owner': '', 'status': 'DOWN', 'port_security_enabled': True, 'description': '', 'project_id': '692ea75df0214763bf86dc12b7fd4f9d'}}
2025-08-26 07:59:56,143 - DEBUG - Created port 25fe281b-cf98-4272-a1bd-3b3bb752c591
2025-08-26 07:59:56,255 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/25fe281b-cf98-4272-a1bd-3b3bb752c591
2025-08-26 07:59:56,593 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/25fe281b-cf98-4272-a1bd-3b3bb752c591
2025-08-26 07:59:56,626 - INFO - VMI 25fe281b-cf98-4272-a1bd-3b3bb752c591 verification in API Server passed
2025-08-26 07:59:56,720 - DEBUG - Response for delete_port : ()
2025-08-26 07:59:56,720 - INFO - Deleted port 25fe281b-cf98-4272-a1bd-3b3bb752c591
2025-08-26 07:59:56,721 - INFO - Deleting VN ctest-vn-27856548
2025-08-26 07:59:56,857 - DEBUG - Response for deleting network ()
2025-08-26 07:59:57,116 - DEBUG - No XMPP flaps were noticed during the test
2025-08-26 07:59:57,116 - INFO - END TEST : test_ports_bindings : PASSED[0:00:03]
2025-08-26 07:59:57,116 - INFO - --------------------------------------------------------------------------------
2025-08-26 07:59:57,119 - INFO - ================================================================================
2025-08-26 07:59:57,119 - INFO - STARTING TEST : test_ports_specific_subnet_ip
2025-08-26 07:59:57,119 - 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-26 07:59:57,406 - DEBUG - Nothing to compare xmpp stats {'10.0.0.38': {'10.20.0.17': '0'}} with
2025-08-26 07:59:57,406 - INFO - Initial checks done. Running the testcase now
2025-08-26 07:59:57,406 - INFO -
2025-08-26 07:59:57,597 - DEBUG - Response for create_network : {'network': {'id': 'a82233a1-1d11-4eb3-bdc1-79f0c7db2490', 'name': 'ctest-vn1-18961587', 'tenant_id': '692ea75df0214763bf86dc12b7fd4f9d', 'project_id': '692ea75df0214763bf86dc12b7fd4f9d', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestPorts-75750440', 'ctest-vn1-18961587'], 'port_security_enabled': True, 'description': ''}}
2025-08-26 07:59:57,778 - DEBUG - Response for create_subnet : {'subnet': {'id': '7d01547b-b06a-4d5f-8eb7-8b3fca3b1268', 'name': '', 'tenant_id': '692ea75df0214763bf86dc12b7fd4f9d', 'network_id': 'a82233a1-1d11-4eb3-bdc1-79f0c7db2490', 'ip_version': 4, 'cidr': '160.114.1.192/26', 'allocation_pools': [{'start': '160.114.1.194', 'end': '160.114.1.254'}], 'gateway_ip': '160.114.1.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '160.114.1.194', 'tags': [], 'project_id': '692ea75df0214763bf86dc12b7fd4f9d'}}
2025-08-26 07:59:57,935 - DEBUG - Response for create_subnet : {'subnet': {'id': '371a2f34-39b7-49da-b29b-34d293e7f8d4', 'name': '', 'tenant_id': '692ea75df0214763bf86dc12b7fd4f9d', 'network_id': 'a82233a1-1d11-4eb3-bdc1-79f0c7db2490', 'ip_version': 4, 'cidr': '196.19.255.128/26', 'allocation_pools': [{'start': '196.19.255.130', 'end': '196.19.255.190'}], 'gateway_ip': '196.19.255.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '196.19.255.130', 'tags': [], 'project_id': '692ea75df0214763bf86dc12b7fd4f9d'}}
2025-08-26 07:59:57,956 - INFO - Created VN ctest-vn1-18961587
2025-08-26 07:59:58,015 - DEBUG - VN ctest-vn1-18961587 UUID is a82233a1-1d11-4eb3-bdc1-79f0c7db2490
2025-08-26 07:59:58,348 - DEBUG - Response for create_port : {'port': {'name': '82520d22-dac3-4c05-a906-9eff6dc00ee0', 'id': '82520d22-dac3-4c05-a906-9eff6dc00ee0', 'tenant_id': '692ea75df0214763bf86dc12b7fd4f9d', 'network_id': 'a82233a1-1d11-4eb3-bdc1-79f0c7db2490', 'mac_address': '02:82:52:0d:22:da', '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': '160.114.1.197', 'subnet_id': '7d01547b-b06a-4d5f-8eb7-8b3fca3b1268'}], 'security_groups': ['cb510d1b-35ec-4a0c-9bbf-615011a83297'], 'admin_state_up': True, 'device_id': '', 'device_owner': '', 'status': 'DOWN', 'port_security_enabled': True, 'description': '', 'project_id': '692ea75df0214763bf86dc12b7fd4f9d'}}
2025-08-26 07:59:58,560 - DEBUG - Response for create_port : {'port': {'name': 'fed7be46-51db-47ba-bdd7-70d37587d41c', 'id': 'fed7be46-51db-47ba-bdd7-70d37587d41c', 'tenant_id': '692ea75df0214763bf86dc12b7fd4f9d', 'network_id': 'a82233a1-1d11-4eb3-bdc1-79f0c7db2490', 'mac_address': '02:fe:d7:be:46:51', '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.19.255.133', 'subnet_id': '371a2f34-39b7-49da-b29b-34d293e7f8d4'}], 'security_groups': ['cb510d1b-35ec-4a0c-9bbf-615011a83297'], 'admin_state_up': True, 'device_id': '', 'device_owner': '', 'status': 'DOWN', 'port_security_enabled': True, 'description': '', 'project_id': '692ea75df0214763bf86dc12b7fd4f9d'}}
2025-08-26 07:59:58,616 - DEBUG - Services list from nova: [, , ]
2025-08-26 07:59:58,940 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-3936-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-3936-1)
2025-08-26 07:59:59,347 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-3936-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-3936-1)
2025-08-26 07:59:59,348 - INFO - Waiting for VM ctest-vn1-vm1-67534234 to be up..
2025-08-26 07:59:59,389 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2025-08-26 08:00:04,491 - DEBUG - VM is in ACTIVE state now
2025-08-26 08:00:04,491 - INFO - VM name : ctest-vn1-vm1-67534234
2025-08-26 08:00:04,583 - DEBUG - VM ctest-vn1-vm1-67534234 ID is ea6cb4d5-77e7-4187-b36b-595b09fffe1c
2025-08-26 08:00:04,620 - DEBUG - VM ctest-vn1-vm1-67534234 launched on Node cn-jenkins-deploy-platform-ansible-os-3936-1
2025-08-26 08:00:04,721 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/ea6cb4d5-77e7-4187-b36b-595b09fffe1c
2025-08-26 08:00:04,731 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/82520d22-dac3-4c05-a906-9eff6dc00ee0
2025-08-26 08:00:08,027 - 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-08-26 08:00:08,027 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1-vm1-67534234 failed!
2025-08-26 08:00:08,087 - DEBUG - Gateway for vn default-domain:ctest-TestPorts-75750440:ctest-vn1-18961587 is 160.114.1.193 and allocation pool is NOT set
2025-08-26 08:00:08,087 - DEBUG - Gateway for vn default-domain:ctest-TestPorts-75750440:ctest-vn1-18961587 is 196.19.255.129 and allocation pool is NOT set
2025-08-26 08:00:12,151 - 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.93 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 1 received, 50% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 7.927/7.927/7.927/0.000 ms')
2025-08-26 08:00:12,151 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1-vm1-67534234 passed
2025-08-26 08:00:12,307 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-26 08:00:12,307 - DEBUG - Waiting to SSH to VM ctest-vn1-vm1-67534234, IP 160.114.1.197, Port 22
2025-08-26 08:00:12,462 - DEBUG - VM ctest-vn1-vm1-67534234 is ready for SSH connections
2025-08-26 08:00:12,462 - INFO - Waiting for VM ctest-vn1-vm2-42816366 to be up..
2025-08-26 08:00:12,541 - DEBUG - VM is in ACTIVE state now
2025-08-26 08:00:12,541 - INFO - VM name : ctest-vn1-vm2-42816366
2025-08-26 08:00:12,615 - DEBUG - VM ctest-vn1-vm2-42816366 ID is 95ad712c-cb47-4d06-b777-bd210737990a
2025-08-26 08:00:12,616 - DEBUG - VM ctest-vn1-vm2-42816366 launched on Node cn-jenkins-deploy-platform-ansible-os-3936-1
2025-08-26 08:00:12,688 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/95ad712c-cb47-4d06-b777-bd210737990a
2025-08-26 08:00:12,698 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/fed7be46-51db-47ba-bdd7-70d37587d41c
2025-08-26 08:00:13,966 - 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.09 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.396 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.396/1.743/3.090/1.347 ms')
2025-08-26 08:00:13,966 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-vn1-vm2-42816366 passed
2025-08-26 08:00:14,123 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-26 08:00:14,123 - DEBUG - Waiting to SSH to VM ctest-vn1-vm2-42816366, IP 196.19.255.133, Port 22
2025-08-26 08:00:14,282 - DEBUG - VM ctest-vn1-vm2-42816366 is ready for SSH connections
2025-08-26 08:00:14,282 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-26 08:00:14,283 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-26 08:00:14,283 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-26 08:00:14,283 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 160.114.1.197, host_string: cirros@169.254.0.4, password: cubswin:)gateway: ubuntu@10.0.0.38, gateway password: c0ntrail123
2025-08-26 08:00:14,283 - DEBUG - ping -s 56 -c 3 -W 1 160.114.1.197
2025-08-26 08:00:16,946 - DEBUG - PING 160.114.1.197 (160.114.1.197): 56 data bytes
64 bytes from 160.114.1.197: seq=0 ttl=63 time=3.738 ms
64 bytes from 160.114.1.197: seq=1 ttl=63 time=0.890 ms
64 bytes from 160.114.1.197: seq=2 ttl=63 time=1.052 ms
--- 160.114.1.197 ping statistics ---
3 packets transmitted, 3 packets received, 0% packet loss
round-trip min/avg/max = 0.890/1.893/3.738 ms
2025-08-26 08:00:16,946 - INFO - Ping to IP 160.114.1.197 from VM ctest-vn1-vm2-42816366 passed
2025-08-26 08:00:16,946 - INFO - Detaching port fed7be46-51db-47ba-bdd7-70d37587d41c from VM ctest-vn1-vm2-42816366
2025-08-26 08:00:16,998 - INFO - Deleting VM ctest-vn1-vm2-42816366
2025-08-26 08:00:17,078 - INFO - Detaching port 82520d22-dac3-4c05-a906-9eff6dc00ee0 from VM ctest-vn1-vm1-67534234
2025-08-26 08:00:17,112 - INFO - Deleting VM ctest-vn1-vm1-67534234
2025-08-26 08:00:17,381 - DEBUG - Response for delete_port : ()
2025-08-26 08:00:17,607 - DEBUG - Response for delete_port : ()
2025-08-26 08:00:17,607 - INFO - Deleting VN ctest-vn1-18961587
2025-08-26 08:00:17,770 - DEBUG - Response for deleting network ()
2025-08-26 08:00:18,034 - DEBUG - No XMPP flaps were noticed during the test
2025-08-26 08:00:18,034 - INFO - END TEST : test_ports_specific_subnet_ip : PASSED[0:00:21]
2025-08-26 08:00:18,034 - INFO - --------------------------------------------------------------------------------
2025-08-26 08:00:18,804 - INFO - Deleted project: ctest-TestPorts-75750440, ID : 692ea75d-f021-4763-bf86-dc12b7fd4f9d