2026-09-02 02:58:19,839 - INFO - Domain Default found not creating
2026-09-02 02:58:20,015 - INFO - Project ctest-TestPorts-58001544 not found, creating it
2026-09-02 02:58:20,502 - INFO - Created Project:ctest-TestPorts-58001544, ID : 02938dd7-31f7-4d84-ad81-52cbc6b22a2d
2026-09-02 02:58:22,061 - INFO - ================================================================================
2026-09-02 02:58:22,061 - INFO - STARTING TEST : test_ports_bindings
2026-09-02 02:58:22,061 - 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
2026-09-02 02:58:22,319 - DEBUG - Nothing to compare xmpp stats {'10.0.0.248': {'10.20.0.17': '0'}} with
2026-09-02 02:58:22,319 - INFO - Initial checks done. Running the testcase now
2026-09-02 02:58:22,320 - INFO -
2026-09-02 02:58:22,943 - DEBUG - Response for create_network : {'network': {'id': '58ca3c7f-14c0-49d1-9ed0-02ea2c7e7274', 'name': 'ctest-vn-28644965', 'tenant_id': '02938dd731f74d84ad8152cbc6b22a2d', 'project_id': '02938dd731f74d84ad8152cbc6b22a2d', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'tags': [], 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestPorts-58001544', 'ctest-vn-28644965'], 'port_security_enabled': True, 'description': ''}}
2026-09-02 02:58:23,152 - DEBUG - Response for create_subnet : {'subnet': {'id': 'a515f134-ae7d-4185-b767-d7e7c8063fa2', 'name': '', 'tenant_id': '02938dd731f74d84ad8152cbc6b22a2d', 'network_id': '58ca3c7f-14c0-49d1-9ed0-02ea2c7e7274', 'ip_version': 4, 'cidr': '167.186.37.192/26', 'allocation_pools': [{'start': '167.186.37.194', 'end': '167.186.37.254'}], 'gateway_ip': '167.186.37.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '167.186.37.194', 'tags': [], 'project_id': '02938dd731f74d84ad8152cbc6b22a2d'}}
2026-09-02 02:58:23,171 - INFO - Created VN ctest-vn-28644965
2026-09-02 02:58:23,226 - DEBUG - VN ctest-vn-28644965 UUID is 58ca3c7f-14c0-49d1-9ed0-02ea2c7e7274
2026-09-02 02:58:23,333 - DEBUG - Read virtual machine interface {'fq_name': ['default-domain', 'ctest-TestPorts-58001544', 'ctest-vmi-38702513']}
2026-09-02 02:58:23,607 - DEBUG - Response for create_port : {'port': {'name': 'ctest-vmi-38702513', 'id': 'effbc513-36d2-4b74-b103-12309e90344b', 'tenant_id': '02938dd731f74d84ad8152cbc6b22a2d', 'network_id': '58ca3c7f-14c0-49d1-9ed0-02ea2c7e7274', 'mac_address': '02:ef:fb:c5:13:36', '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': '167.186.37.195', 'subnet_id': 'a515f134-ae7d-4185-b767-d7e7c8063fa2'}], 'security_groups': ['51889f04-a5c0-4996-b930-599475d6a78d'], 'admin_state_up': True, 'device_id': '', 'device_owner': '', 'status': 'DOWN', 'tags': [], 'port_security_enabled': True, 'description': '', 'project_id': '02938dd731f74d84ad8152cbc6b22a2d'}}
2026-09-02 02:58:23,607 - DEBUG - Created port effbc513-36d2-4b74-b103-12309e90344b
2026-09-02 02:58:23,714 - DEBUG - Requesting: http://10.0.0.248:8082/virtual-machine-interface/effbc513-36d2-4b74-b103-12309e90344b
2026-09-02 02:58:24,040 - DEBUG - Requesting: http://10.0.0.248:8082/virtual-machine-interface/effbc513-36d2-4b74-b103-12309e90344b
2026-09-02 02:58:24,078 - INFO - VMI effbc513-36d2-4b74-b103-12309e90344b verification in API Server passed
2026-09-02 02:58:24,198 - DEBUG - Response for delete_port : ()
2026-09-02 02:58:24,198 - INFO - Deleted port effbc513-36d2-4b74-b103-12309e90344b
2026-09-02 02:58:24,198 - INFO - Deleting VN ctest-vn-28644965
2026-09-02 02:58:24,320 - DEBUG - Response for deleting network ()
2026-09-02 02:58:24,581 - DEBUG - No XMPP flaps were noticed during the test
2026-09-02 02:58:24,581 - INFO - END TEST : test_ports_bindings : PASSED[0:00:02]
2026-09-02 02:58:24,581 - INFO - --------------------------------------------------------------------------------
2026-09-02 02:58:24,584 - INFO - ================================================================================
2026-09-02 02:58:24,584 - INFO - STARTING TEST : test_ports_specific_subnet_ip
2026-09-02 02:58:24,584 - 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
2026-09-02 02:58:24,869 - DEBUG - Nothing to compare xmpp stats {'10.0.0.248': {'10.20.0.17': '0'}} with
2026-09-02 02:58:24,869 - INFO - Initial checks done. Running the testcase now
2026-09-02 02:58:24,869 - INFO -
2026-09-02 02:58:25,087 - DEBUG - Response for create_network : {'network': {'id': '81f11c5f-6591-4161-bcb5-fe8263dd5293', 'name': 'ctest-vn1-19495258', 'tenant_id': '02938dd731f74d84ad8152cbc6b22a2d', 'project_id': '02938dd731f74d84ad8152cbc6b22a2d', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'tags': [], 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestPorts-58001544', 'ctest-vn1-19495258'], 'port_security_enabled': True, 'description': ''}}
2026-09-02 02:58:25,327 - DEBUG - Response for create_subnet : {'subnet': {'id': 'cf80b1e1-644d-4733-b3c0-315bcc4081e8', 'name': '', 'tenant_id': '02938dd731f74d84ad8152cbc6b22a2d', 'network_id': '81f11c5f-6591-4161-bcb5-fe8263dd5293', 'ip_version': 4, 'cidr': '99.127.58.128/26', 'allocation_pools': [{'start': '99.127.58.130', 'end': '99.127.58.190'}], 'gateway_ip': '99.127.58.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '99.127.58.130', 'tags': [], 'project_id': '02938dd731f74d84ad8152cbc6b22a2d'}}
2026-09-02 02:58:25,532 - DEBUG - Response for create_subnet : {'subnet': {'id': '4838ff5e-7bdd-4205-884c-2db269ec1dbf', 'name': '', 'tenant_id': '02938dd731f74d84ad8152cbc6b22a2d', 'network_id': '81f11c5f-6591-4161-bcb5-fe8263dd5293', 'ip_version': 4, 'cidr': '191.114.78.0/26', 'allocation_pools': [{'start': '191.114.78.2', 'end': '191.114.78.62'}], 'gateway_ip': '191.114.78.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '191.114.78.2', 'tags': [], 'project_id': '02938dd731f74d84ad8152cbc6b22a2d'}}
2026-09-02 02:58:25,552 - INFO - Created VN ctest-vn1-19495258
2026-09-02 02:58:25,606 - DEBUG - VN ctest-vn1-19495258 UUID is 81f11c5f-6591-4161-bcb5-fe8263dd5293
2026-09-02 02:58:25,939 - DEBUG - Response for create_port : {'port': {'name': 'dc588987-b9eb-4ce0-95dc-f618eabd5d69', 'id': 'dc588987-b9eb-4ce0-95dc-f618eabd5d69', 'tenant_id': '02938dd731f74d84ad8152cbc6b22a2d', 'network_id': '81f11c5f-6591-4161-bcb5-fe8263dd5293', 'mac_address': '02:dc:58:89:87:b9', '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': '99.127.58.133', 'subnet_id': 'cf80b1e1-644d-4733-b3c0-315bcc4081e8'}], 'security_groups': ['51889f04-a5c0-4996-b930-599475d6a78d'], 'admin_state_up': True, 'device_id': '', 'device_owner': '', 'status': 'DOWN', 'tags': [], 'port_security_enabled': True, 'description': '', 'project_id': '02938dd731f74d84ad8152cbc6b22a2d'}}
2026-09-02 02:58:26,144 - DEBUG - Response for create_port : {'port': {'name': '3b267fc2-5112-4d62-9062-f11c8d073e70', 'id': '3b267fc2-5112-4d62-9062-f11c8d073e70', 'tenant_id': '02938dd731f74d84ad8152cbc6b22a2d', 'network_id': '81f11c5f-6591-4161-bcb5-fe8263dd5293', 'mac_address': '02:3b:26:7f:c2: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': '191.114.78.5', 'subnet_id': '4838ff5e-7bdd-4205-884c-2db269ec1dbf'}], 'security_groups': ['51889f04-a5c0-4996-b930-599475d6a78d'], 'admin_state_up': True, 'device_id': '', 'device_owner': '', 'status': 'DOWN', 'tags': [], 'port_security_enabled': True, 'description': '', 'project_id': '02938dd731f74d84ad8152cbc6b22a2d'}}
2026-09-02 02:58:26,206 - DEBUG - Services list from nova: [, , ]
2026-09-02 02:58:26,559 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-6244-30), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-6244-30)
2026-09-02 02:58:27,025 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-6244-30), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-6244-30)
2026-09-02 02:58:27,025 - INFO - Waiting for VM ctest-vn1-vm1-64475917 to be up..
2026-09-02 02:58:27,066 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2026-09-02 02:58:32,121 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2026-09-02 02:58:37,200 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2026-09-02 02:58:42,277 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2026-09-02 02:58:47,356 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2026-09-02 02:58:52,442 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2026-09-02 02:58:57,519 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2026-09-02 02:59:02,609 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2026-09-02 02:59:07,686 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2026-09-02 02:59:12,767 - DEBUG - VM is in ACTIVE state now
2026-09-02 02:59:12,767 - INFO - VM name : ctest-vn1-vm1-64475917
2026-09-02 02:59:12,839 - DEBUG - VM ctest-vn1-vm1-64475917 ID is 24a52de7-a911-47e0-952a-1f82fe29b3f6
2026-09-02 02:59:12,860 - DEBUG - VM ctest-vn1-vm1-64475917 launched on Node cn-jenkins-deploy-platform-ansible-os-6244-30
2026-09-02 02:59:12,942 - DEBUG - Requesting: http://10.0.0.248:8082/virtual-machine/24a52de7-a911-47e0-952a-1f82fe29b3f6
2026-09-02 02:59:12,952 - DEBUG - Requesting: http://10.0.0.248:8082/virtual-machine-interface/dc588987-b9eb-4ce0-95dc-f618eabd5d69
2026-09-02 02:59:14,195 - 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=9.06 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.563 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.563/4.812/9.062/4.249 ms')
2026-09-02 02:59:14,195 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-vn1-vm1-64475917 passed
2026-09-02 02:59:14,346 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-09-02 02:59:14,346 - DEBUG - Waiting to SSH to VM ctest-vn1-vm1-64475917, IP 99.127.58.133, Port 22
2026-09-02 02:59:14,501 - DEBUG - VM ctest-vn1-vm1-64475917 is ready for SSH connections
2026-09-02 02:59:14,501 - INFO - Waiting for VM ctest-vn1-vm2-67483920 to be up..
2026-09-02 02:59:14,582 - DEBUG - VM is in ACTIVE state now
2026-09-02 02:59:14,582 - INFO - VM name : ctest-vn1-vm2-67483920
2026-09-02 02:59:14,657 - DEBUG - VM ctest-vn1-vm2-67483920 ID is e4dbf4b5-f61a-4e4b-acc7-7dd7461ab17e
2026-09-02 02:59:14,657 - DEBUG - VM ctest-vn1-vm2-67483920 launched on Node cn-jenkins-deploy-platform-ansible-os-6244-30
2026-09-02 02:59:14,729 - DEBUG - Requesting: http://10.0.0.248:8082/virtual-machine/e4dbf4b5-f61a-4e4b-acc7-7dd7461ab17e
2026-09-02 02:59:14,738 - DEBUG - Requesting: http://10.0.0.248:8082/virtual-machine-interface/3b267fc2-5112-4d62-9062-f11c8d073e70
2026-09-02 02:59:15,988 - 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.47 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.586 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.586/2.029/3.473/1.443 ms')
2026-09-02 02:59:15,988 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1-vm2-67483920 passed
2026-09-02 02:59:16,142 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-09-02 02:59:16,142 - DEBUG - Waiting to SSH to VM ctest-vn1-vm2-67483920, IP 191.114.78.5, Port 22
2026-09-02 02:59:16,308 - DEBUG - VM ctest-vn1-vm2-67483920 is ready for SSH connections
2026-09-02 02:59:16,308 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-09-02 02:59:16,308 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-09-02 02:59:16,308 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-09-02 02:59:16,308 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 99.127.58.133, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.248, gateway password: c0ntrail123
2026-09-02 02:59:16,308 - DEBUG - ping -s 56 -c 3 -W 1 99.127.58.133
2026-09-02 02:59:20,284 - DEBUG - PING 99.127.58.133 (99.127.58.133): 56 data bytes
64 bytes from 99.127.58.133: seq=0 ttl=63 time=4.869 ms
64 bytes from 99.127.58.133: seq=1 ttl=63 time=1.329 ms
64 bytes from 99.127.58.133: seq=2 ttl=63 time=1.174 ms
--- 99.127.58.133 ping statistics ---
3 packets transmitted, 3 packets received, 0% packet loss
round-trip min/avg/max = 1.174/2.457/4.869 ms
2026-09-02 02:59:20,285 - INFO - Ping to IP 99.127.58.133 from VM ctest-vn1-vm2-67483920 passed
2026-09-02 02:59:20,285 - INFO - Detaching port 3b267fc2-5112-4d62-9062-f11c8d073e70 from VM ctest-vn1-vm2-67483920
2026-09-02 02:59:20,335 - INFO - Deleting VM ctest-vn1-vm2-67483920
2026-09-02 02:59:20,410 - INFO - Detaching port dc588987-b9eb-4ce0-95dc-f618eabd5d69 from VM ctest-vn1-vm1-64475917
2026-09-02 02:59:20,443 - INFO - Deleting VM ctest-vn1-vm1-64475917
2026-09-02 02:59:20,680 - DEBUG - Response for delete_port : ()
2026-09-02 02:59:20,860 - DEBUG - Response for delete_port : ()
2026-09-02 02:59:20,860 - INFO - Deleting VN ctest-vn1-19495258
2026-09-02 02:59:21,004 - DEBUG - Response for deleting network ()
2026-09-02 02:59:21,268 - DEBUG - No XMPP flaps were noticed during the test
2026-09-02 02:59:21,268 - INFO - END TEST : test_ports_specific_subnet_ip : PASSED[0:00:57]
2026-09-02 02:59:21,268 - INFO - --------------------------------------------------------------------------------
2026-09-02 02:59:22,022 - INFO - Deleted project: ctest-TestPorts-58001544, ID : 02938dd7-31f7-4d84-ad81-52cbc6b22a2d