2026-06-08 06:24:28,120 - INFO - Domain Default found not creating
2026-06-08 06:24:28,305 - INFO - Project ctest-TestBasicVMVN-98691060 not found, creating it
2026-06-08 06:24:28,870 - INFO - Created Project:ctest-TestBasicVMVN-98691060, ID : 4126cccd-1ae8-41d5-881e-36a2062014b1
2026-06-08 06:24:30,394 - INFO - ================================================================================
2026-06-08 06:24:30,394 - INFO - STARTING TEST : test_generic_link_local_service
2026-06-08 06:24:30,394 - INFO - TEST DESCRIPTION :
Description: Test to validate generic linklocal service - running nova list from vm.
1.Create generic link local service to be able to wget to jenkins
2.Create a vm
3.Try wget to jenkins - passes if successful else fails
Maintainer: sandipd@juniper.net
2026-06-08 06:24:31,652 - WARNING - Test is running with cores: {'10.0.0.38': ['core.contrail-collec.1.cn-jenkins-deploy-platform-ansible-os-5902-1.1780898019'], '10.0.0.33': ['core.contrail-collec.1.cn-jenkins-deploy-platform-ansible-os-5902-3.1780898019']}
2026-06-08 06:24:31,664 - DEBUG - Nothing to compare xmpp stats {'10.0.0.249': {'10.20.0.14': '0', '10.20.0.254': '0'}, '10.0.0.22': {'10.20.0.254': '0', '10.20.0.17': '0'}} with
2026-06-08 06:24:31,664 - INFO - Initial checks done. Running the testcase now
2026-06-08 06:24:31,664 - INFO -
2026-06-08 06:24:32,384 - DEBUG - Response for create_network : {'network': {'id': '8c31ad95-4ea1-4d9f-8166-13bd21ec3c81', 'name': 'ctest-vn2_metadata-44091447', 'tenant_id': '4126cccd1ae841d5881e36a2062014b1', 'project_id': '4126cccd1ae841d5881e36a2062014b1', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicVMVN-98691060', 'ctest-vn2_metadata-44091447'], 'port_security_enabled': True, 'description': ''}}
2026-06-08 06:24:32,723 - DEBUG - Response for create_subnet : {'subnet': {'id': '1a095a63-ec82-413c-b516-cf92fea5d317', 'name': '', 'tenant_id': '4126cccd1ae841d5881e36a2062014b1', 'network_id': '8c31ad95-4ea1-4d9f-8166-13bd21ec3c81', 'ip_version': 4, 'cidr': '11.1.1.0/24', 'allocation_pools': [{'start': '11.1.1.2', 'end': '11.1.1.254'}], 'gateway_ip': '11.1.1.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '11.1.1.2', 'tags': [], 'project_id': '4126cccd1ae841d5881e36a2062014b1'}}
2026-06-08 06:24:32,756 - INFO - Created VN ctest-vn2_metadata-44091447
2026-06-08 06:24:32,770 - DEBUG - VN ctest-vn2_metadata-44091447 UUID is 8c31ad95-4ea1-4d9f-8166-13bd21ec3c81
2026-06-08 06:24:32,937 - DEBUG - Services list from nova: [, , , ]
2026-06-08 06:24:33,975 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5902-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5902-2)
2026-06-08 06:24:34,048 - DEBUG - Link local service introspect added
2026-06-08 06:24:34,097 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2026-06-08 06:24:39,195 - DEBUG - VM is in ACTIVE state now
2026-06-08 06:24:39,353 - INFO - Waiting for VM ctest-TestBasicVMVN-98691060-46290089 to be up..
2026-06-08 06:24:39,353 - INFO - VM name : ctest-TestBasicVMVN-98691060-46290089
2026-06-08 06:24:39,447 - DEBUG - VM ctest-TestBasicVMVN-98691060-46290089 ID is 0d87557d-d68e-424e-8684-09b1b72b9017
2026-06-08 06:24:39,447 - DEBUG - VM ctest-TestBasicVMVN-98691060-46290089 launched on Node an-jenkins-deploy-platform-ansible-os-5902-2
2026-06-08 06:24:39,550 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine/0d87557d-d68e-424e-8684-09b1b72b9017
2026-06-08 06:24:39,816 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine/0d87557d-d68e-424e-8684-09b1b72b9017
2026-06-08 06:24:39,855 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine-interface/ebc7fa9c-47df-421b-ab53-d25d79ce11b8
2026-06-08 06:24:43,038 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1019ms')
2026-06-08 06:24:43,038 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicVMVN-98691060-46290089 failed!
2026-06-08 06:24:43,057 - DEBUG - Gateway for vn default-domain:ctest-TestBasicVMVN-98691060:ctest-vn2_metadata-44091447 is 11.1.1.1 and allocation pool is NOT set
2026-06-08 06:24:45,127 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n64 bytes from 169.254.0.3: icmp_seq=1 ttl=63 time=8.99 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.490 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.490/4.739/8.988/4.249 ms')
2026-06-08 06:24:45,128 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicVMVN-98691060-46290089 passed
2026-06-08 06:24:45,199 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-08 06:24:45,199 - DEBUG - Waiting to SSH to VM ctest-TestBasicVMVN-98691060-46290089, IP 11.1.1.3, Port 22
2026-06-08 06:24:45,355 - DEBUG - VM ctest-TestBasicVMVN-98691060-46290089 is ready for SSH connections
2026-06-08 06:24:45,355 - INFO - Retry 0
2026-06-08 06:24:45,355 - DEBUG - Running remote_cmd, Cmd : wget http://169.254.1.2:8083 --spider && echo "Successful", host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.22, gateway password: c0ntrail123
2026-06-08 06:24:45,356 - DEBUG - wget http://169.254.1.2:8083 --spider && echo "Successful"
2026-06-08 06:24:47,348 - DEBUG - Connecting to 169.254.1.2:8083 (169.254.1.2:8083)
Successful
2026-06-08 06:24:47,348 - INFO - Generic metadata worked
2026-06-08 06:24:47,580 - DEBUG - Link local service introspect removed
2026-06-08 06:24:47,580 - INFO - Deleting VM ctest-TestBasicVMVN-98691060-46290089
2026-06-08 06:24:47,668 - INFO - Deleting VN ctest-vn2_metadata-44091447
2026-06-08 06:24:47,723 - DEBUG - VN 8c31ad95-4ea1-4d9f-8166-13bd21ec3c81 still in use: Unable to complete operation on network 8c31ad95-4ea1-4d9f-8166-13bd21ec3c81. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-9783812d-0b3a-4abd-a141-5adac1a9c188']
2026-06-08 06:24:47,723 - WARNING - Deleting VN ctest-vn2_metadata-44091447 failed..Will retry
2026-06-08 06:24:49,885 - DEBUG - Response for deleting network ()
2026-06-08 06:24:51,160 - DEBUG - No XMPP flaps were noticed during the test
2026-06-08 06:24:51,161 - INFO - END TEST : test_generic_link_local_service : PASSED[0:00:21]
2026-06-08 06:24:51,161 - INFO - --------------------------------------------------------------------------------
2026-06-08 06:24:51,163 - INFO - ================================================================================
2026-06-08 06:24:51,163 - INFO - STARTING TEST : test_metadata_service
2026-06-08 06:24:51,163 - INFO - TEST DESCRIPTION :
Description: Test to validate metadata service on VM creation.
1.Verify from global-vrouter-config if metadata configures or not - fails otherwise
2.Create a shell script which writes 'hello world ' in a file in /tmp and save the script on the nova api node
3.Create a vm with userdata pointing to that script - script should get executed during vm boot up
4.Go to the vm and verify if the file with 'hello world ' written saved in /tmp of the vm - fails otherwise
Maintainer: sandipd@juniper.net
2026-06-08 06:24:52,416 - WARNING - Test is running with cores: {'10.0.0.38': ['core.contrail-collec.1.cn-jenkins-deploy-platform-ansible-os-5902-1.1780898019'], '10.0.0.33': ['core.contrail-collec.1.cn-jenkins-deploy-platform-ansible-os-5902-3.1780898019']}
2026-06-08 06:24:52,431 - DEBUG - Nothing to compare xmpp stats {'10.0.0.249': {'10.20.0.14': '0', '10.20.0.254': '0'}, '10.0.0.22': {'10.20.0.254': '0', '10.20.0.17': '0'}} with
2026-06-08 06:24:52,432 - INFO - Initial checks done. Running the testcase now
2026-06-08 06:24:52,432 - INFO -
2026-06-08 06:24:52,432 - DEBUG - Requesting: http://10.0.0.38:8082/global-vrouter-configs
2026-06-08 06:24:52,701 - DEBUG - Requesting: http://10.0.0.38:8082/global-vrouter-configs
2026-06-08 06:24:52,739 - DEBUG - Requesting: http://10.0.0.38:8082/global-vrouter-config/23a45ed2-dae2-4599-b913-2bbfc1202082
2026-06-08 06:24:53,058 - DEBUG - Response for create_network : {'network': {'id': '5640a551-c45f-4c1e-9d7a-4bc629103540', 'name': 'ctest-vn-42907827', 'tenant_id': '4126cccd1ae841d5881e36a2062014b1', 'project_id': '4126cccd1ae841d5881e36a2062014b1', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicVMVN-98691060', 'ctest-vn-42907827'], 'port_security_enabled': True, 'description': ''}}
2026-06-08 06:24:53,329 - DEBUG - Response for create_subnet : {'subnet': {'id': 'a85db3d0-5db3-45d7-b466-c82300c72280', 'name': '', 'tenant_id': '4126cccd1ae841d5881e36a2062014b1', 'network_id': '5640a551-c45f-4c1e-9d7a-4bc629103540', 'ip_version': 4, 'cidr': '212.117.213.0/26', 'allocation_pools': [{'start': '212.117.213.2', 'end': '212.117.213.62'}], 'gateway_ip': '212.117.213.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '212.117.213.2', 'tags': [], 'project_id': '4126cccd1ae841d5881e36a2062014b1'}}
2026-06-08 06:24:53,355 - INFO - Created VN ctest-vn-42907827
2026-06-08 06:24:53,372 - DEBUG - VN ctest-vn-42907827 UUID is 5640a551-c45f-4c1e-9d7a-4bc629103540
2026-06-08 06:24:54,460 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5902-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5902-1)
2026-06-08 06:24:54,461 - INFO - Waiting for VM ctest-TestBasicVMVN-98691060-57090382 to be up..
2026-06-08 06:24:54,510 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2026-06-08 06:24:59,608 - DEBUG - VM is in ACTIVE state now
2026-06-08 06:24:59,608 - INFO - VM name : ctest-TestBasicVMVN-98691060-57090382
2026-06-08 06:24:59,830 - DEBUG - VM ctest-TestBasicVMVN-98691060-57090382 ID is 60dc3b6f-6180-49d2-85e1-523161bdc891
2026-06-08 06:24:59,830 - DEBUG - VM ctest-TestBasicVMVN-98691060-57090382 launched on Node an-jenkins-deploy-platform-ansible-os-5902-1
2026-06-08 06:25:00,069 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine/60dc3b6f-6180-49d2-85e1-523161bdc891
2026-06-08 06:25:00,085 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine-interface/36a7e838-d12d-4053-b098-4fc5f69376ad
2026-06-08 06:25:03,374 - 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-08 06:25:03,374 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicVMVN-98691060-57090382 failed!
2026-06-08 06:25:03,395 - DEBUG - Gateway for vn default-domain:ctest-TestBasicVMVN-98691060:ctest-vn-42907827 is 212.117.213.1 and allocation pool is NOT set
2026-06-08 06:25:07,461 - 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=2 ttl=63 time=7.21 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 1 received, 50% packet loss, time 1011ms\r\nrtt min/avg/max/mdev = 7.206/7.206/7.206/0.000 ms')
2026-06-08 06:25:07,462 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicVMVN-98691060-57090382 passed
2026-06-08 06:25:07,541 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-08 06:25:07,542 - DEBUG - Waiting to SSH to VM ctest-TestBasicVMVN-98691060-57090382, IP 212.117.213.3, Port 22
2026-06-08 06:25:07,699 - DEBUG - VM ctest-TestBasicVMVN-98691060-57090382 is ready for SSH connections
2026-06-08 06:25:07,699 - DEBUG - Running remote_cmd, Cmd : cat /tmp/output.txt, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.249, gateway password: c0ntrail123
2026-06-08 06:25:07,699 - DEBUG - cat /tmp/output.txt
2026-06-08 06:25:09,634 - DEBUG - Hello World. The time is now Mon, 08 Jun 2026 00:25:06 -0600!
2026-06-08 06:25:09,635 - DEBUG - ret : {'cat /tmp/output.txt': 'Hello World. The time is now Mon, 08 Jun 2026 00:25:06 -0600!'}
2026-06-08 06:25:09,635 - INFO - metadata_script.txt got executed in the vm
2026-06-08 06:25:09,635 - INFO - Deleting VM ctest-TestBasicVMVN-98691060-57090382
2026-06-08 06:25:09,723 - INFO - Deleting VN ctest-vn-42907827
2026-06-08 06:25:09,773 - DEBUG - VN 5640a551-c45f-4c1e-9d7a-4bc629103540 still in use: Unable to complete operation on network 5640a551-c45f-4c1e-9d7a-4bc629103540. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-68ff7d99-ca5d-4857-8085-273b3b93d952']
2026-06-08 06:25:09,773 - WARNING - Deleting VN ctest-vn-42907827 failed..Will retry
2026-06-08 06:25:11,959 - DEBUG - Response for deleting network ()
2026-06-08 06:25:13,269 - DEBUG - No XMPP flaps were noticed during the test
2026-06-08 06:25:13,270 - INFO - END TEST : test_metadata_service : PASSED[0:00:22]
2026-06-08 06:25:13,270 - INFO - --------------------------------------------------------------------------------
2026-06-08 06:25:13,274 - INFO - ================================================================================
2026-06-08 06:25:13,274 - INFO - STARTING TEST : test_ping_within_vn_two_vms_two_different_subnets
2026-06-08 06:25:13,274 - INFO - TEST DESCRIPTION :
Description: Validate Ping between 2 VMs in the same VN, 2 VMs in different VN
subnets.
Test steps:
1. Create 1 IPAM's.
2. Create 1 VN with 2 subnets and launch 2 VMs in them.
3. Ping between the VMs in the same VN should go thru fine.
4. Ping to the subnet broadcast and all-broadcast address.
Pass criteria: VM in the same subnet will respond to both the pings, while the VM in a different VN should respond only to the
all-broadcast address.
Maintainer : ganeshahv@juniper.net
2026-06-08 06:25:14,544 - WARNING - Test is running with cores: {'10.0.0.38': ['core.contrail-collec.1.cn-jenkins-deploy-platform-ansible-os-5902-1.1780898019'], '10.0.0.33': ['core.contrail-collec.1.cn-jenkins-deploy-platform-ansible-os-5902-3.1780898019']}
2026-06-08 06:25:14,556 - DEBUG - Nothing to compare xmpp stats {'10.0.0.249': {'10.20.0.14': '0', '10.20.0.254': '0'}, '10.0.0.22': {'10.20.0.254': '0', '10.20.0.17': '0'}} with
2026-06-08 06:25:14,556 - INFO - Initial checks done. Running the testcase now
2026-06-08 06:25:14,556 - INFO -
2026-06-08 06:25:15,196 - DEBUG - Response for create_network : {'network': {'id': 'afc4845f-43ed-49a2-8298-7b4b538c4aee', 'name': 'ctest-vn-89852346', 'tenant_id': '4126cccd1ae841d5881e36a2062014b1', 'project_id': '4126cccd1ae841d5881e36a2062014b1', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicVMVN-98691060', 'ctest-vn-89852346'], 'port_security_enabled': True, 'description': ''}}
2026-06-08 06:25:15,468 - DEBUG - Response for create_subnet : {'subnet': {'id': '0d09af0c-9a15-49c0-8b67-cd7c71b531c7', 'name': '', 'tenant_id': '4126cccd1ae841d5881e36a2062014b1', 'network_id': 'afc4845f-43ed-49a2-8298-7b4b538c4aee', 'ip_version': 4, 'cidr': '31.1.1.0/29', 'allocation_pools': [{'start': '31.1.1.2', 'end': '31.1.1.6'}], 'gateway_ip': '31.1.1.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '31.1.1.2', 'tags': [], 'project_id': '4126cccd1ae841d5881e36a2062014b1'}}
2026-06-08 06:25:15,723 - DEBUG - Response for create_subnet : {'subnet': {'id': 'e0d3cb1f-3cd2-423e-bf48-9e2788a89920', 'name': '', 'tenant_id': '4126cccd1ae841d5881e36a2062014b1', 'network_id': 'afc4845f-43ed-49a2-8298-7b4b538c4aee', 'ip_version': 4, 'cidr': '31.1.2.0/29', 'allocation_pools': [{'start': '31.1.2.2', 'end': '31.1.2.6'}], 'gateway_ip': '31.1.2.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '31.1.2.2', 'tags': [], 'project_id': '4126cccd1ae841d5881e36a2062014b1'}}
2026-06-08 06:25:15,752 - INFO - Created VN ctest-vn-89852346
2026-06-08 06:25:15,766 - DEBUG - VN ctest-vn-89852346 UUID is afc4845f-43ed-49a2-8298-7b4b538c4aee
2026-06-08 06:25:16,293 - DEBUG - Response for create_port : {'port': {'name': 'd634b210-fd52-4b04-b067-c7e4e0accaa6', 'id': 'd634b210-fd52-4b04-b067-c7e4e0accaa6', 'tenant_id': '4126cccd1ae841d5881e36a2062014b1', 'network_id': 'afc4845f-43ed-49a2-8298-7b4b538c4aee', 'mac_address': '02:d6:34:b2:10:fd', 'binding:vnic_type': 'normal', 'binding:vif_details': {'port_filter': True}, 'binding:vif_type': 'unbound', 'binding:host_id': None, 'allowed_address_pairs': [], 'fixed_ips': [{'ip_address': '31.1.1.4', 'subnet_id': '0d09af0c-9a15-49c0-8b67-cd7c71b531c7'}], 'security_groups': ['4b7f3de4-f776-4518-a0c0-588123d37cbb'], 'admin_state_up': True, 'device_id': '', 'device_owner': '', 'status': 'DOWN', 'port_security_enabled': True, 'description': '', 'project_id': '4126cccd1ae841d5881e36a2062014b1'}}
2026-06-08 06:25:16,703 - DEBUG - Response for create_port : {'port': {'name': 'a11641a6-f730-4933-9d84-f6fb7400a488', 'id': 'a11641a6-f730-4933-9d84-f6fb7400a488', 'tenant_id': '4126cccd1ae841d5881e36a2062014b1', 'network_id': 'afc4845f-43ed-49a2-8298-7b4b538c4aee', 'mac_address': '02:a1:16:41:a6:f7', 'binding:vnic_type': 'normal', 'binding:vif_details': {'port_filter': True}, 'binding:vif_type': 'unbound', 'binding:host_id': None, 'allowed_address_pairs': [], 'fixed_ips': [{'ip_address': '31.1.2.4', 'subnet_id': 'e0d3cb1f-3cd2-423e-bf48-9e2788a89920'}], 'security_groups': ['4b7f3de4-f776-4518-a0c0-588123d37cbb'], 'admin_state_up': True, 'device_id': '', 'device_owner': '', 'status': 'DOWN', 'port_security_enabled': True, 'description': '', 'project_id': '4126cccd1ae841d5881e36a2062014b1'}}
2026-06-08 06:25:17,309 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5902-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5902-2)
2026-06-08 06:25:18,024 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5902-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5902-1)
2026-06-08 06:25:19,582 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5902-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5902-2)
2026-06-08 06:25:19,582 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2026-06-08 06:25:19,594 - DEBUG - Requesting: http://10.0.0.38:8082/domain/ff885fe6-2413-4985-853a-0c3bd0a6a9a3
2026-06-08 06:25:19,613 - DEBUG - Requesting: http://10.0.0.38:8082/project/4126cccd-1ae8-41d5-881e-36a2062014b1
2026-06-08 06:25:19,729 - DEBUG - Requesting: http://10.0.0.38:8082/network-ipam/fa0f0cd6-7c54-404a-99d1-48b3d2e61bb3
2026-06-08 06:25:19,744 - INFO - Verifications in API Server for IPAM: ctest-ipam-45200605 passed
2026-06-08 06:25:19,759 - DEBUG - Control-node 10.0.0.38 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestBasicVMVN-98691060:ctest-ipam-45200605', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicVMVN-98691060', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '18018634749481074762', 'uuid-lslong': '11083720095110142899'}, 'enable': 'true', 'created': '2026-06-08T06:25:14', 'last-modified': '2026-06-08T06:25:14', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:04.919932'}
2026-06-08 06:25:19,765 - DEBUG - Control-node 10.0.0.50 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestBasicVMVN-98691060:ctest-ipam-45200605', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicVMVN-98691060', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '18018634749481074762', 'uuid-lslong': '11083720095110142899'}, 'enable': 'true', 'created': '2026-06-08T06:25:14', 'last-modified': '2026-06-08T06:25:14', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:04.933755'}
2026-06-08 06:25:19,772 - DEBUG - Control-node 10.0.0.33 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestBasicVMVN-98691060:ctest-ipam-45200605', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicVMVN-98691060', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '18018634749481074762', 'uuid-lslong': '11083720095110142899'}, 'enable': 'true', 'created': '2026-06-08T06:25:14', 'last-modified': '2026-06-08T06:25:14', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:04.941363'}
2026-06-08 06:25:19,772 - INFO - Verifications in Control node for IPAM: ctest-ipam-45200605 passed
2026-06-08 06:25:19,772 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2026-06-08 06:25:19,780 - DEBUG - Requesting: http://10.0.0.38:8082/domain/ff885fe6-2413-4985-853a-0c3bd0a6a9a3
2026-06-08 06:25:19,797 - DEBUG - Requesting: http://10.0.0.38:8082/project/4126cccd-1ae8-41d5-881e-36a2062014b1
2026-06-08 06:25:19,912 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/afc4845f-43ed-49a2-8298-7b4b538c4aee
2026-06-08 06:25:19,929 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/afc4845f-43ed-49a2-8298-7b4b538c4aee
2026-06-08 06:25:19,943 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/912aaaf2-9ebd-4fdd-9e3b-e602dabfe7c4
2026-06-08 06:25:19,954 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/912aaaf2-9ebd-4fdd-9e3b-e602dabfe7c4
2026-06-08 06:25:19,969 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/9561e707-5c32-4881-b096-69c52d899d5e
2026-06-08 06:25:19,977 - DEBUG - Route Targets: ['target:64512:8000004']
2026-06-08 06:25:19,977 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/afc4845f-43ed-49a2-8298-7b4b538c4aee
2026-06-08 06:25:19,990 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/912aaaf2-9ebd-4fdd-9e3b-e602dabfe7c4
2026-06-08 06:25:20,010 - INFO - Verified VN network id 10 for VN afc4845f-43ed-49a2-8298-7b4b538c4aee
2026-06-08 06:25:20,010 - INFO - Verifications in API Server for VN ctest-vn-89852346 passed
2026-06-08 06:25:20,010 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/afc4845f-43ed-49a2-8298-7b4b538c4aee
2026-06-08 06:25:20,025 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/912aaaf2-9ebd-4fdd-9e3b-e602dabfe7c4
2026-06-08 06:25:20,037 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/912aaaf2-9ebd-4fdd-9e3b-e602dabfe7c4
2026-06-08 06:25:20,050 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/9561e707-5c32-4881-b096-69c52d899d5e
2026-06-08 06:25:20,071 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicVMVN-98691060:ctest-vn-89852346', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicVMVN-98691060', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12665393596769061282', 'uuid-lslong': '9410406984845118190'}, 'enable': 'true', 'created': '2026-06-08T06:25:15', 'last-modified': '2026-06-08T06:25:15', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:04.407255'}
2026-06-08 06:25:20,085 - DEBUG - Route Targets: ['target:64512:8000004']
2026-06-08 06:25:20,096 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicVMVN-98691060:ctest-vn-89852346', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicVMVN-98691060', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12665393596769061282', 'uuid-lslong': '9410406984845118190'}, 'enable': 'true', 'created': '2026-06-08T06:25:15', 'last-modified': '2026-06-08T06:25:15', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:04.422218'}
2026-06-08 06:25:20,107 - DEBUG - Route Targets: ['target:64512:8000004']
2026-06-08 06:25:20,113 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicVMVN-98691060:ctest-vn-89852346', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicVMVN-98691060', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12665393596769061282', 'uuid-lslong': '9410406984845118190'}, 'enable': 'true', 'created': '2026-06-08T06:25:15', 'last-modified': '2026-06-08T06:25:15', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:04.447441'}
2026-06-08 06:25:20,123 - DEBUG - Route Targets: ['target:64512:8000004']
2026-06-08 06:25:20,123 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn-89852346 passed
2026-06-08 06:25:20,123 - DEBUG - ====Verifying policy data for ctest-vn-89852346 in API_Server ======
2026-06-08 06:25:20,123 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2026-06-08 06:25:20,132 - DEBUG - Requesting: http://10.0.0.38:8082/domain/ff885fe6-2413-4985-853a-0c3bd0a6a9a3
2026-06-08 06:25:20,175 - DEBUG - Requesting: http://10.0.0.38:8082/project/4126cccd-1ae8-41d5-881e-36a2062014b1
2026-06-08 06:25:20,295 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/afc4845f-43ed-49a2-8298-7b4b538c4aee
2026-06-08 06:25:20,315 - DEBUG - =>VN ctest-vn-89852346 has no policy to be verified
2026-06-08 06:25:20,315 - DEBUG - Verifying the vn in opserver
2026-06-08 06:25:20,315 - DEBUG - Verifying the default-domain:ctest-TestBasicVMVN-98691060:ctest-vn-89852346 virtual network link through opserver 10.0.0.38
2026-06-08 06:25:20,315 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2026-06-08 06:25:20,728 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2026-06-08 06:25:20,807 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicVMVN-98691060:ctest-vn-89852346', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicVMVN-98691060:ctest-vn-89852346?flat'}
2026-06-08 06:25:20,807 - DEBUG - Verifying the default-domain:ctest-TestBasicVMVN-98691060:ctest-vn-89852346 virtual network link through opserver 10.0.0.50
2026-06-08 06:25:20,808 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2026-06-08 06:25:21,124 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2026-06-08 06:25:21,208 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicVMVN-98691060:ctest-vn-89852346', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicVMVN-98691060:ctest-vn-89852346?flat'}
2026-06-08 06:25:21,208 - DEBUG - Verifying the default-domain:ctest-TestBasicVMVN-98691060:ctest-vn-89852346 virtual network link through opserver 10.0.0.33
2026-06-08 06:25:21,208 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2026-06-08 06:25:21,500 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2026-06-08 06:25:21,564 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicVMVN-98691060:ctest-vn-89852346', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicVMVN-98691060:ctest-vn-89852346?flat'}
2026-06-08 06:25:21,564 - INFO - Validated that VN default-domain:ctest-TestBasicVMVN-98691060:ctest-vn-89852346 is found in opserver
2026-06-08 06:25:21,575 - DEBUG - VRF ids for VN ctest-vn-89852346: {'10.0.0.249': '2', '10.0.0.22': '2'}
2026-06-08 06:25:21,575 - INFO - Waiting for VM ctest-TestBasicVMVN-98691060-47586712 to be up..
2026-06-08 06:25:21,682 - DEBUG - VM is in ACTIVE state now
2026-06-08 06:25:21,682 - INFO - VM name : ctest-TestBasicVMVN-98691060-47586712
2026-06-08 06:25:21,782 - DEBUG - VM ctest-TestBasicVMVN-98691060-47586712 ID is 7d59a650-600b-48c6-a8d5-a5fd31024f1c
2026-06-08 06:25:21,782 - DEBUG - VM ctest-TestBasicVMVN-98691060-47586712 launched on Node an-jenkins-deploy-platform-ansible-os-5902-2
2026-06-08 06:25:21,880 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine/7d59a650-600b-48c6-a8d5-a5fd31024f1c
2026-06-08 06:25:21,892 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine-interface/d634b210-fd52-4b04-b067-c7e4e0accaa6
2026-06-08 06:25:25,082 - 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 1027ms')
2026-06-08 06:25:25,083 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicVMVN-98691060-47586712 failed!
2026-06-08 06:25:25,100 - DEBUG - Gateway for vn default-domain:ctest-TestBasicVMVN-98691060:ctest-vn-89852346 is 31.1.1.1 and allocation pool is NOT set
2026-06-08 06:25:25,100 - DEBUG - Gateway for vn default-domain:ctest-TestBasicVMVN-98691060:ctest-vn-89852346 is 31.1.2.1 and allocation pool is NOT set
2026-06-08 06:25:29,177 - 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 1021ms')
2026-06-08 06:25:29,177 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicVMVN-98691060-47586712 failed!
2026-06-08 06:25:29,192 - DEBUG - Gateway for vn default-domain:ctest-TestBasicVMVN-98691060:ctest-vn-89852346 is 31.1.1.1 and allocation pool is NOT set
2026-06-08 06:25:29,192 - DEBUG - Gateway for vn default-domain:ctest-TestBasicVMVN-98691060:ctest-vn-89852346 is 31.1.2.1 and allocation pool is NOT set
2026-06-08 06:25:31,257 - 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.37 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.844 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.844/2.606/4.368/1.762 ms')
2026-06-08 06:25:31,257 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicVMVN-98691060-47586712 passed
2026-06-08 06:25:31,325 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-08 06:25:31,325 - DEBUG - Waiting to SSH to VM ctest-TestBasicVMVN-98691060-47586712, IP 31.1.1.4, Port 22
2026-06-08 06:25:31,483 - DEBUG - VM ctest-TestBasicVMVN-98691060-47586712 is ready for SSH connections
2026-06-08 06:25:31,483 - INFO - Waiting for VM ctest-TestBasicVMVN-98691060-08399545 to be up..
2026-06-08 06:25:31,578 - DEBUG - VM is in ACTIVE state now
2026-06-08 06:25:31,578 - INFO - VM name : ctest-TestBasicVMVN-98691060-08399545
2026-06-08 06:25:31,680 - DEBUG - VM ctest-TestBasicVMVN-98691060-08399545 ID is 2a9aa709-82c6-4c55-a246-d96357a4ceb3
2026-06-08 06:25:31,680 - DEBUG - VM ctest-TestBasicVMVN-98691060-08399545 launched on Node an-jenkins-deploy-platform-ansible-os-5902-1
2026-06-08 06:25:31,783 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine/2a9aa709-82c6-4c55-a246-d96357a4ceb3
2026-06-08 06:25:31,797 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine-interface/a11641a6-f730-4933-9d84-f6fb7400a488
2026-06-08 06:25:32,981 - 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.62 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.444 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.444/2.530/4.616/2.086 ms')
2026-06-08 06:25:32,981 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicVMVN-98691060-08399545 passed
2026-06-08 06:25:33,054 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-08 06:25:33,054 - DEBUG - Waiting to SSH to VM ctest-TestBasicVMVN-98691060-08399545, IP 31.1.2.4, Port 22
2026-06-08 06:25:33,212 - DEBUG - VM ctest-TestBasicVMVN-98691060-08399545 is ready for SSH connections
2026-06-08 06:25:33,212 - INFO - Waiting for VM ctest-TestBasicVMVN-98691060-36424479 to be up..
2026-06-08 06:25:33,306 - DEBUG - VM is in ACTIVE state now
2026-06-08 06:25:33,306 - INFO - VM name : ctest-TestBasicVMVN-98691060-36424479
2026-06-08 06:25:33,394 - DEBUG - VM ctest-TestBasicVMVN-98691060-36424479 ID is b10f0f8d-9afb-4901-add6-a529e8e797b2
2026-06-08 06:25:33,394 - DEBUG - VM ctest-TestBasicVMVN-98691060-36424479 launched on Node an-jenkins-deploy-platform-ansible-os-5902-2
2026-06-08 06:25:33,477 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine/b10f0f8d-9afb-4901-add6-a529e8e797b2
2026-06-08 06:25:33,490 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine-interface/8f339458-e92f-4da9-8fba-dff1a74b0f96
2026-06-08 06:25:34,666 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n64 bytes from 169.254.0.4: icmp_seq=1 ttl=63 time=2.94 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.712 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1000ms\r\nrtt min/avg/max/mdev = 0.712/1.827/2.942/1.115 ms')
2026-06-08 06:25:34,666 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-TestBasicVMVN-98691060-36424479 passed
2026-06-08 06:25:34,739 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-08 06:25:34,739 - DEBUG - Waiting to SSH to VM ctest-TestBasicVMVN-98691060-36424479, IP 31.1.1.3, Port 22
2026-06-08 06:25:34,895 - DEBUG - VM ctest-TestBasicVMVN-98691060-36424479 is ready for SSH connections
2026-06-08 06:25:34,895 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-08 06:25:34,895 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 31.1.2.4, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.22, gateway password: c0ntrail123
2026-06-08 06:25:34,896 - DEBUG - ping -s 56 -c 3 -W 1 31.1.2.4
2026-06-08 06:25:38,688 - DEBUG - PING 31.1.2.4 (31.1.2.4): 56 data bytes
64 bytes from 31.1.2.4: seq=0 ttl=63 time=5.876 ms
64 bytes from 31.1.2.4: seq=1 ttl=63 time=1.322 ms
64 bytes from 31.1.2.4: seq=2 ttl=63 time=2.185 ms
--- 31.1.2.4 ping statistics ---
3 packets transmitted, 3 packets received, 0% packet loss
round-trip min/avg/max = 1.322/3.127/5.876 ms
2026-06-08 06:25:38,689 - INFO - Ping to IP 31.1.2.4 from VM ctest-TestBasicVMVN-98691060-47586712 passed
2026-06-08 06:25:38,689 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-08 06:25:38,689 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 31.1.1.4, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.249, gateway password: c0ntrail123
2026-06-08 06:25:38,689 - DEBUG - ping -s 56 -c 3 -W 1 31.1.1.4
2026-06-08 06:25:42,398 - DEBUG - PING 31.1.1.4 (31.1.1.4): 56 data bytes
64 bytes from 31.1.1.4: seq=0 ttl=63 time=3.311 ms
64 bytes from 31.1.1.4: seq=1 ttl=63 time=1.446 ms
64 bytes from 31.1.1.4: seq=2 ttl=63 time=1.261 ms
--- 31.1.1.4 ping statistics ---
3 packets transmitted, 3 packets received, 0% packet loss
round-trip min/avg/max = 1.261/2.006/3.311 ms
2026-06-08 06:25:42,398 - INFO - Ping to IP 31.1.1.4 from VM ctest-TestBasicVMVN-98691060-08399545 passed
2026-06-08 06:25:42,398 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-08 06:25:42,398 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-08 06:25:42,398 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-08 06:25:42,398 - DEBUG - Running remote_cmd, Cmd : echo 0 > /proc/sys/net/ipv4/icmp_echo_ignore_broadcasts, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.22, gateway password: c0ntrail123
2026-06-08 06:25:42,398 - DEBUG - echo 0 > /proc/sys/net/ipv4/icmp_echo_ignore_broadcasts
2026-06-08 06:25:42,577 - DEBUG - None
2026-06-08 06:25:42,577 - DEBUG - Running remote_cmd, Cmd : echo 0 > /proc/sys/net/ipv4/icmp_echo_ignore_broadcasts, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.249, gateway password: c0ntrail123
2026-06-08 06:25:42,577 - DEBUG - echo 0 > /proc/sys/net/ipv4/icmp_echo_ignore_broadcasts
2026-06-08 06:25:42,757 - DEBUG - None
2026-06-08 06:25:42,757 - DEBUG - Running remote_cmd, Cmd : echo 0 > /proc/sys/net/ipv4/icmp_echo_ignore_broadcasts, host_string: cirros@169.254.0.4, password: cubswin:)gateway: ubuntu@10.0.0.22, gateway password: c0ntrail123
2026-06-08 06:25:42,757 - DEBUG - echo 0 > /proc/sys/net/ipv4/icmp_echo_ignore_broadcasts
2026-06-08 06:25:43,389 - DEBUG - None
2026-06-08 06:25:43,389 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 31.1.1.7, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.22, gateway password: c0ntrail123
2026-06-08 06:25:43,389 - DEBUG - ping -s 56 -c 3 -W 1 31.1.1.7
2026-06-08 06:25:45,606 - DEBUG - PING 31.1.1.7 (31.1.1.7): 56 data bytes
64 bytes from 31.1.1.4: seq=0 ttl=64 time=2.790 ms
64 bytes from 31.1.1.3: seq=0 ttl=64 time=7.066 ms (DUP!)
64 bytes from 31.1.1.4: seq=1 ttl=64 time=0.436 ms
64 bytes from 31.1.1.3: seq=1 ttl=64 time=1.984 ms (DUP!)
64 bytes from 31.1.1.4: seq=2 ttl=64 time=0.503 ms
--- 31.1.1.7 ping statistics ---
3 packets transmitted, 3 packets received, 2 duplicates, 0% packet loss
round-trip min/avg/max = 0.436/2.555/7.066 ms
2026-06-08 06:25:45,606 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 224.0.0.1, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.22, gateway password: c0ntrail123
2026-06-08 06:25:45,606 - DEBUG - ping -s 56 -c 3 -W 1 224.0.0.1
2026-06-08 06:25:47,866 - DEBUG - PING 224.0.0.1 (224.0.0.1): 56 data bytes
64 bytes from 31.1.1.4: seq=0 ttl=64 time=3.405 ms
64 bytes from 31.1.1.3: seq=0 ttl=64 time=7.157 ms (DUP!)
64 bytes from 31.1.2.4: seq=0 ttl=63 time=8.600 ms (DUP!)
64 bytes from 31.1.1.4: seq=1 ttl=64 time=1.006 ms
64 bytes from 31.1.1.3: seq=1 ttl=64 time=2.328 ms (DUP!)
64 bytes from 31.1.2.4: seq=1 ttl=63 time=3.341 ms (DUP!)
64 bytes from 31.1.1.4: seq=2 ttl=64 time=0.377 ms
--- 224.0.0.1 ping statistics ---
3 packets transmitted, 3 packets received, 4 duplicates, 0% packet loss
round-trip min/avg/max = 0.377/3.744/8.600 ms
2026-06-08 06:25:47,866 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 255.255.255.255, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.22, gateway password: c0ntrail123
2026-06-08 06:25:47,866 - DEBUG - ping -s 56 -c 3 -W 1 255.255.255.255
2026-06-08 06:25:50,105 - DEBUG - PING 255.255.255.255 (255.255.255.255): 56 data bytes
64 bytes from 31.1.1.4: seq=0 ttl=64 time=4.271 ms
64 bytes from 31.1.1.3: seq=0 ttl=64 time=6.053 ms (DUP!)
64 bytes from 31.1.2.4: seq=0 ttl=63 time=6.967 ms (DUP!)
64 bytes from 31.1.1.4: seq=1 ttl=64 time=0.442 ms
64 bytes from 31.1.2.4: seq=1 ttl=63 time=2.568 ms (DUP!)
64 bytes from 31.1.1.3: seq=1 ttl=64 time=3.666 ms (DUP!)
64 bytes from 31.1.1.4: seq=2 ttl=64 time=0.386 ms
--- 255.255.255.255 ping statistics ---
3 packets transmitted, 3 packets received, 4 duplicates, 0% packet loss
round-trip min/avg/max = 0.386/3.479/6.967 ms
2026-06-08 06:25:50,105 - INFO - Deleting VM ctest-TestBasicVMVN-98691060-36424479
2026-06-08 06:25:50,196 - INFO - Detaching port a11641a6-f730-4933-9d84-f6fb7400a488 from VM ctest-TestBasicVMVN-98691060-08399545
2026-06-08 06:25:50,235 - INFO - Deleting VM ctest-TestBasicVMVN-98691060-08399545
2026-06-08 06:25:50,312 - INFO - Detaching port d634b210-fd52-4b04-b067-c7e4e0accaa6 from VM ctest-TestBasicVMVN-98691060-47586712
2026-06-08 06:25:50,349 - INFO - Deleting VM ctest-TestBasicVMVN-98691060-47586712
2026-06-08 06:25:50,421 - INFO - Deleting VN ctest-vn-89852346
2026-06-08 06:25:50,707 - DEBUG - Response for delete_port : ()
2026-06-08 06:25:50,996 - DEBUG - Response for delete_port : ()
2026-06-08 06:25:51,276 - DEBUG - Response for deleting network ()
2026-06-08 06:25:51,276 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/912aaaf2-9ebd-4fdd-9e3b-e602dabfe7c4
2026-06-08 06:25:51,282 - DEBUG - Response Code: 404
2026-06-08 06:25:51,282 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2026-06-08 06:25:51,296 - DEBUG - Requesting: http://10.0.0.38:8082/domain/ff885fe6-2413-4985-853a-0c3bd0a6a9a3
2026-06-08 06:25:51,309 - DEBUG - Requesting: http://10.0.0.38:8082/project/4126cccd-1ae8-41d5-881e-36a2062014b1
2026-06-08 06:25:51,418 - INFO - Validated that VN ctest-vn-89852346 is not found in API Server
2026-06-08 06:25:51,430 - DEBUG - VN ctest-vn-89852346 is not present in Agent 10.0.0.249
2026-06-08 06:25:51,441 - DEBUG - VN ctest-vn-89852346 is not present in Agent 10.0.0.22
2026-06-08 06:25:51,441 - INFO - Validated that VN ctest-vn-89852346 is not in any agent
2026-06-08 06:25:51,447 - DEBUG - VRF 2 is not seen in agent 10.0.0.249
2026-06-08 06:25:51,455 - DEBUG - Vrouter 10.0.0.249 does not have vrf 2 for VN ctest-vn-89852346
2026-06-08 06:25:51,461 - DEBUG - VRF 2 is not seen in agent 10.0.0.22
2026-06-08 06:25:51,468 - DEBUG - Vrouter 10.0.0.22 does not have vrf 2 for VN ctest-vn-89852346
2026-06-08 06:25:51,468 - INFO - Validated that all vrouters do not have the route table for VN default-domain:ctest-TestBasicVMVN-98691060:ctest-vn-89852346
2026-06-08 06:25:51,491 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn-89852346 info
2026-06-08 06:25:51,596 - INFO - IPAM: ctest-ipam-45200605 is not found in API Server
2026-06-08 06:25:51,617 - INFO - IPAM:ctest-ipam-45200605 is not found in control node
2026-06-08 06:25:52,884 - DEBUG - No XMPP flaps were noticed during the test
2026-06-08 06:25:52,884 - INFO - END TEST : test_ping_within_vn_two_vms_two_different_subnets : PASSED[0:00:39]
2026-06-08 06:25:52,884 - INFO - --------------------------------------------------------------------------------
2026-06-08 06:25:53,631 - INFO - Deleted project: ctest-TestBasicVMVN-98691060, ID : 4126cccd-1ae8-41d5-881e-36a2062014b1