2026-06-05 18:31:47,876 - INFO - Domain Default found not creating
2026-06-05 18:31:48,032 - INFO - Project ctest-TestMd5testsOnControl-30192116 not found, creating it
2026-06-05 18:31:48,583 - INFO - Created Project:ctest-TestMd5testsOnControl-30192116, ID : 804fb8c2-5c19-4343-9e33-4ebeb7688603
2026-06-05 18:31:50,865 - DEBUG - Response for create_network : {'network': {'id': '4deab1af-2d58-4942-b259-f99755d94363', 'name': 'test_vnv6sr', 'tenant_id': '804fb8c25c1943439e334ebeb7688603', 'project_id': '804fb8c25c1943439e334ebeb7688603', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-30192116', 'test_vnv6sr'], 'port_security_enabled': True, 'description': ''}}
2026-06-05 18:31:51,115 - DEBUG - Response for create_subnet : {'subnet': {'id': 'fa7a7cf9-6970-4e6b-98ae-0c603d07c157', 'name': '', 'tenant_id': '804fb8c25c1943439e334ebeb7688603', 'network_id': '4deab1af-2d58-4942-b259-f99755d94363', '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': '804fb8c25c1943439e334ebeb7688603'}}
2026-06-05 18:31:51,334 - DEBUG - Response for create_subnet : {'subnet': {'id': '23826563-0c7b-49b0-b02f-219f8c6fd739', 'name': '', 'tenant_id': '804fb8c25c1943439e334ebeb7688603', 'network_id': '4deab1af-2d58-4942-b259-f99755d94363', 'ip_version': 4, 'cidr': '146.147.5.0/26', 'allocation_pools': [{'start': '146.147.5.2', 'end': '146.147.5.62'}], 'gateway_ip': '146.147.5.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '146.147.5.2', 'tags': [], 'project_id': '804fb8c25c1943439e334ebeb7688603'}}
2026-06-05 18:31:51,359 - INFO - Created VN test_vnv6sr
2026-06-05 18:31:51,373 - DEBUG - VN test_vnv6sr UUID is 4deab1af-2d58-4942-b259-f99755d94363
2026-06-05 18:31:51,727 - DEBUG - Response for create_network : {'network': {'id': '5dcbe9ca-27d9-49f7-bc01-859ace59a047', 'name': 'test_vnv6dn', 'tenant_id': '804fb8c25c1943439e334ebeb7688603', 'project_id': '804fb8c25c1943439e334ebeb7688603', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-30192116', 'test_vnv6dn'], 'port_security_enabled': True, 'description': ''}}
2026-06-05 18:31:51,936 - DEBUG - Response for create_subnet : {'subnet': {'id': '733d20bf-cee4-4862-9372-497a5277c7c6', 'name': '', 'tenant_id': '804fb8c25c1943439e334ebeb7688603', 'network_id': '5dcbe9ca-27d9-49f7-bc01-859ace59a047', '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': '804fb8c25c1943439e334ebeb7688603'}}
2026-06-05 18:31:52,141 - DEBUG - Response for create_subnet : {'subnet': {'id': '93c73f12-5df1-4003-a188-84490645ed56', 'name': '', 'tenant_id': '804fb8c25c1943439e334ebeb7688603', 'network_id': '5dcbe9ca-27d9-49f7-bc01-859ace59a047', 'ip_version': 4, 'cidr': '175.43.192.0/26', 'allocation_pools': [{'start': '175.43.192.2', 'end': '175.43.192.62'}], 'gateway_ip': '175.43.192.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '175.43.192.2', 'tags': [], 'project_id': '804fb8c25c1943439e334ebeb7688603'}}
2026-06-05 18:31:52,166 - INFO - Created VN test_vnv6dn
2026-06-05 18:31:52,178 - DEBUG - VN test_vnv6dn UUID is 5dcbe9ca-27d9-49f7-bc01-859ace59a047
2026-06-05 18:31:52,333 - DEBUG - Services list from nova: [, , , ]
2026-06-05 18:31:53,549 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5896-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5896-1)
2026-06-05 18:31:54,970 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5896-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5896-2)
2026-06-05 18:31:54,971 - INFO - Waiting for VM source_vm to be up..
2026-06-05 18:31:55,058 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2026-06-05 18:32:00,150 - DEBUG - VM is in ACTIVE state now
2026-06-05 18:32:00,150 - INFO - VM name : source_vm
2026-06-05 18:32:00,245 - DEBUG - VM source_vm ID is 5f600b1c-4a7a-4fd6-9a79-c6acdc785302
2026-06-05 18:32:00,273 - DEBUG - VM source_vm launched on Node an-jenkins-deploy-platform-ansible-os-5896-1
2026-06-05 18:32:00,372 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/5f600b1c-4a7a-4fd6-9a79-c6acdc785302
2026-06-05 18:32:00,737 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/5f600b1c-4a7a-4fd6-9a79-c6acdc785302
2026-06-05 18:32:00,777 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/42759a45-c773-42d9-8c1c-61a137760ff0
2026-06-05 18:32:03,963 - 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 1031ms')
2026-06-05 18:32:03,963 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM source_vm failed!
2026-06-05 18:32:03,978 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-30192116:test_vnv6sr is 2001::101:1 and allocation pool is NOT set
2026-06-05 18:32:03,978 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-30192116:test_vnv6sr is 146.147.5.1 and allocation pool is NOT set
2026-06-05 18:32:06,042 - 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.59 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=2.05 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 = 2.046/5.816/9.586/3.770 ms')
2026-06-05 18:32:06,042 - INFO - Ping to Metadata IP 169.254.0.3 of VM source_vm passed
2026-06-05 18:32:06,113 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-05 18:32:06,113 - DEBUG - Waiting to SSH to VM source_vm, IP 146.147.5.3, Port 22
2026-06-05 18:32:06,178 - 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': ''}
2026-06-05 18:32:06,263 - DEBUG - VM source_vm is NOT ready for SSH connections, VM status: ACTIVE
2026-06-05 18:32:11,264 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-05 18:32:11,264 - DEBUG - Waiting to SSH to VM source_vm, IP 146.147.5.3, Port 22
2026-06-05 18:32:11,434 - DEBUG - VM source_vm is ready for SSH connections
2026-06-05 18:32:11,434 - INFO - Waiting for VM dest_vm to be up..
2026-06-05 18:32:11,519 - DEBUG - VM is in ACTIVE state now
2026-06-05 18:32:11,519 - INFO - VM name : dest_vm
2026-06-05 18:32:11,597 - DEBUG - VM dest_vm ID is dc59261e-81e9-4a5b-a256-0e4e42086709
2026-06-05 18:32:11,597 - DEBUG - VM dest_vm launched on Node an-jenkins-deploy-platform-ansible-os-5896-2
2026-06-05 18:32:11,674 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/dc59261e-81e9-4a5b-a256-0e4e42086709
2026-06-05 18:32:11,684 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/92bf22d2-4e05-4e84-9b0a-ebb7c0123849
2026-06-05 18:32:12,859 - 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.835 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.835/2.227/3.619/1.392 ms')
2026-06-05 18:32:12,859 - INFO - Ping to Metadata IP 169.254.0.3 of VM dest_vm passed
2026-06-05 18:32:12,933 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-05 18:32:12,933 - DEBUG - Waiting to SSH to VM dest_vm, IP 175.43.192.3, Port 22
2026-06-05 18:32:13,104 - DEBUG - VM dest_vm is ready for SSH connections
2026-06-05 18:32:13,111 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-30192116: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-30192116: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]
2026-06-05 18:32:13,456 - INFO - Attaching policy allow_all to vn test_vnv6sr
2026-06-05 18:32:13,838 - INFO - Attaching policy allow_all to vn test_vnv6dn
2026-06-05 18:32:14,130 - INFO - Configure the policy with allow any
2026-06-05 18:32:14,454 - DEBUG - Response for create_network : {'network': {'id': 'd1b61ef4-7301-4c24-9e76-093fe573ce1c', 'name': 'vn1', 'tenant_id': '804fb8c25c1943439e334ebeb7688603', 'project_id': '804fb8c25c1943439e334ebeb7688603', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-30192116', 'vn1'], 'port_security_enabled': True, 'description': ''}}
2026-06-05 18:32:14,686 - DEBUG - Response for create_subnet : {'subnet': {'id': '66db3b43-5ea1-4eb7-a470-2a18b8363688', 'name': '', 'tenant_id': '804fb8c25c1943439e334ebeb7688603', 'network_id': 'd1b61ef4-7301-4c24-9e76-093fe573ce1c', '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': '804fb8c25c1943439e334ebeb7688603'}}
2026-06-05 18:32:14,711 - INFO - Created VN vn1
2026-06-05 18:32:14,728 - DEBUG - VN vn1 UUID is d1b61ef4-7301-4c24-9e76-093fe573ce1c
2026-06-05 18:32:15,128 - DEBUG - Response for create_network : {'network': {'id': '327acc61-39ea-419d-98d9-d6dcc922651a', 'name': 'vn2', 'tenant_id': '804fb8c25c1943439e334ebeb7688603', 'project_id': '804fb8c25c1943439e334ebeb7688603', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-30192116', 'vn2'], 'port_security_enabled': True, 'description': ''}}
2026-06-05 18:32:15,394 - DEBUG - Response for create_subnet : {'subnet': {'id': '092815fd-9c2e-4fc6-a216-b8f5c765a3b4', 'name': '', 'tenant_id': '804fb8c25c1943439e334ebeb7688603', 'network_id': '327acc61-39ea-419d-98d9-d6dcc922651a', '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': '804fb8c25c1943439e334ebeb7688603'}}
2026-06-05 18:32:15,420 - INFO - Created VN vn2
2026-06-05 18:32:15,433 - DEBUG - VN vn2 UUID is 327acc61-39ea-419d-98d9-d6dcc922651a
2026-06-05 18:32:15,551 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-30192116: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-30192116: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]
2026-06-05 18:32:15,882 - INFO - Attaching policy sec_grp_policy_ctest-random-69189244 to vn vn1
2026-06-05 18:32:16,247 - INFO - Attaching policy sec_grp_policy_ctest-random-69189244 to vn vn2
2026-06-05 18:32:17,909 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5896-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5896-1)
2026-06-05 18:32:19,504 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5896-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5896-2)
2026-06-05 18:32:19,567 - INFO - ================================================================================
2026-06-05 18:32:19,567 - INFO - STARTING TEST : test_check_per_peer_on_control
2026-06-05 18:32:19,567 - INFO - TEST DESCRIPTION :
Description: Verify per peer md5 and specific protocol on all ports and policy with allow all between VN's
2026-06-05 18:32:20,866 - DEBUG - Skipping xmpp flap check
2026-06-05 18:32:20,867 - INFO - Initial checks done. Running the testcase now
2026-06-05 18:32:20,867 - INFO -
2026-06-05 18:32:20,867 - INFO - config per peer with auth_data None
2026-06-05 18:32:21,447 - INFO - configure MD5 on host 1060464b-d87e-40b6-96fe-3b4f11b741f9
2026-06-05 18:32:21,539 - INFO - config per peer with auth_data None
2026-06-05 18:32:22,131 - INFO - configure MD5 on host 179415df-598f-4f0b-9f7b-d4934cfb508f
2026-06-05 18:32:22,215 - INFO - config per peer with auth_data None
2026-06-05 18:32:22,791 - INFO - configure MD5 on host 92eefd9d-6925-4658-b50d-35dd5edcee41
2026-06-05 18:32:22,883 - INFO - check BGP peering is up before setting md5 config
2026-06-05 18:32:22,883 - INFO - Check BGP staus between peers
2026-06-05 18:32:22,895 - INFO - config per peer with auth_data {'key_items': [{'key': 'juniper', 'key_id': 0}], 'key_type': 'md5'}
2026-06-05 18:32:23,499 - INFO - Check BGP staus between peers
2026-06-05 18:32:23,514 - DEBUG - Check the BGP connection on 10.0.0.33
2026-06-05 18:32:23,514 - DEBUG - Check the BGP connection on 10.0.0.33
2026-06-05 18:32:33,515 - INFO - Check BGP staus between peers
2026-06-05 18:32:33,528 - INFO - remove md5 configured on host 1060464b-d87e-40b6-96fe-3b4f11b741f9
2026-06-05 18:32:33,529 - INFO - config per peer with auth_data None
2026-06-05 18:32:34,127 - INFO - configure MD5 on host 1060464b-d87e-40b6-96fe-3b4f11b741f9
2026-06-05 18:32:34,211 - INFO - remove md5 configured on host 179415df-598f-4f0b-9f7b-d4934cfb508f
2026-06-05 18:32:34,211 - INFO - config per peer with auth_data None
2026-06-05 18:32:34,855 - INFO - configure MD5 on host 179415df-598f-4f0b-9f7b-d4934cfb508f
2026-06-05 18:32:34,963 - INFO - remove md5 configured on host 92eefd9d-6925-4658-b50d-35dd5edcee41
2026-06-05 18:32:34,963 - INFO - config per peer with auth_data None
2026-06-05 18:32:35,615 - INFO - configure MD5 on host 92eefd9d-6925-4658-b50d-35dd5edcee41
2026-06-05 18:32:35,699 - INFO - Skip interface_detach for VM vn2_vm_0 in state BUILD
2026-06-05 18:32:35,699 - INFO - Deleting VM vn2_vm_0
2026-06-05 18:32:35,699 - ERROR - VM vn2_vm_0 has failed to come up
2026-06-05 18:32:35,699 - ERROR - Nova failed to get host of the VM
2026-06-05 18:32:35,699 - ERROR - VM vn2_vm_0 has failed to come up
2026-06-05 18:32:35,699 - ERROR - Nova failed to get host of the VM
2026-06-05 18:32:35,827 - INFO - Skip interface_detach for VM vn1_vm_0 in state BUILD
2026-06-05 18:32:35,827 - INFO - Deleting VM vn1_vm_0
2026-06-05 18:32:35,827 - ERROR - VM vn1_vm_0 has failed to come up
2026-06-05 18:32:35,827 - ERROR - Nova failed to get host of the VM
2026-06-05 18:32:35,827 - ERROR - VM vn1_vm_0 has failed to come up
2026-06-05 18:32:35,827 - ERROR - Nova failed to get host of the VM
2026-06-05 18:32:35,899 - INFO - Dettaching policy sec_grp_policy_ctest-random-69189244 from vn vn2
2026-06-05 18:32:36,074 - INFO - Dettaching policy sec_grp_policy_ctest-random-69189244 from vn vn1
2026-06-05 18:32:36,317 - INFO - Deleted policy sec_grp_policy_ctest-random-69189244
2026-06-05 18:32:36,317 - INFO - Deleting VN vn2
2026-06-05 18:32:36,388 - DEBUG - VN 327acc61-39ea-419d-98d9-d6dcc922651a still in use: Unable to complete operation on network 327acc61-39ea-419d-98d9-d6dcc922651a. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-694917a1-faaa-4362-a2c8-4c96a7e2f81e']
2026-06-05 18:32:36,388 - WARNING - Deleting VN vn2 failed..Will retry
2026-06-05 18:32:38,609 - DEBUG - Response for deleting network ()
2026-06-05 18:32:38,609 - INFO - Deleting VN vn1
2026-06-05 18:32:38,806 - DEBUG - Response for deleting network ()
2026-06-05 18:32:38,806 - INFO - Dettaching policy allow_all from vn test_vnv6dn
2026-06-05 18:32:39,006 - INFO - Dettaching policy allow_all from vn test_vnv6sr
2026-06-05 18:32:39,253 - INFO - Deleted policy allow_all
2026-06-05 18:32:39,253 - INFO - Deleting VM dest_vm
2026-06-05 18:32:39,341 - INFO - Deleting VM source_vm
2026-06-05 18:32:39,408 - INFO - Deleting VN test_vnv6dn
2026-06-05 18:32:39,453 - DEBUG - VN 5dcbe9ca-27d9-49f7-bc01-859ace59a047 still in use: Unable to complete operation on network 5dcbe9ca-27d9-49f7-bc01-859ace59a047. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-ad44a1f3-5a6d-46fd-8352-fe67ec3a4dc9']
2026-06-05 18:32:39,453 - WARNING - Deleting VN test_vnv6dn failed..Will retry
2026-06-05 18:32:41,634 - DEBUG - Response for deleting network ()
2026-06-05 18:32:41,634 - INFO - Deleting VN test_vnv6sr
2026-06-05 18:32:41,829 - DEBUG - Response for deleting network ()
2026-06-05 18:32:43,087 - DEBUG - Skipping xmpp flap check
2026-06-05 18:32:43,087 - INFO - END TEST : test_check_per_peer_on_control : PASSED[0:00:24]
2026-06-05 18:32:43,087 - INFO - --------------------------------------------------------------------------------
2026-06-05 18:32:43,384 - DEBUG - Response for create_network : {'network': {'id': '781236d3-7d92-4ef5-9c96-8bb55b37e722', 'name': 'test_vnv6sr', 'tenant_id': '804fb8c25c1943439e334ebeb7688603', 'project_id': '804fb8c25c1943439e334ebeb7688603', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-30192116', 'test_vnv6sr'], 'port_security_enabled': True, 'description': ''}}
2026-06-05 18:32:43,662 - DEBUG - Response for create_subnet : {'subnet': {'id': '87c3f8ec-24f5-4642-b541-c0750e18e637', 'name': '', 'tenant_id': '804fb8c25c1943439e334ebeb7688603', 'network_id': '781236d3-7d92-4ef5-9c96-8bb55b37e722', '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': '804fb8c25c1943439e334ebeb7688603'}}
2026-06-05 18:32:43,865 - DEBUG - Response for create_subnet : {'subnet': {'id': '90842afe-bee4-4eba-b886-a8d7397865d0', 'name': '', 'tenant_id': '804fb8c25c1943439e334ebeb7688603', 'network_id': '781236d3-7d92-4ef5-9c96-8bb55b37e722', 'ip_version': 4, 'cidr': '173.211.182.64/26', 'allocation_pools': [{'start': '173.211.182.66', 'end': '173.211.182.126'}], 'gateway_ip': '173.211.182.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '173.211.182.66', 'tags': [], 'project_id': '804fb8c25c1943439e334ebeb7688603'}}
2026-06-05 18:32:43,889 - INFO - Created VN test_vnv6sr
2026-06-05 18:32:43,902 - DEBUG - VN test_vnv6sr UUID is 781236d3-7d92-4ef5-9c96-8bb55b37e722
2026-06-05 18:32:44,277 - DEBUG - Response for create_network : {'network': {'id': 'f9177958-aaaa-4438-9c4d-cd9e739a2f36', 'name': 'test_vnv6dn', 'tenant_id': '804fb8c25c1943439e334ebeb7688603', 'project_id': '804fb8c25c1943439e334ebeb7688603', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-30192116', 'test_vnv6dn'], 'port_security_enabled': True, 'description': ''}}
2026-06-05 18:32:44,491 - DEBUG - Response for create_subnet : {'subnet': {'id': '44212bf0-6bce-48ce-b85c-b47e80f6f706', 'name': '', 'tenant_id': '804fb8c25c1943439e334ebeb7688603', 'network_id': 'f9177958-aaaa-4438-9c4d-cd9e739a2f36', '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': '804fb8c25c1943439e334ebeb7688603'}}
2026-06-05 18:32:44,728 - DEBUG - Response for create_subnet : {'subnet': {'id': 'c4c9892e-d18e-47db-bf46-c181ddb671f7', 'name': '', 'tenant_id': '804fb8c25c1943439e334ebeb7688603', 'network_id': 'f9177958-aaaa-4438-9c4d-cd9e739a2f36', 'ip_version': 4, 'cidr': '180.143.224.64/26', 'allocation_pools': [{'start': '180.143.224.66', 'end': '180.143.224.126'}], 'gateway_ip': '180.143.224.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '180.143.224.66', 'tags': [], 'project_id': '804fb8c25c1943439e334ebeb7688603'}}
2026-06-05 18:32:44,751 - INFO - Created VN test_vnv6dn
2026-06-05 18:32:44,764 - DEBUG - VN test_vnv6dn UUID is f9177958-aaaa-4438-9c4d-cd9e739a2f36
2026-06-05 18:32:45,991 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5896-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5896-1)
2026-06-05 18:32:47,709 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5896-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5896-2)
2026-06-05 18:32:47,710 - INFO - Waiting for VM source_vm to be up..
2026-06-05 18:32:47,795 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2026-06-05 18:32:52,885 - DEBUG - VM is in ACTIVE state now
2026-06-05 18:32:52,885 - INFO - VM name : source_vm
2026-06-05 18:32:52,985 - DEBUG - VM source_vm ID is dec88fab-7333-4d3d-8013-0eee290012c5
2026-06-05 18:32:52,986 - DEBUG - VM source_vm launched on Node an-jenkins-deploy-platform-ansible-os-5896-1
2026-06-05 18:32:53,084 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/dec88fab-7333-4d3d-8013-0eee290012c5
2026-06-05 18:32:53,095 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/8effea72-9cbf-4cd2-8da2-3a8a0f19483c
2026-06-05 18:32:56,278 - 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 1030ms')
2026-06-05 18:32:56,278 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM source_vm failed!
2026-06-05 18:32:56,294 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-30192116:test_vnv6sr is 2001::101:1 and allocation pool is NOT set
2026-06-05 18:32:56,295 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-30192116:test_vnv6sr is 173.211.182.65 and allocation pool is NOT set
2026-06-05 18:32:58,364 - 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=7.68 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.466 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.466/4.071/7.677/3.605 ms')
2026-06-05 18:32:58,364 - INFO - Ping to Metadata IP 169.254.0.3 of VM source_vm passed
2026-06-05 18:32:58,436 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-05 18:32:58,437 - DEBUG - Waiting to SSH to VM source_vm, IP 173.211.182.67, Port 22
2026-06-05 18:32:58,611 - DEBUG - VM source_vm is ready for SSH connections
2026-06-05 18:32:58,611 - INFO - Waiting for VM dest_vm to be up..
2026-06-05 18:32:58,721 - DEBUG - VM is in ACTIVE state now
2026-06-05 18:32:58,721 - INFO - VM name : dest_vm
2026-06-05 18:32:58,814 - DEBUG - VM dest_vm ID is d92a8aa5-6a8e-40c7-994e-3cb6c3c7d9bb
2026-06-05 18:32:58,815 - DEBUG - VM dest_vm launched on Node an-jenkins-deploy-platform-ansible-os-5896-2
2026-06-05 18:32:58,899 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/d92a8aa5-6a8e-40c7-994e-3cb6c3c7d9bb
2026-06-05 18:32:58,910 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/3701b2da-c9d5-4ce1-9bbc-804b862cf2bc
2026-06-05 18:33:00,087 - 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.79 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=2.17 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 = 2.174/5.984/9.794/3.810 ms')
2026-06-05 18:33:00,088 - INFO - Ping to Metadata IP 169.254.0.3 of VM dest_vm passed
2026-06-05 18:33:00,162 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-05 18:33:00,162 - DEBUG - Waiting to SSH to VM dest_vm, IP 180.143.224.67, Port 22
2026-06-05 18:33:00,229 - 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': ''}
2026-06-05 18:33:00,317 - DEBUG - VM dest_vm is NOT ready for SSH connections, VM status: ACTIVE
2026-06-05 18:33:05,318 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-05 18:33:05,318 - DEBUG - Waiting to SSH to VM dest_vm, IP 180.143.224.67, Port 22
2026-06-05 18:33:05,492 - DEBUG - VM dest_vm is ready for SSH connections
2026-06-05 18:33:05,498 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-30192116: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-30192116: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]
2026-06-05 18:33:05,859 - INFO - Attaching policy allow_all to vn test_vnv6sr
2026-06-05 18:33:06,218 - INFO - Attaching policy allow_all to vn test_vnv6dn
2026-06-05 18:33:06,510 - INFO - Configure the policy with allow any
2026-06-05 18:33:06,856 - DEBUG - Response for create_network : {'network': {'id': '389533c9-c266-4b70-83ac-fa1cb24e1454', 'name': 'vn1', 'tenant_id': '804fb8c25c1943439e334ebeb7688603', 'project_id': '804fb8c25c1943439e334ebeb7688603', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-30192116', 'vn1'], 'port_security_enabled': True, 'description': ''}}
2026-06-05 18:33:07,142 - DEBUG - Response for create_subnet : {'subnet': {'id': 'e430130e-1026-4402-9b53-3a3202e7057d', 'name': '', 'tenant_id': '804fb8c25c1943439e334ebeb7688603', 'network_id': '389533c9-c266-4b70-83ac-fa1cb24e1454', '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': '804fb8c25c1943439e334ebeb7688603'}}
2026-06-05 18:33:07,167 - INFO - Created VN vn1
2026-06-05 18:33:07,182 - DEBUG - VN vn1 UUID is 389533c9-c266-4b70-83ac-fa1cb24e1454
2026-06-05 18:33:07,561 - DEBUG - Response for create_network : {'network': {'id': 'e8934e1b-5970-45ec-9a18-250b118fce48', 'name': 'vn2', 'tenant_id': '804fb8c25c1943439e334ebeb7688603', 'project_id': '804fb8c25c1943439e334ebeb7688603', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-30192116', 'vn2'], 'port_security_enabled': True, 'description': ''}}
2026-06-05 18:33:07,838 - DEBUG - Response for create_subnet : {'subnet': {'id': '00fa346c-273d-47cd-8b0b-0bc109873489', 'name': '', 'tenant_id': '804fb8c25c1943439e334ebeb7688603', 'network_id': 'e8934e1b-5970-45ec-9a18-250b118fce48', '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': '804fb8c25c1943439e334ebeb7688603'}}
2026-06-05 18:33:07,866 - INFO - Created VN vn2
2026-06-05 18:33:07,878 - DEBUG - VN vn2 UUID is e8934e1b-5970-45ec-9a18-250b118fce48
2026-06-05 18:33:07,995 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-30192116: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-30192116: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]
2026-06-05 18:33:08,342 - INFO - Attaching policy sec_grp_policy_ctest-random-47424805 to vn vn1
2026-06-05 18:33:08,710 - INFO - Attaching policy sec_grp_policy_ctest-random-47424805 to vn vn2
2026-06-05 18:33:10,404 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5896-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5896-1)
2026-06-05 18:33:12,108 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5896-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5896-2)
2026-06-05 18:33:12,171 - INFO - ================================================================================
2026-06-05 18:33:12,171 - INFO - STARTING TEST : test_create_md5_on_control
2026-06-05 18:33:12,171 - INFO - TEST DESCRIPTION :
Description: Verify md5 with allow specific protocol on all ports and policy with allow all between VN's
2026-06-05 18:33:13,457 - DEBUG - Skipping xmpp flap check
2026-06-05 18:33:13,458 - INFO - Initial checks done. Running the testcase now
2026-06-05 18:33:13,458 - INFO -
2026-06-05 18:33:13,459 - INFO - config md5 on each host with auth_data None
2026-06-05 18:33:13,459 - INFO - config per peer with auth_data None
2026-06-05 18:33:14,055 - INFO - configure MD5 on host 1060464b-d87e-40b6-96fe-3b4f11b741f9
2026-06-05 18:33:14,147 - INFO - config per peer with auth_data None
2026-06-05 18:33:14,787 - INFO - configure MD5 on host 179415df-598f-4f0b-9f7b-d4934cfb508f
2026-06-05 18:33:14,879 - INFO - config per peer with auth_data None
2026-06-05 18:33:15,503 - INFO - configure MD5 on host 92eefd9d-6925-4658-b50d-35dd5edcee41
2026-06-05 18:33:15,583 - INFO - check if BGP between peers are up before setting md5
2026-06-05 18:33:15,583 - INFO - Check BGP staus between peers
2026-06-05 18:33:15,601 - INFO - setting MD5 config on host 1060464b-d87e-40b6-96fe-3b4f11b741f9
2026-06-05 18:33:15,601 - INFO - configure MD5 on host 1060464b-d87e-40b6-96fe-3b4f11b741f9
2026-06-05 18:33:15,679 - INFO - setting MD5 config on host 179415df-598f-4f0b-9f7b-d4934cfb508f
2026-06-05 18:33:15,680 - INFO - configure MD5 on host 179415df-598f-4f0b-9f7b-d4934cfb508f
2026-06-05 18:33:15,763 - INFO - setting MD5 config on host 92eefd9d-6925-4658-b50d-35dd5edcee41
2026-06-05 18:33:15,763 - INFO - configure MD5 on host 92eefd9d-6925-4658-b50d-35dd5edcee41
2026-06-05 18:33:15,843 - INFO - check if BGP peering is up after md5 config
2026-06-05 18:33:15,843 - INFO - Check BGP staus between peers
2026-06-05 18:33:15,853 - DEBUG - Check the BGP connection on 10.0.0.33
2026-06-05 18:33:15,853 - DEBUG - Check the BGP connection on 10.0.0.33
2026-06-05 18:33:25,854 - INFO - Check BGP staus between peers
2026-06-05 18:33:25,865 - INFO - remove md5 configured on host 1060464b-d87e-40b6-96fe-3b4f11b741f9
2026-06-05 18:33:25,866 - INFO - config per peer with auth_data None
2026-06-05 18:33:26,447 - INFO - configure MD5 on host 1060464b-d87e-40b6-96fe-3b4f11b741f9
2026-06-05 18:33:26,531 - INFO - remove md5 configured on host 179415df-598f-4f0b-9f7b-d4934cfb508f
2026-06-05 18:33:26,531 - INFO - config per peer with auth_data None
2026-06-05 18:33:27,144 - INFO - configure MD5 on host 179415df-598f-4f0b-9f7b-d4934cfb508f
2026-06-05 18:33:27,259 - INFO - remove md5 configured on host 92eefd9d-6925-4658-b50d-35dd5edcee41
2026-06-05 18:33:27,259 - INFO - config per peer with auth_data None
2026-06-05 18:33:27,855 - INFO - configure MD5 on host 92eefd9d-6925-4658-b50d-35dd5edcee41
2026-06-05 18:33:27,935 - INFO - Skip interface_detach for VM vn2_vm_0 in state BUILD
2026-06-05 18:33:27,935 - INFO - Deleting VM vn2_vm_0
2026-06-05 18:33:27,936 - ERROR - VM vn2_vm_0 has failed to come up
2026-06-05 18:33:27,937 - ERROR - Nova failed to get host of the VM
2026-06-05 18:33:27,937 - ERROR - VM vn2_vm_0 has failed to come up
2026-06-05 18:33:27,937 - ERROR - Nova failed to get host of the VM
2026-06-05 18:33:28,029 - INFO - Skip interface_detach for VM vn1_vm_0 in state BUILD
2026-06-05 18:33:28,029 - INFO - Deleting VM vn1_vm_0
2026-06-05 18:33:28,030 - ERROR - VM vn1_vm_0 has failed to come up
2026-06-05 18:33:28,030 - ERROR - Nova failed to get host of the VM
2026-06-05 18:33:28,030 - ERROR - VM vn1_vm_0 has failed to come up
2026-06-05 18:33:28,030 - ERROR - Nova failed to get host of the VM
2026-06-05 18:33:28,109 - INFO - Dettaching policy sec_grp_policy_ctest-random-47424805 from vn vn2
2026-06-05 18:33:28,303 - INFO - Dettaching policy sec_grp_policy_ctest-random-47424805 from vn vn1
2026-06-05 18:33:28,596 - INFO - Deleted policy sec_grp_policy_ctest-random-47424805
2026-06-05 18:33:28,596 - INFO - Deleting VN vn2
2026-06-05 18:33:28,645 - DEBUG - VN e8934e1b-5970-45ec-9a18-250b118fce48 still in use: Unable to complete operation on network e8934e1b-5970-45ec-9a18-250b118fce48. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-3ed8c98c-e35c-4a42-b03c-355ffcf70e13']
2026-06-05 18:33:28,645 - WARNING - Deleting VN vn2 failed..Will retry
2026-06-05 18:33:30,797 - DEBUG - Response for deleting network ()
2026-06-05 18:33:30,797 - INFO - Deleting VN vn1
2026-06-05 18:33:30,959 - DEBUG - Response for deleting network ()
2026-06-05 18:33:30,959 - INFO - Dettaching policy allow_all from vn test_vnv6dn
2026-06-05 18:33:31,145 - INFO - Dettaching policy allow_all from vn test_vnv6sr
2026-06-05 18:33:31,422 - INFO - Deleted policy allow_all
2026-06-05 18:33:31,422 - INFO - Deleting VM dest_vm
2026-06-05 18:33:31,515 - INFO - Deleting VM source_vm
2026-06-05 18:33:31,605 - INFO - Deleting VN test_vnv6dn
2026-06-05 18:33:31,658 - DEBUG - VN f9177958-aaaa-4438-9c4d-cd9e739a2f36 still in use: Unable to complete operation on network f9177958-aaaa-4438-9c4d-cd9e739a2f36. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-7be6f736-e942-4d48-a9be-8c8fb238e9a4']
2026-06-05 18:33:31,658 - WARNING - Deleting VN test_vnv6dn failed..Will retry
2026-06-05 18:33:33,871 - DEBUG - Response for deleting network ()
2026-06-05 18:33:33,872 - INFO - Deleting VN test_vnv6sr
2026-06-05 18:33:34,065 - DEBUG - Response for deleting network ()
2026-06-05 18:33:35,341 - DEBUG - Skipping xmpp flap check
2026-06-05 18:33:35,341 - INFO - END TEST : test_create_md5_on_control : PASSED[0:00:23]
2026-06-05 18:33:35,341 - INFO - --------------------------------------------------------------------------------
2026-06-05 18:33:36,125 - INFO - Deleted project: ctest-TestMd5testsOnControl-30192116, ID : 804fb8c2-5c19-4343-9e33-4ebeb7688603