2026-01-26 04:01:23,199 - INFO - Domain Default found not creating
2026-01-26 04:01:23,348 - INFO - Project ctest-TestMd5testsOnControl-22435960 not found, creating it
2026-01-26 04:01:23,900 - INFO - Created Project:ctest-TestMd5testsOnControl-22435960, ID : 89fde83d-6348-46db-99bd-9e38c4e4305f
2026-01-26 04:01:26,141 - DEBUG - Response for create_network : {'network': {'id': 'aa09a928-0f77-431d-a3a1-5cb9c8928662', 'name': 'test_vnv6sr', 'tenant_id': '89fde83d634846db99bd9e38c4e4305f', 'project_id': '89fde83d634846db99bd9e38c4e4305f', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-22435960', 'test_vnv6sr'], 'port_security_enabled': True, 'description': ''}}
2026-01-26 04:01:26,422 - DEBUG - Response for create_subnet : {'subnet': {'id': 'a828ffe2-978f-4f98-bd96-ca5cd5b63e3d', 'name': '', 'tenant_id': '89fde83d634846db99bd9e38c4e4305f', 'network_id': 'aa09a928-0f77-431d-a3a1-5cb9c8928662', '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': '89fde83d634846db99bd9e38c4e4305f'}}
2026-01-26 04:01:26,681 - DEBUG - Response for create_subnet : {'subnet': {'id': '66635136-299b-4c24-9a66-4dc8c3fe9580', 'name': '', 'tenant_id': '89fde83d634846db99bd9e38c4e4305f', 'network_id': 'aa09a928-0f77-431d-a3a1-5cb9c8928662', 'ip_version': 4, 'cidr': '103.111.41.128/26', 'allocation_pools': [{'start': '103.111.41.130', 'end': '103.111.41.190'}], 'gateway_ip': '103.111.41.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '103.111.41.130', 'tags': [], 'project_id': '89fde83d634846db99bd9e38c4e4305f'}}
2026-01-26 04:01:26,706 - INFO - Created VN test_vnv6sr
2026-01-26 04:01:26,721 - DEBUG - VN test_vnv6sr UUID is aa09a928-0f77-431d-a3a1-5cb9c8928662
2026-01-26 04:01:27,113 - DEBUG - Response for create_network : {'network': {'id': '34ea6763-6af7-448e-8f70-852c429648e1', 'name': 'test_vnv6dn', 'tenant_id': '89fde83d634846db99bd9e38c4e4305f', 'project_id': '89fde83d634846db99bd9e38c4e4305f', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-22435960', 'test_vnv6dn'], 'port_security_enabled': True, 'description': ''}}
2026-01-26 04:01:27,365 - DEBUG - Response for create_subnet : {'subnet': {'id': '5e7b96fc-3941-49fc-bef3-d1e465ff3026', 'name': '', 'tenant_id': '89fde83d634846db99bd9e38c4e4305f', 'network_id': '34ea6763-6af7-448e-8f70-852c429648e1', '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': '89fde83d634846db99bd9e38c4e4305f'}}
2026-01-26 04:01:27,588 - DEBUG - Response for create_subnet : {'subnet': {'id': '26db0b45-da32-4cdc-b7a7-acb3b777e321', 'name': '', 'tenant_id': '89fde83d634846db99bd9e38c4e4305f', 'network_id': '34ea6763-6af7-448e-8f70-852c429648e1', 'ip_version': 4, 'cidr': '219.198.150.0/26', 'allocation_pools': [{'start': '219.198.150.2', 'end': '219.198.150.62'}], 'gateway_ip': '219.198.150.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '219.198.150.2', 'tags': [], 'project_id': '89fde83d634846db99bd9e38c4e4305f'}}
2026-01-26 04:01:27,611 - INFO - Created VN test_vnv6dn
2026-01-26 04:01:27,628 - DEBUG - VN test_vnv6dn UUID is 34ea6763-6af7-448e-8f70-852c429648e1
2026-01-26 04:01:27,905 - DEBUG - Services list from nova: [, , , ]
2026-01-26 04:01:29,041 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5076-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5076-1)
2026-01-26 04:01:30,514 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5076-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5076-2)
2026-01-26 04:01:30,514 - INFO - Waiting for VM source_vm to be up..
2026-01-26 04:01:30,608 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2026-01-26 04:01:35,698 - DEBUG - VM is in ACTIVE state now
2026-01-26 04:01:35,698 - INFO - VM name : source_vm
2026-01-26 04:01:35,784 - DEBUG - VM source_vm ID is c173a110-e7f5-4574-9924-7c8a94b959e8
2026-01-26 04:01:35,808 - DEBUG - VM source_vm launched on Node an-jenkins-deploy-platform-ansible-os-5076-1
2026-01-26 04:01:35,887 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-machine/c173a110-e7f5-4574-9924-7c8a94b959e8
2026-01-26 04:01:36,183 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-machine/c173a110-e7f5-4574-9924-7c8a94b959e8
2026-01-26 04:01:36,223 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-machine-interface/10420f07-6f9f-4e58-afc7-3da401142a2e
2026-01-26 04:01:39,403 - DEBUG - (True, 'PING 169.254.0.7 (169.254.0.7) 56(84) bytes of data.\r\n\r\n--- 169.254.0.7 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1018ms')
2026-01-26 04:01:39,404 - DEBUG - Ping to Metadata IP 169.254.0.7 of VM source_vm failed!
2026-01-26 04:01:39,418 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-22435960:test_vnv6sr is 2001::101:1 and allocation pool is NOT set
2026-01-26 04:01:39,418 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-22435960:test_vnv6sr is 103.111.41.129 and allocation pool is NOT set
2026-01-26 04:01:41,480 - DEBUG - (True, 'PING 169.254.0.7 (169.254.0.7) 56(84) bytes of data.\r\n64 bytes from 169.254.0.7: icmp_seq=1 ttl=63 time=3.72 ms\r\n64 bytes from 169.254.0.7: icmp_seq=2 ttl=63 time=2.81 ms\r\n\r\n--- 169.254.0.7 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 2.808/3.264/3.720/0.456 ms')
2026-01-26 04:01:41,480 - INFO - Ping to Metadata IP 169.254.0.7 of VM source_vm passed
2026-01-26 04:01:41,552 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-01-26 04:01:41,552 - DEBUG - Waiting to SSH to VM source_vm, IP 103.111.41.131, Port 22
2026-01-26 04:01:41,721 - DEBUG - VM source_vm is ready for SSH connections
2026-01-26 04:01:41,721 - INFO - Waiting for VM dest_vm to be up..
2026-01-26 04:01:41,812 - DEBUG - VM is in ACTIVE state now
2026-01-26 04:01:41,813 - INFO - VM name : dest_vm
2026-01-26 04:01:41,898 - DEBUG - VM dest_vm ID is dd708ac0-4067-4e1b-9491-7ed949d9f15c
2026-01-26 04:01:41,898 - DEBUG - VM dest_vm launched on Node an-jenkins-deploy-platform-ansible-os-5076-2
2026-01-26 04:01:41,978 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-machine/dd708ac0-4067-4e1b-9491-7ed949d9f15c
2026-01-26 04:01:41,989 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-machine-interface/f3b3968a-dca7-4b9a-8883-a60cae481e1e
2026-01-26 04:01:43,152 - 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=5.79 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.437 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.437/3.111/5.785/2.674 ms')
2026-01-26 04:01:43,152 - INFO - Ping to Metadata IP 169.254.0.3 of VM dest_vm passed
2026-01-26 04:01:43,226 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-01-26 04:01:43,226 - DEBUG - Waiting to SSH to VM dest_vm, IP 219.198.150.3, Port 22
2026-01-26 04:01:43,382 - DEBUG - VM dest_vm is ready for SSH connections
2026-01-26 04:01:43,389 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-22435960: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-22435960: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-01-26 04:01:43,736 - INFO - Attaching policy allow_all to vn test_vnv6sr
2026-01-26 04:01:44,108 - INFO - Attaching policy allow_all to vn test_vnv6dn
2026-01-26 04:01:44,348 - INFO - Configure the policy with allow any
2026-01-26 04:01:44,679 - DEBUG - Response for create_network : {'network': {'id': 'b56c8c6b-fd9b-4914-9182-b4562ef5c948', 'name': 'vn1', 'tenant_id': '89fde83d634846db99bd9e38c4e4305f', 'project_id': '89fde83d634846db99bd9e38c4e4305f', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-22435960', 'vn1'], 'port_security_enabled': True, 'description': ''}}
2026-01-26 04:01:44,923 - DEBUG - Response for create_subnet : {'subnet': {'id': 'ecc9631f-4b58-427c-baea-16a62b43b16e', 'name': '', 'tenant_id': '89fde83d634846db99bd9e38c4e4305f', 'network_id': 'b56c8c6b-fd9b-4914-9182-b4562ef5c948', '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': '89fde83d634846db99bd9e38c4e4305f'}}
2026-01-26 04:01:44,946 - INFO - Created VN vn1
2026-01-26 04:01:44,965 - DEBUG - VN vn1 UUID is b56c8c6b-fd9b-4914-9182-b4562ef5c948
2026-01-26 04:01:45,352 - DEBUG - Response for create_network : {'network': {'id': 'ccb78ffa-418a-4c24-b0de-0dd163fbadde', 'name': 'vn2', 'tenant_id': '89fde83d634846db99bd9e38c4e4305f', 'project_id': '89fde83d634846db99bd9e38c4e4305f', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-22435960', 'vn2'], 'port_security_enabled': True, 'description': ''}}
2026-01-26 04:01:45,587 - DEBUG - Response for create_subnet : {'subnet': {'id': '6f3c70dd-4f01-495a-a355-babba83ce109', 'name': '', 'tenant_id': '89fde83d634846db99bd9e38c4e4305f', 'network_id': 'ccb78ffa-418a-4c24-b0de-0dd163fbadde', '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': '89fde83d634846db99bd9e38c4e4305f'}}
2026-01-26 04:01:45,609 - INFO - Created VN vn2
2026-01-26 04:01:45,621 - DEBUG - VN vn2 UUID is ccb78ffa-418a-4c24-b0de-0dd163fbadde
2026-01-26 04:01:45,705 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-22435960: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-22435960: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-01-26 04:01:46,052 - INFO - Attaching policy sec_grp_policy_ctest-random-14515823 to vn vn1
2026-01-26 04:01:46,408 - INFO - Attaching policy sec_grp_policy_ctest-random-14515823 to vn vn2
2026-01-26 04:01:48,039 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5076-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5076-1)
2026-01-26 04:01:49,502 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5076-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5076-2)
2026-01-26 04:01:49,565 - INFO - ================================================================================
2026-01-26 04:01:49,565 - INFO - STARTING TEST : test_check_per_peer_on_control
2026-01-26 04:01:49,565 - INFO - TEST DESCRIPTION :
Description: Verify per peer md5 and specific protocol on all ports and policy with allow all between VN's
2026-01-26 04:01:50,853 - DEBUG - Skipping xmpp flap check
2026-01-26 04:01:50,853 - INFO - Initial checks done. Running the testcase now
2026-01-26 04:01:50,854 - INFO -
2026-01-26 04:01:50,854 - INFO - config per peer with auth_data None
2026-01-26 04:01:51,444 - INFO - configure MD5 on host ef128445-9078-45dd-b2de-e5e937a7fa48
2026-01-26 04:01:51,548 - INFO - config per peer with auth_data None
2026-01-26 04:01:52,236 - INFO - configure MD5 on host f5998afc-9d26-4c48-a958-0449b00d74bc
2026-01-26 04:01:52,328 - INFO - config per peer with auth_data None
2026-01-26 04:01:52,948 - INFO - configure MD5 on host a3c33c6d-7ffe-49b1-80b2-a5b8825f0115
2026-01-26 04:01:53,044 - INFO - check BGP peering is up before setting md5 config
2026-01-26 04:01:53,045 - INFO - Check BGP staus between peers
2026-01-26 04:01:53,045 - DEBUG - Requesting: http://10.0.0.141:8083/Snh_BgpNeighborReq?domain=&ip_address=
2026-01-26 04:01:53,057 - INFO - config per peer with auth_data {'key_items': [{'key': 'juniper', 'key_id': 0}], 'key_type': 'md5'}
2026-01-26 04:01:53,725 - INFO - Check BGP staus between peers
2026-01-26 04:01:53,725 - DEBUG - Requesting: http://10.0.0.141:8083/Snh_BgpNeighborReq?domain=&ip_address=
2026-01-26 04:01:53,745 - DEBUG - Check the BGP connection on 10.0.0.141
2026-01-26 04:01:53,745 - DEBUG - Check the BGP connection on 10.0.0.141
2026-01-26 04:02:03,745 - INFO - Check BGP staus between peers
2026-01-26 04:02:03,745 - DEBUG - Requesting: http://10.0.0.141:8083/Snh_BgpNeighborReq?domain=&ip_address=
2026-01-26 04:02:03,757 - INFO - remove md5 configured on host ef128445-9078-45dd-b2de-e5e937a7fa48
2026-01-26 04:02:03,763 - INFO - config per peer with auth_data None
2026-01-26 04:02:04,408 - INFO - configure MD5 on host ef128445-9078-45dd-b2de-e5e937a7fa48
2026-01-26 04:02:04,513 - INFO - remove md5 configured on host f5998afc-9d26-4c48-a958-0449b00d74bc
2026-01-26 04:02:04,513 - INFO - config per peer with auth_data None
2026-01-26 04:02:05,128 - INFO - configure MD5 on host f5998afc-9d26-4c48-a958-0449b00d74bc
2026-01-26 04:02:05,216 - INFO - remove md5 configured on host a3c33c6d-7ffe-49b1-80b2-a5b8825f0115
2026-01-26 04:02:05,216 - INFO - config per peer with auth_data None
2026-01-26 04:02:05,836 - INFO - configure MD5 on host a3c33c6d-7ffe-49b1-80b2-a5b8825f0115
2026-01-26 04:02:05,928 - INFO - Skip interface_detach for VM vn2_vm_0 in state BUILD
2026-01-26 04:02:05,928 - INFO - Deleting VM vn2_vm_0
2026-01-26 04:02:05,929 - ERROR - VM vn2_vm_0 has failed to come up
2026-01-26 04:02:05,929 - ERROR - Nova failed to get host of the VM
2026-01-26 04:02:05,929 - ERROR - VM vn2_vm_0 has failed to come up
2026-01-26 04:02:05,929 - ERROR - Nova failed to get host of the VM
2026-01-26 04:02:06,005 - INFO - Skip interface_detach for VM vn1_vm_0 in state BUILD
2026-01-26 04:02:06,006 - INFO - Deleting VM vn1_vm_0
2026-01-26 04:02:06,006 - ERROR - VM vn1_vm_0 has failed to come up
2026-01-26 04:02:06,006 - ERROR - Nova failed to get host of the VM
2026-01-26 04:02:06,006 - ERROR - VM vn1_vm_0 has failed to come up
2026-01-26 04:02:06,006 - ERROR - Nova failed to get host of the VM
2026-01-26 04:02:06,088 - INFO - Dettaching policy sec_grp_policy_ctest-random-14515823 from vn vn2
2026-01-26 04:02:06,245 - INFO - Dettaching policy sec_grp_policy_ctest-random-14515823 from vn vn1
2026-01-26 04:02:06,482 - INFO - Deleted policy sec_grp_policy_ctest-random-14515823
2026-01-26 04:02:06,483 - INFO - Deleting VN vn2
2026-01-26 04:02:06,549 - DEBUG - VN ccb78ffa-418a-4c24-b0de-0dd163fbadde still in use: Unable to complete operation on network ccb78ffa-418a-4c24-b0de-0dd163fbadde. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-f5af781a-bbba-454b-b926-c8e6cdd5432e']
2026-01-26 04:02:06,549 - WARNING - Deleting VN vn2 failed..Will retry
2026-01-26 04:02:08,712 - DEBUG - Response for deleting network ()
2026-01-26 04:02:08,712 - INFO - Deleting VN vn1
2026-01-26 04:02:08,901 - DEBUG - Response for deleting network ()
2026-01-26 04:02:08,901 - INFO - Dettaching policy allow_all from vn test_vnv6dn
2026-01-26 04:02:09,063 - INFO - Dettaching policy allow_all from vn test_vnv6sr
2026-01-26 04:02:09,303 - INFO - Deleted policy allow_all
2026-01-26 04:02:09,303 - INFO - Deleting VM dest_vm
2026-01-26 04:02:09,371 - INFO - Deleting VM source_vm
2026-01-26 04:02:09,448 - INFO - Deleting VN test_vnv6dn
2026-01-26 04:02:09,499 - DEBUG - VN 34ea6763-6af7-448e-8f70-852c429648e1 still in use: Unable to complete operation on network 34ea6763-6af7-448e-8f70-852c429648e1. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-452106ff-ff71-4a35-977c-e2e2f7874f23']
2026-01-26 04:02:09,499 - WARNING - Deleting VN test_vnv6dn failed..Will retry
2026-01-26 04:02:11,669 - DEBUG - Response for deleting network ()
2026-01-26 04:02:11,669 - INFO - Deleting VN test_vnv6sr
2026-01-26 04:02:11,849 - DEBUG - Response for deleting network ()
2026-01-26 04:02:13,113 - DEBUG - Skipping xmpp flap check
2026-01-26 04:02:13,114 - INFO - END TEST : test_check_per_peer_on_control : PASSED[0:00:24]
2026-01-26 04:02:13,114 - INFO - --------------------------------------------------------------------------------
2026-01-26 04:02:13,395 - DEBUG - Response for create_network : {'network': {'id': 'd986145f-0f5c-4559-8611-9120c0730b36', 'name': 'test_vnv6sr', 'tenant_id': '89fde83d634846db99bd9e38c4e4305f', 'project_id': '89fde83d634846db99bd9e38c4e4305f', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-22435960', 'test_vnv6sr'], 'port_security_enabled': True, 'description': ''}}
2026-01-26 04:02:13,626 - DEBUG - Response for create_subnet : {'subnet': {'id': 'b7983a4f-fc77-46e1-9ad9-ebe23acd735d', 'name': '', 'tenant_id': '89fde83d634846db99bd9e38c4e4305f', 'network_id': 'd986145f-0f5c-4559-8611-9120c0730b36', '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': '89fde83d634846db99bd9e38c4e4305f'}}
2026-01-26 04:02:13,832 - DEBUG - Response for create_subnet : {'subnet': {'id': 'acc6a144-3975-489c-9ecf-0f3118cd8529', 'name': '', 'tenant_id': '89fde83d634846db99bd9e38c4e4305f', 'network_id': 'd986145f-0f5c-4559-8611-9120c0730b36', 'ip_version': 4, 'cidr': '221.161.109.128/26', 'allocation_pools': [{'start': '221.161.109.130', 'end': '221.161.109.190'}], 'gateway_ip': '221.161.109.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '221.161.109.130', 'tags': [], 'project_id': '89fde83d634846db99bd9e38c4e4305f'}}
2026-01-26 04:02:13,858 - INFO - Created VN test_vnv6sr
2026-01-26 04:02:13,871 - DEBUG - VN test_vnv6sr UUID is d986145f-0f5c-4559-8611-9120c0730b36
2026-01-26 04:02:14,265 - DEBUG - Response for create_network : {'network': {'id': 'ee96b165-a955-4f76-8197-b6fecb9a5fb1', 'name': 'test_vnv6dn', 'tenant_id': '89fde83d634846db99bd9e38c4e4305f', 'project_id': '89fde83d634846db99bd9e38c4e4305f', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-22435960', 'test_vnv6dn'], 'port_security_enabled': True, 'description': ''}}
2026-01-26 04:02:14,499 - DEBUG - Response for create_subnet : {'subnet': {'id': '03b3ed1f-9d01-4f75-af59-9e39d482f60d', 'name': '', 'tenant_id': '89fde83d634846db99bd9e38c4e4305f', 'network_id': 'ee96b165-a955-4f76-8197-b6fecb9a5fb1', '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': '89fde83d634846db99bd9e38c4e4305f'}}
2026-01-26 04:02:14,722 - DEBUG - Response for create_subnet : {'subnet': {'id': '631817f0-37cb-42ff-83d4-1e6be5473a58', 'name': '', 'tenant_id': '89fde83d634846db99bd9e38c4e4305f', 'network_id': 'ee96b165-a955-4f76-8197-b6fecb9a5fb1', 'ip_version': 4, 'cidr': '8.19.214.0/26', 'allocation_pools': [{'start': '8.19.214.2', 'end': '8.19.214.62'}], 'gateway_ip': '8.19.214.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '8.19.214.2', 'tags': [], 'project_id': '89fde83d634846db99bd9e38c4e4305f'}}
2026-01-26 04:02:14,750 - INFO - Created VN test_vnv6dn
2026-01-26 04:02:14,763 - DEBUG - VN test_vnv6dn UUID is ee96b165-a955-4f76-8197-b6fecb9a5fb1
2026-01-26 04:02:16,113 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5076-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5076-1)
2026-01-26 04:02:17,548 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5076-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5076-2)
2026-01-26 04:02:17,548 - INFO - Waiting for VM source_vm to be up..
2026-01-26 04:02:17,632 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2026-01-26 04:02:22,723 - DEBUG - VM is in ACTIVE state now
2026-01-26 04:02:22,723 - INFO - VM name : source_vm
2026-01-26 04:02:22,804 - DEBUG - VM source_vm ID is a9f821c3-3ff4-46ca-9470-0243324b4366
2026-01-26 04:02:22,804 - DEBUG - VM source_vm launched on Node an-jenkins-deploy-platform-ansible-os-5076-1
2026-01-26 04:02:22,892 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-machine/a9f821c3-3ff4-46ca-9470-0243324b4366
2026-01-26 04:02:22,905 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-machine-interface/a3d350c9-76bd-4fe2-a827-e61009c600c7
2026-01-26 04:02:26,097 - DEBUG - (True, 'PING 169.254.0.7 (169.254.0.7) 56(84) bytes of data.\r\n\r\n--- 169.254.0.7 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1022ms')
2026-01-26 04:02:26,097 - DEBUG - Ping to Metadata IP 169.254.0.7 of VM source_vm failed!
2026-01-26 04:02:26,115 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-22435960:test_vnv6sr is 2001::101:1 and allocation pool is NOT set
2026-01-26 04:02:26,115 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-22435960:test_vnv6sr is 221.161.109.129 and allocation pool is NOT set
2026-01-26 04:02:28,186 - DEBUG - (True, 'PING 169.254.0.7 (169.254.0.7) 56(84) bytes of data.\r\n64 bytes from 169.254.0.7: icmp_seq=1 ttl=63 time=2.85 ms\r\n64 bytes from 169.254.0.7: icmp_seq=2 ttl=63 time=0.407 ms\r\n\r\n--- 169.254.0.7 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 0.407/1.629/2.851/1.222 ms')
2026-01-26 04:02:28,186 - INFO - Ping to Metadata IP 169.254.0.7 of VM source_vm passed
2026-01-26 04:02:28,265 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-01-26 04:02:28,266 - DEBUG - Waiting to SSH to VM source_vm, IP 221.161.109.131, Port 22
2026-01-26 04:02:28,437 - DEBUG - VM source_vm is ready for SSH connections
2026-01-26 04:02:28,437 - INFO - Waiting for VM dest_vm to be up..
2026-01-26 04:02:28,536 - DEBUG - VM is in ACTIVE state now
2026-01-26 04:02:28,536 - INFO - VM name : dest_vm
2026-01-26 04:02:28,641 - DEBUG - VM dest_vm ID is 8e907334-f459-4eef-913d-9a9063d4a4e6
2026-01-26 04:02:28,641 - DEBUG - VM dest_vm launched on Node an-jenkins-deploy-platform-ansible-os-5076-2
2026-01-26 04:02:28,731 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-machine/8e907334-f459-4eef-913d-9a9063d4a4e6
2026-01-26 04:02:28,742 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-machine-interface/6ef71c52-bb16-4014-9de1-13ab9196db44
2026-01-26 04:02:29,921 - 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=4.11 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.436 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.436/2.274/4.112/1.838 ms')
2026-01-26 04:02:29,921 - INFO - Ping to Metadata IP 169.254.0.3 of VM dest_vm passed
2026-01-26 04:02:30,098 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-01-26 04:02:30,098 - DEBUG - Waiting to SSH to VM dest_vm, IP 8.19.214.3, Port 22
2026-01-26 04:02:30,258 - DEBUG - VM dest_vm is ready for SSH connections
2026-01-26 04:02:30,264 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-22435960: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-22435960: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-01-26 04:02:30,599 - INFO - Attaching policy allow_all to vn test_vnv6sr
2026-01-26 04:02:30,966 - INFO - Attaching policy allow_all to vn test_vnv6dn
2026-01-26 04:02:31,235 - INFO - Configure the policy with allow any
2026-01-26 04:02:31,569 - DEBUG - Response for create_network : {'network': {'id': '9c223a03-9f8d-4611-9961-c7cf6b3565c5', 'name': 'vn1', 'tenant_id': '89fde83d634846db99bd9e38c4e4305f', 'project_id': '89fde83d634846db99bd9e38c4e4305f', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-22435960', 'vn1'], 'port_security_enabled': True, 'description': ''}}
2026-01-26 04:02:31,826 - DEBUG - Response for create_subnet : {'subnet': {'id': '1ab078aa-7fb9-4fbb-86fc-ef85ad6f5520', 'name': '', 'tenant_id': '89fde83d634846db99bd9e38c4e4305f', 'network_id': '9c223a03-9f8d-4611-9961-c7cf6b3565c5', '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': '89fde83d634846db99bd9e38c4e4305f'}}
2026-01-26 04:02:31,865 - INFO - Created VN vn1
2026-01-26 04:02:31,878 - DEBUG - VN vn1 UUID is 9c223a03-9f8d-4611-9961-c7cf6b3565c5
2026-01-26 04:02:32,276 - DEBUG - Response for create_network : {'network': {'id': '5cca664e-b1c9-4066-9212-30ac22738394', 'name': 'vn2', 'tenant_id': '89fde83d634846db99bd9e38c4e4305f', 'project_id': '89fde83d634846db99bd9e38c4e4305f', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-22435960', 'vn2'], 'port_security_enabled': True, 'description': ''}}
2026-01-26 04:02:32,499 - DEBUG - Response for create_subnet : {'subnet': {'id': '253f1c26-4849-4388-8e85-1c3a90636fe1', 'name': '', 'tenant_id': '89fde83d634846db99bd9e38c4e4305f', 'network_id': '5cca664e-b1c9-4066-9212-30ac22738394', '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': '89fde83d634846db99bd9e38c4e4305f'}}
2026-01-26 04:02:32,520 - INFO - Created VN vn2
2026-01-26 04:02:32,538 - DEBUG - VN vn2 UUID is 5cca664e-b1c9-4066-9212-30ac22738394
2026-01-26 04:02:32,619 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-22435960: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-22435960: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-01-26 04:02:32,981 - INFO - Attaching policy sec_grp_policy_ctest-random-86437226 to vn vn1
2026-01-26 04:02:33,348 - INFO - Attaching policy sec_grp_policy_ctest-random-86437226 to vn vn2
2026-01-26 04:02:34,958 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5076-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5076-1)
2026-01-26 04:02:36,411 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5076-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5076-2)
2026-01-26 04:02:36,472 - INFO - ================================================================================
2026-01-26 04:02:36,472 - INFO - STARTING TEST : test_create_md5_on_control
2026-01-26 04:02:36,472 - INFO - TEST DESCRIPTION :
Description: Verify md5 with allow specific protocol on all ports and policy with allow all between VN's
2026-01-26 04:02:37,781 - DEBUG - Skipping xmpp flap check
2026-01-26 04:02:37,781 - INFO - Initial checks done. Running the testcase now
2026-01-26 04:02:37,781 - INFO -
2026-01-26 04:02:37,782 - INFO - config md5 on each host with auth_data None
2026-01-26 04:02:37,782 - INFO - config per peer with auth_data None
2026-01-26 04:02:38,380 - INFO - configure MD5 on host ef128445-9078-45dd-b2de-e5e937a7fa48
2026-01-26 04:02:38,464 - INFO - config per peer with auth_data None
2026-01-26 04:02:39,068 - INFO - configure MD5 on host f5998afc-9d26-4c48-a958-0449b00d74bc
2026-01-26 04:02:39,172 - INFO - config per peer with auth_data None
2026-01-26 04:02:39,792 - INFO - configure MD5 on host a3c33c6d-7ffe-49b1-80b2-a5b8825f0115
2026-01-26 04:02:39,892 - INFO - check if BGP between peers are up before setting md5
2026-01-26 04:02:39,892 - INFO - Check BGP staus between peers
2026-01-26 04:02:39,892 - DEBUG - Requesting: http://10.0.0.141:8083/Snh_BgpNeighborReq?domain=&ip_address=
2026-01-26 04:02:39,907 - INFO - setting MD5 config on host ef128445-9078-45dd-b2de-e5e937a7fa48
2026-01-26 04:02:39,908 - INFO - configure MD5 on host ef128445-9078-45dd-b2de-e5e937a7fa48
2026-01-26 04:02:39,992 - INFO - setting MD5 config on host f5998afc-9d26-4c48-a958-0449b00d74bc
2026-01-26 04:02:39,992 - INFO - configure MD5 on host f5998afc-9d26-4c48-a958-0449b00d74bc
2026-01-26 04:02:40,073 - INFO - setting MD5 config on host a3c33c6d-7ffe-49b1-80b2-a5b8825f0115
2026-01-26 04:02:40,074 - INFO - configure MD5 on host a3c33c6d-7ffe-49b1-80b2-a5b8825f0115
2026-01-26 04:02:40,152 - INFO - check if BGP peering is up after md5 config
2026-01-26 04:02:40,152 - INFO - Check BGP staus between peers
2026-01-26 04:02:40,152 - DEBUG - Requesting: http://10.0.0.141:8083/Snh_BgpNeighborReq?domain=&ip_address=
2026-01-26 04:02:40,162 - DEBUG - Check the BGP connection on 10.0.0.141
2026-01-26 04:02:40,162 - DEBUG - Check the BGP connection on 10.0.0.141
2026-01-26 04:02:50,163 - INFO - Check BGP staus between peers
2026-01-26 04:02:50,163 - DEBUG - Requesting: http://10.0.0.141:8083/Snh_BgpNeighborReq?domain=&ip_address=
2026-01-26 04:02:50,174 - INFO - remove md5 configured on host ef128445-9078-45dd-b2de-e5e937a7fa48
2026-01-26 04:02:50,175 - INFO - config per peer with auth_data None
2026-01-26 04:02:50,748 - INFO - configure MD5 on host ef128445-9078-45dd-b2de-e5e937a7fa48
2026-01-26 04:02:50,848 - INFO - remove md5 configured on host f5998afc-9d26-4c48-a958-0449b00d74bc
2026-01-26 04:02:50,848 - INFO - config per peer with auth_data None
2026-01-26 04:02:51,464 - INFO - configure MD5 on host f5998afc-9d26-4c48-a958-0449b00d74bc
2026-01-26 04:02:51,572 - INFO - remove md5 configured on host a3c33c6d-7ffe-49b1-80b2-a5b8825f0115
2026-01-26 04:02:51,572 - INFO - config per peer with auth_data None
2026-01-26 04:02:52,212 - INFO - configure MD5 on host a3c33c6d-7ffe-49b1-80b2-a5b8825f0115
2026-01-26 04:02:52,300 - INFO - Skip interface_detach for VM vn2_vm_0 in state BUILD
2026-01-26 04:02:52,300 - INFO - Deleting VM vn2_vm_0
2026-01-26 04:02:52,300 - ERROR - VM vn2_vm_0 has failed to come up
2026-01-26 04:02:52,300 - ERROR - Nova failed to get host of the VM
2026-01-26 04:02:52,300 - ERROR - VM vn2_vm_0 has failed to come up
2026-01-26 04:02:52,300 - ERROR - Nova failed to get host of the VM
2026-01-26 04:02:52,378 - INFO - Skip interface_detach for VM vn1_vm_0 in state BUILD
2026-01-26 04:02:52,380 - INFO - Deleting VM vn1_vm_0
2026-01-26 04:02:52,380 - ERROR - VM vn1_vm_0 has failed to come up
2026-01-26 04:02:52,380 - ERROR - Nova failed to get host of the VM
2026-01-26 04:02:52,380 - ERROR - VM vn1_vm_0 has failed to come up
2026-01-26 04:02:52,380 - ERROR - Nova failed to get host of the VM
2026-01-26 04:02:52,448 - INFO - Dettaching policy sec_grp_policy_ctest-random-86437226 from vn vn2
2026-01-26 04:02:52,612 - INFO - Dettaching policy sec_grp_policy_ctest-random-86437226 from vn vn1
2026-01-26 04:02:52,898 - INFO - Deleted policy sec_grp_policy_ctest-random-86437226
2026-01-26 04:02:52,898 - INFO - Deleting VN vn2
2026-01-26 04:02:52,953 - DEBUG - VN 5cca664e-b1c9-4066-9212-30ac22738394 still in use: Unable to complete operation on network 5cca664e-b1c9-4066-9212-30ac22738394. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-a4882786-e036-4693-b3e7-2f71630dd751']
2026-01-26 04:02:52,953 - WARNING - Deleting VN vn2 failed..Will retry
2026-01-26 04:02:55,096 - DEBUG - Response for deleting network ()
2026-01-26 04:02:55,096 - INFO - Deleting VN vn1
2026-01-26 04:02:55,244 - DEBUG - Response for deleting network ()
2026-01-26 04:02:55,244 - INFO - Dettaching policy allow_all from vn test_vnv6dn
2026-01-26 04:02:55,391 - INFO - Dettaching policy allow_all from vn test_vnv6sr
2026-01-26 04:02:55,620 - INFO - Deleted policy allow_all
2026-01-26 04:02:55,620 - INFO - Deleting VM dest_vm
2026-01-26 04:02:55,707 - INFO - Deleting VM source_vm
2026-01-26 04:02:55,775 - INFO - Deleting VN test_vnv6dn
2026-01-26 04:02:55,836 - DEBUG - VN ee96b165-a955-4f76-8197-b6fecb9a5fb1 still in use: Unable to complete operation on network ee96b165-a955-4f76-8197-b6fecb9a5fb1. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-6ce9f288-122a-4658-ba96-0da300d862e3']
2026-01-26 04:02:55,836 - WARNING - Deleting VN test_vnv6dn failed..Will retry
2026-01-26 04:02:58,036 - DEBUG - Response for deleting network ()
2026-01-26 04:02:58,036 - INFO - Deleting VN test_vnv6sr
2026-01-26 04:02:58,212 - DEBUG - Response for deleting network ()
2026-01-26 04:02:59,471 - DEBUG - Skipping xmpp flap check
2026-01-26 04:02:59,471 - INFO - END TEST : test_create_md5_on_control : PASSED[0:00:23]
2026-01-26 04:02:59,471 - INFO - --------------------------------------------------------------------------------
2026-01-26 04:03:00,239 - INFO - Deleted project: ctest-TestMd5testsOnControl-22435960, ID : 89fde83d-6348-46db-99bd-9e38c4e4305f