2025-10-08 15:39:24,546 - INFO - Domain Default found not creating 2025-10-08 15:39:24,752 - INFO - Project ctest-TestMd5testsOnControl-16553809 not found, creating it 2025-10-08 15:39:25,316 - INFO - Created Project:ctest-TestMd5testsOnControl-16553809, ID : 831d4a22-55cf-4214-a225-7dfeacbb35e9 2025-10-08 15:39:27,786 - DEBUG - Response for create_network : {'network': {'id': 'b0bec243-7ca2-4a0e-9310-811e88299432', 'name': 'test_vnv6sr', 'tenant_id': '831d4a2255cf4214a2257dfeacbb35e9', 'project_id': '831d4a2255cf4214a2257dfeacbb35e9', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-16553809', 'test_vnv6sr'], 'port_security_enabled': True, 'description': ''}} 2025-10-08 15:39:28,278 - DEBUG - Response for create_subnet : {'subnet': {'id': '75f424ac-0c31-4c6d-9001-73e0351b556e', 'name': '', 'tenant_id': '831d4a2255cf4214a2257dfeacbb35e9', 'network_id': 'b0bec243-7ca2-4a0e-9310-811e88299432', 'ip_version': 6, 'cidr': '2001::101:0/120', 'allocation_pools': [{'start': '2001::101:2', 'end': '2001::101:fe'}], 'gateway_ip': '2001::101:1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '2001::101:2', 'tags': [], 'project_id': '831d4a2255cf4214a2257dfeacbb35e9'}} 2025-10-08 15:39:28,529 - DEBUG - Response for create_subnet : {'subnet': {'id': '7fccc77f-b005-4bac-9083-76d69ed74c24', 'name': '', 'tenant_id': '831d4a2255cf4214a2257dfeacbb35e9', 'network_id': 'b0bec243-7ca2-4a0e-9310-811e88299432', 'ip_version': 4, 'cidr': '123.68.117.128/26', 'allocation_pools': [{'start': '123.68.117.130', 'end': '123.68.117.190'}], 'gateway_ip': '123.68.117.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '123.68.117.130', 'tags': [], 'project_id': '831d4a2255cf4214a2257dfeacbb35e9'}} 2025-10-08 15:39:28,559 - INFO - Created VN test_vnv6sr 2025-10-08 15:39:28,574 - DEBUG - VN test_vnv6sr UUID is b0bec243-7ca2-4a0e-9310-811e88299432 2025-10-08 15:39:29,011 - DEBUG - Response for create_network : {'network': {'id': 'c584eb51-92dd-4025-a665-da84d4ad3987', 'name': 'test_vnv6dn', 'tenant_id': '831d4a2255cf4214a2257dfeacbb35e9', 'project_id': '831d4a2255cf4214a2257dfeacbb35e9', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-16553809', 'test_vnv6dn'], 'port_security_enabled': True, 'description': ''}} 2025-10-08 15:39:29,247 - DEBUG - Response for create_subnet : {'subnet': {'id': '6c8d751d-d955-4f7b-a5d0-5ea2f02b20ee', 'name': '', 'tenant_id': '831d4a2255cf4214a2257dfeacbb35e9', 'network_id': 'c584eb51-92dd-4025-a665-da84d4ad3987', 'ip_version': 6, 'cidr': '2001::201:0/120', 'allocation_pools': [{'start': '2001::201:2', 'end': '2001::201:fe'}], 'gateway_ip': '2001::201:1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '2001::201:2', 'tags': [], 'project_id': '831d4a2255cf4214a2257dfeacbb35e9'}} 2025-10-08 15:39:29,506 - DEBUG - Response for create_subnet : {'subnet': {'id': '4f1f77a9-a710-41d3-99fa-fc9f630fef43', 'name': '', 'tenant_id': '831d4a2255cf4214a2257dfeacbb35e9', 'network_id': 'c584eb51-92dd-4025-a665-da84d4ad3987', 'ip_version': 4, 'cidr': '122.199.123.192/26', 'allocation_pools': [{'start': '122.199.123.194', 'end': '122.199.123.254'}], 'gateway_ip': '122.199.123.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '122.199.123.194', 'tags': [], 'project_id': '831d4a2255cf4214a2257dfeacbb35e9'}} 2025-10-08 15:39:29,535 - INFO - Created VN test_vnv6dn 2025-10-08 15:39:29,548 - DEBUG - VN test_vnv6dn UUID is c584eb51-92dd-4025-a665-da84d4ad3987 2025-10-08 15:39:29,881 - DEBUG - Services list from nova: [, , , ] 2025-10-08 15:39:31,176 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4236-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4236-2) 2025-10-08 15:39:32,762 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4236-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4236-1) 2025-10-08 15:39:32,763 - INFO - Waiting for VM source_vm to be up.. 2025-10-08 15:39:32,878 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-10-08 15:39:37,996 - DEBUG - VM is in ACTIVE state now 2025-10-08 15:39:37,996 - INFO - VM name : source_vm 2025-10-08 15:39:38,092 - DEBUG - VM source_vm ID is 219b2765-6c95-4023-aec9-9b5b37107120 2025-10-08 15:39:38,122 - DEBUG - VM source_vm launched on Node an-jenkins-deploy-platform-ansible-os-4236-2 2025-10-08 15:39:38,211 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-machine/219b2765-6c95-4023-aec9-9b5b37107120 2025-10-08 15:39:38,527 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-machine/219b2765-6c95-4023-aec9-9b5b37107120 2025-10-08 15:39:38,577 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-machine-interface/a3b2767d-4e4f-4251-9e0b-864a65d7bbb2 2025-10-08 15:39:41,769 - 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 1011ms') 2025-10-08 15:39:41,769 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM source_vm failed! 2025-10-08 15:39:41,786 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-16553809:test_vnv6sr is 2001::101:1 and allocation pool is NOT set 2025-10-08 15:39:41,786 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-16553809:test_vnv6sr is 123.68.117.129 and allocation pool is NOT set 2025-10-08 15:39:43,851 - 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=8.10 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.381 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.381/4.242/8.103/3.861 ms') 2025-10-08 15:39:43,851 - INFO - Ping to Metadata IP 169.254.0.3 of VM source_vm passed 2025-10-08 15:39:43,926 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-08 15:39:43,926 - DEBUG - Waiting to SSH to VM source_vm, IP 123.68.117.131, Port 22 2025-10-08 15:39:44,089 - DEBUG - VM source_vm is ready for SSH connections 2025-10-08 15:39:44,089 - INFO - Waiting for VM dest_vm to be up.. 2025-10-08 15:39:44,195 - DEBUG - VM is in ACTIVE state now 2025-10-08 15:39:44,195 - INFO - VM name : dest_vm 2025-10-08 15:39:44,302 - DEBUG - VM dest_vm ID is 5b1fbdb4-9bcf-422e-a292-58bd1892a057 2025-10-08 15:39:44,303 - DEBUG - VM dest_vm launched on Node an-jenkins-deploy-platform-ansible-os-4236-1 2025-10-08 15:39:44,408 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-machine/5b1fbdb4-9bcf-422e-a292-58bd1892a057 2025-10-08 15:39:44,420 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-machine-interface/80d6c19f-8d1c-431d-8df2-41b53f3b4d6e 2025-10-08 15:39:45,598 - 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=2.82 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.421 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 0.421/1.619/2.818/1.198 ms') 2025-10-08 15:39:45,599 - INFO - Ping to Metadata IP 169.254.0.3 of VM dest_vm passed 2025-10-08 15:39:45,678 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-08 15:39:45,678 - DEBUG - Waiting to SSH to VM dest_vm, IP 122.199.123.195, Port 22 2025-10-08 15:39:45,835 - DEBUG - VM dest_vm is ready for SSH connections 2025-10-08 15:39:45,842 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-16553809:test_vnv6sr, security_group = None, network_policy = None, subnet_list = []], src_ports = [start_port = 0, end_port = -1], application = [], dst_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-16553809:test_vnv6dn, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = 0, end_port = -1], action_list = simple_action = pass, gateway_name = None, apply_service = [], service_properties = None, mirror_to = None, assign_routing_instance = None, log = False, alert = False, qos_action = None, host_based_service = False, ethertype = None, created = None, last_modified = None, description = None] 2025-10-08 15:39:46,218 - INFO - Attaching policy allow_all to vn test_vnv6sr 2025-10-08 15:39:46,613 - INFO - Attaching policy allow_all to vn test_vnv6dn 2025-10-08 15:39:46,959 - INFO - Configure the policy with allow any 2025-10-08 15:39:47,326 - DEBUG - Response for create_network : {'network': {'id': '98961bdd-d24d-46f5-bdfa-cf0e60984010', 'name': 'vn1', 'tenant_id': '831d4a2255cf4214a2257dfeacbb35e9', 'project_id': '831d4a2255cf4214a2257dfeacbb35e9', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-16553809', 'vn1'], 'port_security_enabled': True, 'description': ''}} 2025-10-08 15:39:47,625 - DEBUG - Response for create_subnet : {'subnet': {'id': '927b109d-3482-447e-ac75-22130c334752', 'name': '', 'tenant_id': '831d4a2255cf4214a2257dfeacbb35e9', 'network_id': '98961bdd-d24d-46f5-bdfa-cf0e60984010', 'ip_version': 4, 'cidr': '10.1.1.0/24', 'allocation_pools': [{'start': '10.1.1.2', 'end': '10.1.1.254'}], 'gateway_ip': '10.1.1.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '10.1.1.2', 'tags': [], 'project_id': '831d4a2255cf4214a2257dfeacbb35e9'}} 2025-10-08 15:39:47,653 - INFO - Created VN vn1 2025-10-08 15:39:47,666 - DEBUG - VN vn1 UUID is 98961bdd-d24d-46f5-bdfa-cf0e60984010 2025-10-08 15:39:48,068 - DEBUG - Response for create_network : {'network': {'id': '6e2e5982-b74e-4b9c-86ae-9de166784650', 'name': 'vn2', 'tenant_id': '831d4a2255cf4214a2257dfeacbb35e9', 'project_id': '831d4a2255cf4214a2257dfeacbb35e9', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-16553809', 'vn2'], 'port_security_enabled': True, 'description': ''}} 2025-10-08 15:39:48,340 - DEBUG - Response for create_subnet : {'subnet': {'id': '9958d35a-4a2e-4e6c-bd4f-3ba2cf306384', 'name': '', 'tenant_id': '831d4a2255cf4214a2257dfeacbb35e9', 'network_id': '6e2e5982-b74e-4b9c-86ae-9de166784650', 'ip_version': 4, 'cidr': '20.1.1.0/24', 'allocation_pools': [{'start': '20.1.1.2', 'end': '20.1.1.254'}], 'gateway_ip': '20.1.1.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '20.1.1.2', 'tags': [], 'project_id': '831d4a2255cf4214a2257dfeacbb35e9'}} 2025-10-08 15:39:48,364 - INFO - Created VN vn2 2025-10-08 15:39:48,380 - DEBUG - VN vn2 UUID is 6e2e5982-b74e-4b9c-86ae-9de166784650 2025-10-08 15:39:48,474 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-16553809:vn1, security_group = None, network_policy = None, subnet_list = []], src_ports = [start_port = 0, end_port = -1], application = [], dst_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-16553809:vn2, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = 0, end_port = -1], action_list = simple_action = pass, gateway_name = None, apply_service = [], service_properties = None, mirror_to = None, assign_routing_instance = None, log = False, alert = False, qos_action = None, host_based_service = False, ethertype = None, created = None, last_modified = None, description = None] 2025-10-08 15:39:48,836 - INFO - Attaching policy sec_grp_policy_ctest-random-15656316 to vn vn1 2025-10-08 15:39:49,256 - INFO - Attaching policy sec_grp_policy_ctest-random-15656316 to vn vn2 2025-10-08 15:39:51,074 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4236-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4236-2) 2025-10-08 15:39:52,775 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4236-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4236-1) 2025-10-08 15:39:52,838 - INFO - ================================================================================ 2025-10-08 15:39:52,838 - INFO - STARTING TEST : test_check_per_peer_on_control 2025-10-08 15:39:52,838 - INFO - TEST DESCRIPTION : Description: Verify per peer md5 and specific protocol on all ports and policy with allow all between VN's 2025-10-08 15:39:54,125 - DEBUG - Skipping xmpp flap check 2025-10-08 15:39:54,125 - INFO - Initial checks done. Running the testcase now 2025-10-08 15:39:54,125 - INFO - 2025-10-08 15:39:54,125 - INFO - config per peer with auth_data None 2025-10-08 15:39:54,800 - INFO - configure MD5 on host 7fd640e1-de12-4ef5-a765-c783f02b245e 2025-10-08 15:39:54,904 - INFO - config per peer with auth_data None 2025-10-08 15:39:55,564 - INFO - configure MD5 on host 1618fc67-95dc-4a4b-8021-7daf4ccf296e 2025-10-08 15:39:55,668 - INFO - config per peer with auth_data None 2025-10-08 15:39:56,332 - INFO - configure MD5 on host 8aa80a42-19f2-48bc-a8da-ab06c7cc4825 2025-10-08 15:39:56,456 - INFO - check BGP peering is up before setting md5 config 2025-10-08 15:39:56,456 - INFO - Check BGP staus between peers 2025-10-08 15:39:56,470 - INFO - config per peer with auth_data {'key_items': [{'key': 'juniper', 'key_id': 0}], 'key_type': 'md5'} 2025-10-08 15:39:57,136 - INFO - Check BGP staus between peers 2025-10-08 15:39:57,147 - DEBUG - Check the BGP connection on 10.0.0.133 2025-10-08 15:39:57,147 - DEBUG - Check the BGP connection on 10.0.0.133 2025-10-08 15:40:07,148 - INFO - Check BGP staus between peers 2025-10-08 15:40:07,159 - INFO - remove md5 configured on host 7fd640e1-de12-4ef5-a765-c783f02b245e 2025-10-08 15:40:07,160 - INFO - config per peer with auth_data None 2025-10-08 15:40:07,768 - INFO - configure MD5 on host 7fd640e1-de12-4ef5-a765-c783f02b245e 2025-10-08 15:40:07,864 - INFO - remove md5 configured on host 1618fc67-95dc-4a4b-8021-7daf4ccf296e 2025-10-08 15:40:07,864 - INFO - config per peer with auth_data None 2025-10-08 15:40:08,536 - INFO - configure MD5 on host 1618fc67-95dc-4a4b-8021-7daf4ccf296e 2025-10-08 15:40:08,636 - INFO - remove md5 configured on host 8aa80a42-19f2-48bc-a8da-ab06c7cc4825 2025-10-08 15:40:08,636 - INFO - config per peer with auth_data None 2025-10-08 15:40:09,292 - INFO - configure MD5 on host 8aa80a42-19f2-48bc-a8da-ab06c7cc4825 2025-10-08 15:40:09,376 - INFO - Skip interface_detach for VM vn2_vm_0 in state BUILD 2025-10-08 15:40:09,376 - INFO - Deleting VM vn2_vm_0 2025-10-08 15:40:09,377 - ERROR - VM vn2_vm_0 has failed to come up 2025-10-08 15:40:09,377 - ERROR - Nova failed to get host of the VM 2025-10-08 15:40:09,377 - ERROR - VM vn2_vm_0 has failed to come up 2025-10-08 15:40:09,377 - ERROR - Nova failed to get host of the VM 2025-10-08 15:40:09,463 - INFO - Skip interface_detach for VM vn1_vm_0 in state BUILD 2025-10-08 15:40:09,463 - INFO - Deleting VM vn1_vm_0 2025-10-08 15:40:09,464 - ERROR - VM vn1_vm_0 has failed to come up 2025-10-08 15:40:09,464 - ERROR - Nova failed to get host of the VM 2025-10-08 15:40:09,464 - ERROR - VM vn1_vm_0 has failed to come up 2025-10-08 15:40:09,464 - ERROR - Nova failed to get host of the VM 2025-10-08 15:40:09,549 - INFO - Dettaching policy sec_grp_policy_ctest-random-15656316 from vn vn2 2025-10-08 15:40:09,779 - INFO - Dettaching policy sec_grp_policy_ctest-random-15656316 from vn vn1 2025-10-08 15:40:10,056 - INFO - Deleted policy sec_grp_policy_ctest-random-15656316 2025-10-08 15:40:10,056 - INFO - Deleting VN vn2 2025-10-08 15:40:10,127 - DEBUG - VN 6e2e5982-b74e-4b9c-86ae-9de166784650 still in use: Unable to complete operation on network 6e2e5982-b74e-4b9c-86ae-9de166784650. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-fc253114-3472-4d99-9a0f-d8556f009dc6'] 2025-10-08 15:40:10,127 - WARNING - Deleting VN vn2 failed..Will retry 2025-10-08 15:40:12,352 - DEBUG - Response for deleting network () 2025-10-08 15:40:12,352 - INFO - Deleting VN vn1 2025-10-08 15:40:12,526 - DEBUG - Response for deleting network () 2025-10-08 15:40:12,526 - INFO - Dettaching policy allow_all from vn test_vnv6dn 2025-10-08 15:40:12,754 - INFO - Dettaching policy allow_all from vn test_vnv6sr 2025-10-08 15:40:13,097 - INFO - Deleted policy allow_all 2025-10-08 15:40:13,097 - INFO - Deleting VM dest_vm 2025-10-08 15:40:13,202 - INFO - Deleting VM source_vm 2025-10-08 15:40:13,288 - INFO - Deleting VN test_vnv6dn 2025-10-08 15:40:13,350 - DEBUG - VN c584eb51-92dd-4025-a665-da84d4ad3987 still in use: Unable to complete operation on network c584eb51-92dd-4025-a665-da84d4ad3987. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-9f385f09-6c95-4fb3-9281-e784576853a5'] 2025-10-08 15:40:13,351 - WARNING - Deleting VN test_vnv6dn failed..Will retry 2025-10-08 15:40:15,638 - DEBUG - Response for deleting network () 2025-10-08 15:40:15,638 - INFO - Deleting VN test_vnv6sr 2025-10-08 15:40:15,935 - DEBUG - Response for deleting network () 2025-10-08 15:40:17,220 - DEBUG - Skipping xmpp flap check
2025-10-08 15:40:17,220 - INFO - END TEST : test_check_per_peer_on_control : PASSED[0:00:25]
2025-10-08 15:40:17,220 - INFO - -------------------------------------------------------------------------------- 2025-10-08 15:40:17,604 - DEBUG - Response for create_network : {'network': {'id': '8a8001e9-32ea-4513-9725-7af74be6146b', 'name': 'test_vnv6sr', 'tenant_id': '831d4a2255cf4214a2257dfeacbb35e9', 'project_id': '831d4a2255cf4214a2257dfeacbb35e9', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-16553809', 'test_vnv6sr'], 'port_security_enabled': True, 'description': ''}} 2025-10-08 15:40:17,942 - DEBUG - Response for create_subnet : {'subnet': {'id': '9b91d3c2-6f8d-46bf-bad6-7dbcc24a4f2b', 'name': '', 'tenant_id': '831d4a2255cf4214a2257dfeacbb35e9', 'network_id': '8a8001e9-32ea-4513-9725-7af74be6146b', 'ip_version': 6, 'cidr': '2001::101:0/120', 'allocation_pools': [{'start': '2001::101:2', 'end': '2001::101:fe'}], 'gateway_ip': '2001::101:1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '2001::101:2', 'tags': [], 'project_id': '831d4a2255cf4214a2257dfeacbb35e9'}} 2025-10-08 15:40:18,208 - DEBUG - Response for create_subnet : {'subnet': {'id': '53429d69-0aa5-4890-86a7-283c7d81c095', 'name': '', 'tenant_id': '831d4a2255cf4214a2257dfeacbb35e9', 'network_id': '8a8001e9-32ea-4513-9725-7af74be6146b', 'ip_version': 4, 'cidr': '61.40.185.0/26', 'allocation_pools': [{'start': '61.40.185.2', 'end': '61.40.185.62'}], 'gateway_ip': '61.40.185.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '61.40.185.2', 'tags': [], 'project_id': '831d4a2255cf4214a2257dfeacbb35e9'}} 2025-10-08 15:40:18,246 - INFO - Created VN test_vnv6sr 2025-10-08 15:40:18,266 - DEBUG - VN test_vnv6sr UUID is 8a8001e9-32ea-4513-9725-7af74be6146b 2025-10-08 15:40:18,837 - DEBUG - Response for create_network : {'network': {'id': 'b7fb5141-92c9-484c-b334-9b7a51a67df2', 'name': 'test_vnv6dn', 'tenant_id': '831d4a2255cf4214a2257dfeacbb35e9', 'project_id': '831d4a2255cf4214a2257dfeacbb35e9', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-16553809', 'test_vnv6dn'], 'port_security_enabled': True, 'description': ''}} 2025-10-08 15:40:19,144 - DEBUG - Response for create_subnet : {'subnet': {'id': 'b4cfd7da-c2ea-4973-9be3-02c8d0bb2f36', 'name': '', 'tenant_id': '831d4a2255cf4214a2257dfeacbb35e9', 'network_id': 'b7fb5141-92c9-484c-b334-9b7a51a67df2', 'ip_version': 6, 'cidr': '2001::201:0/120', 'allocation_pools': [{'start': '2001::201:2', 'end': '2001::201:fe'}], 'gateway_ip': '2001::201:1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '2001::201:2', 'tags': [], 'project_id': '831d4a2255cf4214a2257dfeacbb35e9'}} 2025-10-08 15:40:19,394 - DEBUG - Response for create_subnet : {'subnet': {'id': '93c46345-1107-416d-b6fe-0c5dfc89aaad', 'name': '', 'tenant_id': '831d4a2255cf4214a2257dfeacbb35e9', 'network_id': 'b7fb5141-92c9-484c-b334-9b7a51a67df2', 'ip_version': 4, 'cidr': '15.137.253.64/26', 'allocation_pools': [{'start': '15.137.253.66', 'end': '15.137.253.126'}], 'gateway_ip': '15.137.253.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '15.137.253.66', 'tags': [], 'project_id': '831d4a2255cf4214a2257dfeacbb35e9'}} 2025-10-08 15:40:19,451 - INFO - Created VN test_vnv6dn 2025-10-08 15:40:19,467 - DEBUG - VN test_vnv6dn UUID is b7fb5141-92c9-484c-b334-9b7a51a67df2 2025-10-08 15:40:21,073 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4236-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4236-2) 2025-10-08 15:40:23,005 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4236-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4236-1) 2025-10-08 15:40:23,005 - INFO - Waiting for VM source_vm to be up.. 2025-10-08 15:40:23,158 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-10-08 15:40:28,262 - DEBUG - VM is in ACTIVE state now 2025-10-08 15:40:28,263 - INFO - VM name : source_vm 2025-10-08 15:40:28,374 - DEBUG - VM source_vm ID is de103475-e74c-41a5-a7fe-fe500274aea6 2025-10-08 15:40:28,374 - DEBUG - VM source_vm launched on Node an-jenkins-deploy-platform-ansible-os-4236-2 2025-10-08 15:40:28,499 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-machine/de103475-e74c-41a5-a7fe-fe500274aea6 2025-10-08 15:40:28,510 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-machine-interface/cbf4ea8e-e12b-4633-96d2-126fe57f10d5 2025-10-08 15:40:31,723 - 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 15:40:31,723 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM source_vm failed! 2025-10-08 15:40:31,745 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-16553809:test_vnv6sr is 2001::101:1 and allocation pool is NOT set 2025-10-08 15:40:31,745 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-16553809:test_vnv6sr is 61.40.185.1 and allocation pool is NOT set 2025-10-08 15:40:33,813 - 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=9.40 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.364 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1003ms\r\nrtt min/avg/max/mdev = 0.364/4.884/9.404/4.520 ms') 2025-10-08 15:40:33,813 - INFO - Ping to Metadata IP 169.254.0.3 of VM source_vm passed 2025-10-08 15:40:33,885 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-08 15:40:33,885 - DEBUG - Waiting to SSH to VM source_vm, IP 61.40.185.3, Port 22 2025-10-08 15:40:33,946 - DEBUG - Error on ssh to cirros@169.254.0.3:22, result: /bin/bash: connect: Connection refused /bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''} 2025-10-08 15:40:34,058 - DEBUG - VM source_vm is NOT ready for SSH connections, VM status: ACTIVE 2025-10-08 15:40:39,059 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-08 15:40:39,059 - DEBUG - Waiting to SSH to VM source_vm, IP 61.40.185.3, Port 22 2025-10-08 15:40:39,217 - DEBUG - VM source_vm is ready for SSH connections 2025-10-08 15:40:39,217 - INFO - Waiting for VM dest_vm to be up.. 2025-10-08 15:40:39,319 - DEBUG - VM is in ACTIVE state now 2025-10-08 15:40:39,319 - INFO - VM name : dest_vm 2025-10-08 15:40:39,413 - DEBUG - VM dest_vm ID is 3ae19c70-1ccb-454c-8a8b-988cfd5556f2 2025-10-08 15:40:39,413 - DEBUG - VM dest_vm launched on Node an-jenkins-deploy-platform-ansible-os-4236-1 2025-10-08 15:40:39,503 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-machine/3ae19c70-1ccb-454c-8a8b-988cfd5556f2 2025-10-08 15:40:39,515 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-machine-interface/21499a79-0154-4f06-8b17-2e8d9c01a935 2025-10-08 15:40:40,685 - 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=2.77 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.364 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 0.364/1.565/2.767/1.201 ms') 2025-10-08 15:40:40,686 - INFO - Ping to Metadata IP 169.254.0.3 of VM dest_vm passed 2025-10-08 15:40:40,761 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-08 15:40:40,761 - DEBUG - Waiting to SSH to VM dest_vm, IP 15.137.253.67, Port 22 2025-10-08 15:40:40,934 - DEBUG - VM dest_vm is ready for SSH connections 2025-10-08 15:40:40,941 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-16553809:test_vnv6sr, security_group = None, network_policy = None, subnet_list = []], src_ports = [start_port = 0, end_port = -1], application = [], dst_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-16553809:test_vnv6dn, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = 0, end_port = -1], action_list = simple_action = pass, gateway_name = None, apply_service = [], service_properties = None, mirror_to = None, assign_routing_instance = None, log = False, alert = False, qos_action = None, host_based_service = False, ethertype = None, created = None, last_modified = None, description = None] 2025-10-08 15:40:41,316 - INFO - Attaching policy allow_all to vn test_vnv6sr 2025-10-08 15:40:41,740 - INFO - Attaching policy allow_all to vn test_vnv6dn 2025-10-08 15:40:42,032 - INFO - Configure the policy with allow any 2025-10-08 15:40:42,356 - DEBUG - Response for create_network : {'network': {'id': 'dbf569a6-fc2c-4aa5-aeba-f4980a811753', 'name': 'vn1', 'tenant_id': '831d4a2255cf4214a2257dfeacbb35e9', 'project_id': '831d4a2255cf4214a2257dfeacbb35e9', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-16553809', 'vn1'], 'port_security_enabled': True, 'description': ''}} 2025-10-08 15:40:42,611 - DEBUG - Response for create_subnet : {'subnet': {'id': '4bb35a1c-16cc-4880-9911-95618f8c40d6', 'name': '', 'tenant_id': '831d4a2255cf4214a2257dfeacbb35e9', 'network_id': 'dbf569a6-fc2c-4aa5-aeba-f4980a811753', 'ip_version': 4, 'cidr': '10.1.1.0/24', 'allocation_pools': [{'start': '10.1.1.2', 'end': '10.1.1.254'}], 'gateway_ip': '10.1.1.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '10.1.1.2', 'tags': [], 'project_id': '831d4a2255cf4214a2257dfeacbb35e9'}} 2025-10-08 15:40:42,637 - INFO - Created VN vn1 2025-10-08 15:40:42,653 - DEBUG - VN vn1 UUID is dbf569a6-fc2c-4aa5-aeba-f4980a811753 2025-10-08 15:40:43,045 - DEBUG - Response for create_network : {'network': {'id': '3e258438-7dcc-4fce-9ddb-1a62f9dab821', 'name': 'vn2', 'tenant_id': '831d4a2255cf4214a2257dfeacbb35e9', 'project_id': '831d4a2255cf4214a2257dfeacbb35e9', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-16553809', 'vn2'], 'port_security_enabled': True, 'description': ''}} 2025-10-08 15:40:43,262 - DEBUG - Response for create_subnet : {'subnet': {'id': '9f1719f8-fee1-45a2-8b21-1b0c9e613337', 'name': '', 'tenant_id': '831d4a2255cf4214a2257dfeacbb35e9', 'network_id': '3e258438-7dcc-4fce-9ddb-1a62f9dab821', 'ip_version': 4, 'cidr': '20.1.1.0/24', 'allocation_pools': [{'start': '20.1.1.2', 'end': '20.1.1.254'}], 'gateway_ip': '20.1.1.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '20.1.1.2', 'tags': [], 'project_id': '831d4a2255cf4214a2257dfeacbb35e9'}} 2025-10-08 15:40:43,294 - INFO - Created VN vn2 2025-10-08 15:40:43,309 - DEBUG - VN vn2 UUID is 3e258438-7dcc-4fce-9ddb-1a62f9dab821 2025-10-08 15:40:43,410 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-16553809:vn1, security_group = None, network_policy = None, subnet_list = []], src_ports = [start_port = 0, end_port = -1], application = [], dst_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-16553809:vn2, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = 0, end_port = -1], action_list = simple_action = pass, gateway_name = None, apply_service = [], service_properties = None, mirror_to = None, assign_routing_instance = None, log = False, alert = False, qos_action = None, host_based_service = False, ethertype = None, created = None, last_modified = None, description = None] 2025-10-08 15:40:43,786 - INFO - Attaching policy sec_grp_policy_ctest-random-84513808 to vn vn1 2025-10-08 15:40:44,205 - INFO - Attaching policy sec_grp_policy_ctest-random-84513808 to vn vn2 2025-10-08 15:40:46,000 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4236-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4236-2) 2025-10-08 15:40:47,514 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4236-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4236-1) 2025-10-08 15:40:47,582 - INFO - ================================================================================ 2025-10-08 15:40:47,583 - INFO - STARTING TEST : test_create_md5_on_control 2025-10-08 15:40:47,583 - INFO - TEST DESCRIPTION : Description: Verify md5 with allow specific protocol on all ports and policy with allow all between VN's 2025-10-08 15:40:48,873 - DEBUG - Skipping xmpp flap check 2025-10-08 15:40:48,873 - INFO - Initial checks done. Running the testcase now 2025-10-08 15:40:48,873 - INFO - 2025-10-08 15:40:48,873 - INFO - config md5 on each host with auth_data None 2025-10-08 15:40:48,873 - INFO - config per peer with auth_data None 2025-10-08 15:40:49,544 - INFO - configure MD5 on host 7fd640e1-de12-4ef5-a765-c783f02b245e 2025-10-08 15:40:49,660 - INFO - config per peer with auth_data None 2025-10-08 15:40:50,304 - INFO - configure MD5 on host 1618fc67-95dc-4a4b-8021-7daf4ccf296e 2025-10-08 15:40:50,408 - INFO - config per peer with auth_data None 2025-10-08 15:40:51,080 - INFO - configure MD5 on host 8aa80a42-19f2-48bc-a8da-ab06c7cc4825 2025-10-08 15:40:51,160 - INFO - check if BGP between peers are up before setting md5 2025-10-08 15:40:51,160 - INFO - Check BGP staus between peers 2025-10-08 15:40:51,174 - INFO - setting MD5 config on host 7fd640e1-de12-4ef5-a765-c783f02b245e 2025-10-08 15:40:51,175 - INFO - configure MD5 on host 7fd640e1-de12-4ef5-a765-c783f02b245e 2025-10-08 15:40:51,252 - INFO - setting MD5 config on host 1618fc67-95dc-4a4b-8021-7daf4ccf296e 2025-10-08 15:40:51,252 - INFO - configure MD5 on host 1618fc67-95dc-4a4b-8021-7daf4ccf296e 2025-10-08 15:40:51,332 - INFO - setting MD5 config on host 8aa80a42-19f2-48bc-a8da-ab06c7cc4825 2025-10-08 15:40:51,332 - INFO - configure MD5 on host 8aa80a42-19f2-48bc-a8da-ab06c7cc4825 2025-10-08 15:40:51,412 - INFO - check if BGP peering is up after md5 config 2025-10-08 15:40:51,412 - INFO - Check BGP staus between peers 2025-10-08 15:40:51,423 - DEBUG - Check the BGP connection on 10.0.0.133 2025-10-08 15:40:51,423 - DEBUG - Check the BGP connection on 10.0.0.133 2025-10-08 15:41:01,423 - INFO - Check BGP staus between peers 2025-10-08 15:41:01,436 - INFO - remove md5 configured on host 7fd640e1-de12-4ef5-a765-c783f02b245e 2025-10-08 15:41:01,436 - INFO - config per peer with auth_data None 2025-10-08 15:41:02,164 - INFO - configure MD5 on host 7fd640e1-de12-4ef5-a765-c783f02b245e 2025-10-08 15:41:02,260 - INFO - remove md5 configured on host 1618fc67-95dc-4a4b-8021-7daf4ccf296e 2025-10-08 15:41:02,260 - INFO - config per peer with auth_data None 2025-10-08 15:41:02,941 - INFO - configure MD5 on host 1618fc67-95dc-4a4b-8021-7daf4ccf296e 2025-10-08 15:41:03,040 - INFO - remove md5 configured on host 8aa80a42-19f2-48bc-a8da-ab06c7cc4825 2025-10-08 15:41:03,040 - INFO - config per peer with auth_data None 2025-10-08 15:41:03,692 - INFO - configure MD5 on host 8aa80a42-19f2-48bc-a8da-ab06c7cc4825 2025-10-08 15:41:03,796 - INFO - Skip interface_detach for VM vn2_vm_0 in state BUILD 2025-10-08 15:41:03,796 - INFO - Deleting VM vn2_vm_0 2025-10-08 15:41:03,796 - ERROR - VM vn2_vm_0 has failed to come up 2025-10-08 15:41:03,796 - ERROR - Nova failed to get host of the VM 2025-10-08 15:41:03,796 - ERROR - VM vn2_vm_0 has failed to come up 2025-10-08 15:41:03,796 - ERROR - Nova failed to get host of the VM 2025-10-08 15:41:03,956 - INFO - Skip interface_detach for VM vn1_vm_0 in state BUILD 2025-10-08 15:41:03,956 - INFO - Deleting VM vn1_vm_0 2025-10-08 15:41:03,957 - ERROR - VM vn1_vm_0 has failed to come up 2025-10-08 15:41:03,957 - ERROR - Nova failed to get host of the VM 2025-10-08 15:41:03,957 - ERROR - VM vn1_vm_0 has failed to come up 2025-10-08 15:41:03,957 - ERROR - Nova failed to get host of the VM 2025-10-08 15:41:04,051 - INFO - Dettaching policy sec_grp_policy_ctest-random-84513808 from vn vn2 2025-10-08 15:41:04,223 - INFO - Dettaching policy sec_grp_policy_ctest-random-84513808 from vn vn1 2025-10-08 15:41:04,494 - INFO - Deleted policy sec_grp_policy_ctest-random-84513808 2025-10-08 15:41:04,494 - INFO - Deleting VN vn2 2025-10-08 15:41:04,552 - DEBUG - VN 3e258438-7dcc-4fce-9ddb-1a62f9dab821 still in use: Unable to complete operation on network 3e258438-7dcc-4fce-9ddb-1a62f9dab821. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-35a0fa8a-b989-485c-9386-18ccf04a556f'] 2025-10-08 15:41:04,552 - WARNING - Deleting VN vn2 failed..Will retry 2025-10-08 15:41:06,763 - DEBUG - Response for deleting network () 2025-10-08 15:41:06,763 - INFO - Deleting VN vn1 2025-10-08 15:41:06,975 - DEBUG - Response for deleting network () 2025-10-08 15:41:06,975 - INFO - Dettaching policy allow_all from vn test_vnv6dn 2025-10-08 15:41:07,166 - INFO - Dettaching policy allow_all from vn test_vnv6sr 2025-10-08 15:41:07,445 - INFO - Deleted policy allow_all 2025-10-08 15:41:07,445 - INFO - Deleting VM dest_vm 2025-10-08 15:41:07,589 - INFO - Deleting VM source_vm 2025-10-08 15:41:07,671 - INFO - Deleting VN test_vnv6dn 2025-10-08 15:41:07,721 - DEBUG - VN b7fb5141-92c9-484c-b334-9b7a51a67df2 still in use: Unable to complete operation on network b7fb5141-92c9-484c-b334-9b7a51a67df2. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-f1899d4d-60a1-4e49-be45-543e04d42ffa'] 2025-10-08 15:41:07,721 - WARNING - Deleting VN test_vnv6dn failed..Will retry 2025-10-08 15:41:09,932 - DEBUG - Response for deleting network () 2025-10-08 15:41:09,933 - INFO - Deleting VN test_vnv6sr 2025-10-08 15:41:10,165 - DEBUG - Response for deleting network () 2025-10-08 15:41:11,441 - DEBUG - Skipping xmpp flap check
2025-10-08 15:41:11,441 - INFO - END TEST : test_create_md5_on_control : PASSED[0:00:24]
2025-10-08 15:41:11,441 - INFO - -------------------------------------------------------------------------------- 2025-10-08 15:41:12,276 - INFO - Deleted project: ctest-TestMd5testsOnControl-16553809, ID : 831d4a22-55cf-4214-a225-7dfeacbb35e9