2025-08-14 03:52:01,341 - INFO - Domain Default found not creating
2025-08-14 03:52:01,469 - INFO - Project ctest-TestMd5testsOnControl-18066671 not found, creating it
2025-08-14 03:52:01,981 - INFO - Created Project:ctest-TestMd5testsOnControl-18066671, ID : 84e75b72-ae3a-4aae-9ad3-107822cb1e25
2025-08-14 03:52:03,954 - DEBUG - Response for create_network : {'network': {'id': 'a632b4dc-c716-422c-b4aa-9d595baf7db0', 'name': 'test_vnv6sr', 'tenant_id': '84e75b72ae3a4aae9ad3107822cb1e25', 'project_id': '84e75b72ae3a4aae9ad3107822cb1e25', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-18066671', 'test_vnv6sr'], 'port_security_enabled': True, 'description': ''}}
2025-08-14 03:52:04,191 - DEBUG - Response for create_subnet : {'subnet': {'id': '5bc9bbd9-3a81-42f8-ae35-b8295612c717', 'name': '', 'tenant_id': '84e75b72ae3a4aae9ad3107822cb1e25', 'network_id': 'a632b4dc-c716-422c-b4aa-9d595baf7db0', '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': '84e75b72ae3a4aae9ad3107822cb1e25'}}
2025-08-14 03:52:04,389 - DEBUG - Response for create_subnet : {'subnet': {'id': 'ea905458-ea61-4c9b-a029-27fe3d8461e5', 'name': '', 'tenant_id': '84e75b72ae3a4aae9ad3107822cb1e25', 'network_id': 'a632b4dc-c716-422c-b4aa-9d595baf7db0', 'ip_version': 4, 'cidr': '203.78.208.64/26', 'allocation_pools': [{'start': '203.78.208.66', 'end': '203.78.208.126'}], 'gateway_ip': '203.78.208.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '203.78.208.66', 'tags': [], 'project_id': '84e75b72ae3a4aae9ad3107822cb1e25'}}
2025-08-14 03:52:04,419 - INFO - Created VN test_vnv6sr
2025-08-14 03:52:04,432 - DEBUG - VN test_vnv6sr UUID is a632b4dc-c716-422c-b4aa-9d595baf7db0
2025-08-14 03:52:04,756 - DEBUG - Response for create_network : {'network': {'id': 'ce8b087d-cb9a-456b-adbd-20f09782e25b', 'name': 'test_vnv6dn', 'tenant_id': '84e75b72ae3a4aae9ad3107822cb1e25', 'project_id': '84e75b72ae3a4aae9ad3107822cb1e25', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-18066671', 'test_vnv6dn'], 'port_security_enabled': True, 'description': ''}}
2025-08-14 03:52:05,053 - DEBUG - Response for create_subnet : {'subnet': {'id': 'cb0dc328-6bee-4377-bb4e-a647919da1a4', 'name': '', 'tenant_id': '84e75b72ae3a4aae9ad3107822cb1e25', 'network_id': 'ce8b087d-cb9a-456b-adbd-20f09782e25b', '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': '84e75b72ae3a4aae9ad3107822cb1e25'}}
2025-08-14 03:52:05,287 - DEBUG - Response for create_subnet : {'subnet': {'id': '882aaef0-94bb-4440-95e5-e55e1944e2d2', 'name': '', 'tenant_id': '84e75b72ae3a4aae9ad3107822cb1e25', 'network_id': 'ce8b087d-cb9a-456b-adbd-20f09782e25b', 'ip_version': 4, 'cidr': '135.153.161.192/26', 'allocation_pools': [{'start': '135.153.161.194', 'end': '135.153.161.254'}], 'gateway_ip': '135.153.161.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '135.153.161.194', 'tags': [], 'project_id': '84e75b72ae3a4aae9ad3107822cb1e25'}}
2025-08-14 03:52:05,309 - INFO - Created VN test_vnv6dn
2025-08-14 03:52:05,320 - DEBUG - VN test_vnv6dn UUID is ce8b087d-cb9a-456b-adbd-20f09782e25b
2025-08-14 03:52:05,464 - DEBUG - Services list from nova: [, , , ]
2025-08-14 03:52:06,461 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3851-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3851-2)
2025-08-14 03:52:07,742 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3851-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3851-1)
2025-08-14 03:52:07,742 - INFO - Waiting for VM source_vm to be up..
2025-08-14 03:52:07,836 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2025-08-14 03:52:12,912 - DEBUG - VM is in ACTIVE state now
2025-08-14 03:52:12,913 - INFO - VM name : source_vm
2025-08-14 03:52:12,992 - DEBUG - VM source_vm ID is 14522c12-fe2a-4c0c-bcf2-a1387a0030f3
2025-08-14 03:52:13,016 - DEBUG - VM source_vm launched on Node an-jenkins-deploy-platform-ansible-os-3851-2
2025-08-14 03:52:13,097 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/14522c12-fe2a-4c0c-bcf2-a1387a0030f3
2025-08-14 03:52:13,466 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/14522c12-fe2a-4c0c-bcf2-a1387a0030f3
2025-08-14 03:52:13,503 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/764d839c-5c43-4de0-bcde-ed5efccea5f4
2025-08-14 03:52:16,681 - 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 1020ms')
2025-08-14 03:52:16,682 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM source_vm failed!
2025-08-14 03:52:16,696 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-18066671:test_vnv6sr is 2001::101:1 and allocation pool is NOT set
2025-08-14 03:52:16,696 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-18066671:test_vnv6sr is 203.78.208.65 and allocation pool is NOT set
2025-08-14 03:52:18,759 - 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.42 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.449 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.449/1.932/3.415/1.483 ms')
2025-08-14 03:52:18,759 - INFO - Ping to Metadata IP 169.254.0.3 of VM source_vm passed
2025-08-14 03:52:18,828 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-14 03:52:18,829 - DEBUG - Waiting to SSH to VM source_vm, IP 203.78.208.67, Port 22
2025-08-14 03:52:18,978 - DEBUG - VM source_vm is ready for SSH connections
2025-08-14 03:52:18,979 - INFO - Waiting for VM dest_vm to be up..
2025-08-14 03:52:19,069 - DEBUG - VM is in ACTIVE state now
2025-08-14 03:52:19,070 - INFO - VM name : dest_vm
2025-08-14 03:52:19,176 - DEBUG - VM dest_vm ID is 668a9b6c-0623-4db3-af06-d95c9274aa51
2025-08-14 03:52:19,176 - DEBUG - VM dest_vm launched on Node an-jenkins-deploy-platform-ansible-os-3851-1
2025-08-14 03:52:19,257 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/668a9b6c-0623-4db3-af06-d95c9274aa51
2025-08-14 03:52:19,266 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/a289ed4d-cb79-4a37-bb22-7145c03dab9d
2025-08-14 03:52:20,425 - 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.04 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.379 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1000ms\r\nrtt min/avg/max/mdev = 0.379/1.710/3.042/1.331 ms')
2025-08-14 03:52:20,425 - INFO - Ping to Metadata IP 169.254.0.3 of VM dest_vm passed
2025-08-14 03:52:20,496 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-14 03:52:20,496 - DEBUG - Waiting to SSH to VM dest_vm, IP 135.153.161.195, Port 22
2025-08-14 03:52:20,654 - DEBUG - VM dest_vm is ready for SSH connections
2025-08-14 03:52:20,660 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-18066671: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-18066671: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-08-14 03:52:21,003 - INFO - Attaching policy allow_all to vn test_vnv6sr
2025-08-14 03:52:21,358 - INFO - Attaching policy allow_all to vn test_vnv6dn
2025-08-14 03:52:21,630 - INFO - Configure the policy with allow any
2025-08-14 03:52:21,922 - DEBUG - Response for create_network : {'network': {'id': '3c4fad84-229d-4ab5-9d43-5d530770d7e3', 'name': 'vn1', 'tenant_id': '84e75b72ae3a4aae9ad3107822cb1e25', 'project_id': '84e75b72ae3a4aae9ad3107822cb1e25', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-18066671', 'vn1'], 'port_security_enabled': True, 'description': ''}}
2025-08-14 03:52:22,167 - DEBUG - Response for create_subnet : {'subnet': {'id': 'ddce36a6-8472-4c6e-8612-f6d4aa1258cb', 'name': '', 'tenant_id': '84e75b72ae3a4aae9ad3107822cb1e25', 'network_id': '3c4fad84-229d-4ab5-9d43-5d530770d7e3', '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': '84e75b72ae3a4aae9ad3107822cb1e25'}}
2025-08-14 03:52:22,205 - INFO - Created VN vn1
2025-08-14 03:52:22,216 - DEBUG - VN vn1 UUID is 3c4fad84-229d-4ab5-9d43-5d530770d7e3
2025-08-14 03:52:23,030 - DEBUG - Response for create_network : {'network': {'id': '0faeefb9-ab42-41aa-a9c9-740e05f6cba3', 'name': 'vn2', 'tenant_id': '84e75b72ae3a4aae9ad3107822cb1e25', 'project_id': '84e75b72ae3a4aae9ad3107822cb1e25', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-18066671', 'vn2'], 'port_security_enabled': True, 'description': ''}}
2025-08-14 03:52:23,516 - DEBUG - Response for create_subnet : {'subnet': {'id': 'c0ffd803-91ca-41f9-bd56-11ffa3fbbc10', 'name': '', 'tenant_id': '84e75b72ae3a4aae9ad3107822cb1e25', 'network_id': '0faeefb9-ab42-41aa-a9c9-740e05f6cba3', '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': '84e75b72ae3a4aae9ad3107822cb1e25'}}
2025-08-14 03:52:23,537 - INFO - Created VN vn2
2025-08-14 03:52:23,560 - DEBUG - VN vn2 UUID is 0faeefb9-ab42-41aa-a9c9-740e05f6cba3
2025-08-14 03:52:23,643 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-18066671: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-18066671: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-08-14 03:52:23,979 - INFO - Attaching policy sec_grp_policy_ctest-random-64651991 to vn vn1
2025-08-14 03:52:24,334 - INFO - Attaching policy sec_grp_policy_ctest-random-64651991 to vn vn2
2025-08-14 03:52:25,789 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3851-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3851-2)
2025-08-14 03:52:27,144 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3851-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3851-1)
2025-08-14 03:52:27,219 - INFO - ================================================================================
2025-08-14 03:52:27,219 - INFO - STARTING TEST : test_check_per_peer_on_control
2025-08-14 03:52:27,220 - INFO - TEST DESCRIPTION :
Description: Verify per peer md5 and specific protocol on all ports and policy with allow all between VN's
2025-08-14 03:52:28,515 - DEBUG - Skipping xmpp flap check
2025-08-14 03:52:28,515 - INFO - Initial checks done. Running the testcase now
2025-08-14 03:52:28,515 - INFO -
2025-08-14 03:52:28,515 - INFO - config per peer with auth_data None
2025-08-14 03:52:29,063 - INFO - configure MD5 on host 40aac936-6984-4457-91e1-02433c15575c
2025-08-14 03:52:29,147 - INFO - config per peer with auth_data None
2025-08-14 03:52:29,759 - INFO - configure MD5 on host 5c0c967d-6810-47d2-b93f-d689d69b728d
2025-08-14 03:52:29,859 - INFO - config per peer with auth_data None
2025-08-14 03:52:30,463 - INFO - configure MD5 on host e6aef833-9cc4-42ae-83b8-db75af5e77ab
2025-08-14 03:52:30,539 - INFO - check BGP peering is up before setting md5 config
2025-08-14 03:52:30,540 - INFO - Check BGP staus between peers
2025-08-14 03:52:30,554 - INFO - config per peer with auth_data {'key_items': [{'key': 'juniper', 'key_id': 0}], 'key_type': 'md5'}
2025-08-14 03:52:31,171 - INFO - Check BGP staus between peers
2025-08-14 03:52:31,183 - DEBUG - Check the BGP connection on 10.0.0.38
2025-08-14 03:52:31,183 - DEBUG - Check the BGP connection on 10.0.0.38
2025-08-14 03:52:41,184 - INFO - Check BGP staus between peers
2025-08-14 03:52:41,205 - INFO - remove md5 configured on host 40aac936-6984-4457-91e1-02433c15575c
2025-08-14 03:52:41,207 - INFO - config per peer with auth_data None
2025-08-14 03:52:41,771 - INFO - configure MD5 on host 40aac936-6984-4457-91e1-02433c15575c
2025-08-14 03:52:41,847 - INFO - remove md5 configured on host 5c0c967d-6810-47d2-b93f-d689d69b728d
2025-08-14 03:52:41,848 - INFO - config per peer with auth_data None
2025-08-14 03:52:42,443 - INFO - configure MD5 on host 5c0c967d-6810-47d2-b93f-d689d69b728d
2025-08-14 03:52:42,551 - INFO - remove md5 configured on host e6aef833-9cc4-42ae-83b8-db75af5e77ab
2025-08-14 03:52:42,552 - INFO - config per peer with auth_data None
2025-08-14 03:52:43,107 - INFO - configure MD5 on host e6aef833-9cc4-42ae-83b8-db75af5e77ab
2025-08-14 03:52:43,195 - INFO - Skip interface_detach for VM vn2_vm_0 in state BUILD
2025-08-14 03:52:43,196 - INFO - Deleting VM vn2_vm_0
2025-08-14 03:52:43,196 - ERROR - VM vn2_vm_0 has failed to come up
2025-08-14 03:52:43,196 - ERROR - Nova failed to get host of the VM
2025-08-14 03:52:43,196 - ERROR - VM vn2_vm_0 has failed to come up
2025-08-14 03:52:43,196 - ERROR - Nova failed to get host of the VM
2025-08-14 03:52:43,264 - INFO - Skip interface_detach for VM vn1_vm_0 in state BUILD
2025-08-14 03:52:43,264 - INFO - Deleting VM vn1_vm_0
2025-08-14 03:52:43,265 - ERROR - VM vn1_vm_0 has failed to come up
2025-08-14 03:52:43,265 - ERROR - Nova failed to get host of the VM
2025-08-14 03:52:43,265 - ERROR - VM vn1_vm_0 has failed to come up
2025-08-14 03:52:43,266 - ERROR - Nova failed to get host of the VM
2025-08-14 03:52:43,330 - INFO - Dettaching policy sec_grp_policy_ctest-random-64651991 from vn vn2
2025-08-14 03:52:43,503 - INFO - Dettaching policy sec_grp_policy_ctest-random-64651991 from vn vn1
2025-08-14 03:52:43,740 - INFO - Deleted policy sec_grp_policy_ctest-random-64651991
2025-08-14 03:52:43,740 - INFO - Deleting VN vn2
2025-08-14 03:52:43,792 - DEBUG - VN 0faeefb9-ab42-41aa-a9c9-740e05f6cba3 still in use: Unable to complete operation on network 0faeefb9-ab42-41aa-a9c9-740e05f6cba3. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-7057d442-8c01-444b-8d1c-b07f77a70ef5']
2025-08-14 03:52:43,792 - WARNING - Deleting VN vn2 failed..Will retry
2025-08-14 03:52:45,974 - DEBUG - Response for deleting network ()
2025-08-14 03:52:45,974 - INFO - Deleting VN vn1
2025-08-14 03:52:46,150 - DEBUG - Response for deleting network ()
2025-08-14 03:52:46,150 - INFO - Dettaching policy allow_all from vn test_vnv6dn
2025-08-14 03:52:46,374 - INFO - Dettaching policy allow_all from vn test_vnv6sr
2025-08-14 03:52:46,661 - INFO - Deleted policy allow_all
2025-08-14 03:52:46,661 - INFO - Deleting VM dest_vm
2025-08-14 03:52:46,762 - INFO - Deleting VM source_vm
2025-08-14 03:52:46,840 - INFO - Deleting VN test_vnv6dn
2025-08-14 03:52:46,903 - DEBUG - VN ce8b087d-cb9a-456b-adbd-20f09782e25b still in use: Unable to complete operation on network ce8b087d-cb9a-456b-adbd-20f09782e25b. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-81ab7579-4530-4885-8f06-2d3dc20930a2']
2025-08-14 03:52:46,903 - WARNING - Deleting VN test_vnv6dn failed..Will retry
2025-08-14 03:52:49,069 - DEBUG - Response for deleting network ()
2025-08-14 03:52:49,070 - INFO - Deleting VN test_vnv6sr
2025-08-14 03:52:49,264 - DEBUG - Response for deleting network ()
2025-08-14 03:52:50,500 - DEBUG - Skipping xmpp flap check
2025-08-14 03:52:50,500 - INFO - END TEST : test_check_per_peer_on_control : PASSED[0:00:23]
2025-08-14 03:52:50,500 - INFO - --------------------------------------------------------------------------------
2025-08-14 03:52:50,786 - DEBUG - Response for create_network : {'network': {'id': '6908d015-c35e-453a-855b-e7efa72e1841', 'name': 'test_vnv6sr', 'tenant_id': '84e75b72ae3a4aae9ad3107822cb1e25', 'project_id': '84e75b72ae3a4aae9ad3107822cb1e25', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-18066671', 'test_vnv6sr'], 'port_security_enabled': True, 'description': ''}}
2025-08-14 03:52:51,036 - DEBUG - Response for create_subnet : {'subnet': {'id': '354669b3-b251-4d49-b0ed-b14ced1b7d45', 'name': '', 'tenant_id': '84e75b72ae3a4aae9ad3107822cb1e25', 'network_id': '6908d015-c35e-453a-855b-e7efa72e1841', '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': '84e75b72ae3a4aae9ad3107822cb1e25'}}
2025-08-14 03:52:51,300 - DEBUG - Response for create_subnet : {'subnet': {'id': '3e9e860f-3730-457a-b787-1ad551510b70', 'name': '', 'tenant_id': '84e75b72ae3a4aae9ad3107822cb1e25', 'network_id': '6908d015-c35e-453a-855b-e7efa72e1841', 'ip_version': 4, 'cidr': '18.178.51.192/26', 'allocation_pools': [{'start': '18.178.51.194', 'end': '18.178.51.254'}], 'gateway_ip': '18.178.51.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '18.178.51.194', 'tags': [], 'project_id': '84e75b72ae3a4aae9ad3107822cb1e25'}}
2025-08-14 03:52:51,325 - INFO - Created VN test_vnv6sr
2025-08-14 03:52:51,340 - DEBUG - VN test_vnv6sr UUID is 6908d015-c35e-453a-855b-e7efa72e1841
2025-08-14 03:52:51,752 - DEBUG - Response for create_network : {'network': {'id': '76368e7e-a638-48b2-b37c-8bb6073490ca', 'name': 'test_vnv6dn', 'tenant_id': '84e75b72ae3a4aae9ad3107822cb1e25', 'project_id': '84e75b72ae3a4aae9ad3107822cb1e25', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-18066671', 'test_vnv6dn'], 'port_security_enabled': True, 'description': ''}}
2025-08-14 03:52:52,026 - DEBUG - Response for create_subnet : {'subnet': {'id': 'f32fd556-624a-40ae-8532-65487392e06e', 'name': '', 'tenant_id': '84e75b72ae3a4aae9ad3107822cb1e25', 'network_id': '76368e7e-a638-48b2-b37c-8bb6073490ca', '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': '84e75b72ae3a4aae9ad3107822cb1e25'}}
2025-08-14 03:52:52,311 - DEBUG - Response for create_subnet : {'subnet': {'id': '29b3a39c-477e-4c88-825e-5d59feece791', 'name': '', 'tenant_id': '84e75b72ae3a4aae9ad3107822cb1e25', 'network_id': '76368e7e-a638-48b2-b37c-8bb6073490ca', 'ip_version': 4, 'cidr': '46.220.119.64/26', 'allocation_pools': [{'start': '46.220.119.66', 'end': '46.220.119.126'}], 'gateway_ip': '46.220.119.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '46.220.119.66', 'tags': [], 'project_id': '84e75b72ae3a4aae9ad3107822cb1e25'}}
2025-08-14 03:52:52,336 - INFO - Created VN test_vnv6dn
2025-08-14 03:52:52,352 - DEBUG - VN test_vnv6dn UUID is 76368e7e-a638-48b2-b37c-8bb6073490ca
2025-08-14 03:52:53,515 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3851-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3851-2)
2025-08-14 03:52:54,749 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3851-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3851-1)
2025-08-14 03:52:54,749 - INFO - Waiting for VM source_vm to be up..
2025-08-14 03:52:54,831 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2025-08-14 03:52:59,904 - DEBUG - VM is in ACTIVE state now
2025-08-14 03:52:59,905 - INFO - VM name : source_vm
2025-08-14 03:52:59,975 - DEBUG - VM source_vm ID is 844cfa8c-0655-4981-9ae4-d52b4c3b205f
2025-08-14 03:52:59,976 - DEBUG - VM source_vm launched on Node an-jenkins-deploy-platform-ansible-os-3851-2
2025-08-14 03:53:00,071 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/844cfa8c-0655-4981-9ae4-d52b4c3b205f
2025-08-14 03:53:00,084 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/0355985b-46f9-4d72-a857-6dc743dfd0b6
2025-08-14 03:53:03,244 - 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 1004ms')
2025-08-14 03:53:03,244 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM source_vm failed!
2025-08-14 03:53:03,259 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-18066671:test_vnv6sr is 2001::101:1 and allocation pool is NOT set
2025-08-14 03:53:03,259 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-18066671:test_vnv6sr is 18.178.51.193 and allocation pool is NOT set
2025-08-14 03:53:05,320 - 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.13 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.418 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1000ms\r\nrtt min/avg/max/mdev = 0.418/2.276/4.134/1.858 ms')
2025-08-14 03:53:05,320 - INFO - Ping to Metadata IP 169.254.0.3 of VM source_vm passed
2025-08-14 03:53:05,390 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-14 03:53:05,390 - DEBUG - Waiting to SSH to VM source_vm, IP 18.178.51.195, Port 22
2025-08-14 03:53:05,547 - DEBUG - VM source_vm is ready for SSH connections
2025-08-14 03:53:05,547 - INFO - Waiting for VM dest_vm to be up..
2025-08-14 03:53:05,628 - DEBUG - VM is in ACTIVE state now
2025-08-14 03:53:05,628 - INFO - VM name : dest_vm
2025-08-14 03:53:05,703 - DEBUG - VM dest_vm ID is 581b2aa4-a1ac-4bea-a8d8-b8627db32101
2025-08-14 03:53:05,703 - DEBUG - VM dest_vm launched on Node an-jenkins-deploy-platform-ansible-os-3851-1
2025-08-14 03:53:05,786 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/581b2aa4-a1ac-4bea-a8d8-b8627db32101
2025-08-14 03:53:05,797 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/e4498405-dd9e-4984-92af-f8db1d37d8ca
2025-08-14 03:53:06,959 - 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.67 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.462 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.462/2.565/4.668/2.103 ms')
2025-08-14 03:53:06,960 - INFO - Ping to Metadata IP 169.254.0.3 of VM dest_vm passed
2025-08-14 03:53:07,028 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-14 03:53:07,028 - DEBUG - Waiting to SSH to VM dest_vm, IP 46.220.119.67, Port 22
2025-08-14 03:53:07,186 - DEBUG - VM dest_vm is ready for SSH connections
2025-08-14 03:53:07,193 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-18066671: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-18066671: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-08-14 03:53:07,538 - INFO - Attaching policy allow_all to vn test_vnv6sr
2025-08-14 03:53:07,899 - INFO - Attaching policy allow_all to vn test_vnv6dn
2025-08-14 03:53:08,214 - INFO - Configure the policy with allow any
2025-08-14 03:53:08,535 - DEBUG - Response for create_network : {'network': {'id': '739b13e6-0e7a-419b-9741-46f93caae0f1', 'name': 'vn1', 'tenant_id': '84e75b72ae3a4aae9ad3107822cb1e25', 'project_id': '84e75b72ae3a4aae9ad3107822cb1e25', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-18066671', 'vn1'], 'port_security_enabled': True, 'description': ''}}
2025-08-14 03:53:08,770 - DEBUG - Response for create_subnet : {'subnet': {'id': '874e995a-3311-4d62-8a29-5903b2ab2982', 'name': '', 'tenant_id': '84e75b72ae3a4aae9ad3107822cb1e25', 'network_id': '739b13e6-0e7a-419b-9741-46f93caae0f1', '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': '84e75b72ae3a4aae9ad3107822cb1e25'}}
2025-08-14 03:53:08,792 - INFO - Created VN vn1
2025-08-14 03:53:08,804 - DEBUG - VN vn1 UUID is 739b13e6-0e7a-419b-9741-46f93caae0f1
2025-08-14 03:53:09,121 - DEBUG - Response for create_network : {'network': {'id': 'ab3173e2-8dce-4ad0-b130-6fe287cb8021', 'name': 'vn2', 'tenant_id': '84e75b72ae3a4aae9ad3107822cb1e25', 'project_id': '84e75b72ae3a4aae9ad3107822cb1e25', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-18066671', 'vn2'], 'port_security_enabled': True, 'description': ''}}
2025-08-14 03:53:09,361 - DEBUG - Response for create_subnet : {'subnet': {'id': '7f4a34ee-6433-47a1-b75b-ce606988f1ea', 'name': '', 'tenant_id': '84e75b72ae3a4aae9ad3107822cb1e25', 'network_id': 'ab3173e2-8dce-4ad0-b130-6fe287cb8021', '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': '84e75b72ae3a4aae9ad3107822cb1e25'}}
2025-08-14 03:53:09,383 - INFO - Created VN vn2
2025-08-14 03:53:09,396 - DEBUG - VN vn2 UUID is ab3173e2-8dce-4ad0-b130-6fe287cb8021
2025-08-14 03:53:09,512 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-18066671: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-18066671: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-08-14 03:53:09,832 - INFO - Attaching policy sec_grp_policy_ctest-random-25943939 to vn vn1
2025-08-14 03:53:10,211 - INFO - Attaching policy sec_grp_policy_ctest-random-25943939 to vn vn2
2025-08-14 03:53:11,785 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3851-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3851-2)
2025-08-14 03:53:13,377 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3851-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3851-1)
2025-08-14 03:53:13,441 - INFO - ================================================================================
2025-08-14 03:53:13,441 - INFO - STARTING TEST : test_create_md5_on_control
2025-08-14 03:53:13,441 - INFO - TEST DESCRIPTION :
Description: Verify md5 with allow specific protocol on all ports and policy with allow all between VN's
2025-08-14 03:53:14,727 - DEBUG - Skipping xmpp flap check
2025-08-14 03:53:14,727 - INFO - Initial checks done. Running the testcase now
2025-08-14 03:53:14,727 - INFO -
2025-08-14 03:53:14,727 - INFO - config md5 on each host with auth_data None
2025-08-14 03:53:14,727 - INFO - config per peer with auth_data None
2025-08-14 03:53:15,316 - INFO - configure MD5 on host 40aac936-6984-4457-91e1-02433c15575c
2025-08-14 03:53:15,427 - INFO - config per peer with auth_data None
2025-08-14 03:53:16,055 - INFO - configure MD5 on host 5c0c967d-6810-47d2-b93f-d689d69b728d
2025-08-14 03:53:16,139 - INFO - config per peer with auth_data None
2025-08-14 03:53:16,704 - INFO - configure MD5 on host e6aef833-9cc4-42ae-83b8-db75af5e77ab
2025-08-14 03:53:16,795 - INFO - check if BGP between peers are up before setting md5
2025-08-14 03:53:16,796 - INFO - Check BGP staus between peers
2025-08-14 03:53:16,807 - INFO - setting MD5 config on host 40aac936-6984-4457-91e1-02433c15575c
2025-08-14 03:53:16,807 - INFO - configure MD5 on host 40aac936-6984-4457-91e1-02433c15575c
2025-08-14 03:53:16,883 - INFO - setting MD5 config on host 5c0c967d-6810-47d2-b93f-d689d69b728d
2025-08-14 03:53:16,884 - INFO - configure MD5 on host 5c0c967d-6810-47d2-b93f-d689d69b728d
2025-08-14 03:53:16,955 - INFO - setting MD5 config on host e6aef833-9cc4-42ae-83b8-db75af5e77ab
2025-08-14 03:53:16,956 - INFO - configure MD5 on host e6aef833-9cc4-42ae-83b8-db75af5e77ab
2025-08-14 03:53:17,043 - INFO - check if BGP peering is up after md5 config
2025-08-14 03:53:17,044 - INFO - Check BGP staus between peers
2025-08-14 03:53:17,054 - DEBUG - Check the BGP connection on 10.0.0.38
2025-08-14 03:53:17,054 - DEBUG - Check the BGP connection on 10.0.0.38
2025-08-14 03:53:27,055 - INFO - Check BGP staus between peers
2025-08-14 03:53:27,067 - INFO - remove md5 configured on host 40aac936-6984-4457-91e1-02433c15575c
2025-08-14 03:53:27,067 - INFO - config per peer with auth_data None
2025-08-14 03:53:27,692 - INFO - configure MD5 on host 40aac936-6984-4457-91e1-02433c15575c
2025-08-14 03:53:27,784 - INFO - remove md5 configured on host 5c0c967d-6810-47d2-b93f-d689d69b728d
2025-08-14 03:53:27,784 - INFO - config per peer with auth_data None
2025-08-14 03:53:28,416 - INFO - configure MD5 on host 5c0c967d-6810-47d2-b93f-d689d69b728d
2025-08-14 03:53:28,536 - INFO - remove md5 configured on host e6aef833-9cc4-42ae-83b8-db75af5e77ab
2025-08-14 03:53:28,537 - INFO - config per peer with auth_data None
2025-08-14 03:53:29,188 - INFO - configure MD5 on host e6aef833-9cc4-42ae-83b8-db75af5e77ab
2025-08-14 03:53:29,296 - INFO - Skip interface_detach for VM vn2_vm_0 in state BUILD
2025-08-14 03:53:29,296 - INFO - Deleting VM vn2_vm_0
2025-08-14 03:53:29,298 - ERROR - VM vn2_vm_0 has failed to come up
2025-08-14 03:53:29,298 - ERROR - Nova failed to get host of the VM
2025-08-14 03:53:29,299 - ERROR - VM vn2_vm_0 has failed to come up
2025-08-14 03:53:29,299 - ERROR - Nova failed to get host of the VM
2025-08-14 03:53:29,394 - INFO - Skip interface_detach for VM vn1_vm_0 in state BUILD
2025-08-14 03:53:29,394 - INFO - Deleting VM vn1_vm_0
2025-08-14 03:53:29,394 - ERROR - VM vn1_vm_0 has failed to come up
2025-08-14 03:53:29,394 - ERROR - Nova failed to get host of the VM
2025-08-14 03:53:29,394 - ERROR - VM vn1_vm_0 has failed to come up
2025-08-14 03:53:29,395 - ERROR - Nova failed to get host of the VM
2025-08-14 03:53:29,475 - INFO - Dettaching policy sec_grp_policy_ctest-random-25943939 from vn vn2
2025-08-14 03:53:29,660 - INFO - Dettaching policy sec_grp_policy_ctest-random-25943939 from vn vn1
2025-08-14 03:53:29,948 - INFO - Deleted policy sec_grp_policy_ctest-random-25943939
2025-08-14 03:53:29,948 - INFO - Deleting VN vn2
2025-08-14 03:53:30,009 - DEBUG - VN ab3173e2-8dce-4ad0-b130-6fe287cb8021 still in use: Unable to complete operation on network ab3173e2-8dce-4ad0-b130-6fe287cb8021. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-2b97a41d-9aed-479f-bac5-228126b6065f']
2025-08-14 03:53:30,009 - WARNING - Deleting VN vn2 failed..Will retry
2025-08-14 03:53:32,167 - DEBUG - Response for deleting network ()
2025-08-14 03:53:32,167 - INFO - Deleting VN vn1
2025-08-14 03:53:32,340 - DEBUG - Response for deleting network ()
2025-08-14 03:53:32,341 - INFO - Dettaching policy allow_all from vn test_vnv6dn
2025-08-14 03:53:32,528 - INFO - Dettaching policy allow_all from vn test_vnv6sr
2025-08-14 03:53:32,799 - INFO - Deleted policy allow_all
2025-08-14 03:53:32,800 - INFO - Deleting VM dest_vm
2025-08-14 03:53:32,865 - INFO - Deleting VM source_vm
2025-08-14 03:53:32,927 - INFO - Deleting VN test_vnv6dn
2025-08-14 03:53:32,969 - DEBUG - VN 76368e7e-a638-48b2-b37c-8bb6073490ca still in use: Unable to complete operation on network 76368e7e-a638-48b2-b37c-8bb6073490ca. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-a8068ab3-83a8-4340-9b2b-897fda104cef']
2025-08-14 03:53:32,969 - WARNING - Deleting VN test_vnv6dn failed..Will retry
2025-08-14 03:53:35,146 - DEBUG - Response for deleting network ()
2025-08-14 03:53:35,146 - INFO - Deleting VN test_vnv6sr
2025-08-14 03:53:35,343 - DEBUG - Response for deleting network ()
2025-08-14 03:53:36,599 - DEBUG - Skipping xmpp flap check
2025-08-14 03:53:36,599 - INFO - END TEST : test_create_md5_on_control : PASSED[0:00:23]
2025-08-14 03:53:36,600 - INFO - --------------------------------------------------------------------------------
2025-08-14 03:53:37,308 - INFO - Deleted project: ctest-TestMd5testsOnControl-18066671, ID : 84e75b72-ae3a-4aae-9ad3-107822cb1e25