2026-04-27 03:56:37,388 - INFO - Domain Default found not creating
2026-04-27 03:56:37,598 - INFO - Project ctest-TestBasicVMVN-80087160 not found, creating it
2026-04-27 03:56:38,297 - INFO - Created Project:ctest-TestBasicVMVN-80087160, ID : 7aa4b54c-05b0-40ae-8815-960456b4320f
2026-04-27 03:56:40,236 - INFO - ================================================================================
2026-04-27 03:56:40,236 - INFO - STARTING TEST : test_generic_link_local_service
2026-04-27 03:56:40,237 - 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-04-27 03:56:41,576 - DEBUG - Nothing to compare xmpp stats {'10.0.0.33': {'10.20.0.193': '0', '10.20.0.18': '0'}, '10.0.0.65': {'10.20.0.25': '0', '10.20.0.18': '0'}} with
2026-04-27 03:56:41,576 - INFO - Initial checks done. Running the testcase now
2026-04-27 03:56:41,576 - INFO -
2026-04-27 03:56:42,411 - DEBUG - Response for create_network : {'network': {'id': '0c5b89db-ed86-4515-9d9f-b30502752e7f', 'name': 'ctest-vn2_metadata-58188750', 'tenant_id': '7aa4b54c05b040ae8815960456b4320f', 'project_id': '7aa4b54c05b040ae8815960456b4320f', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicVMVN-80087160', 'ctest-vn2_metadata-58188750'], 'port_security_enabled': True, 'description': ''}}
2026-04-27 03:56:42,785 - DEBUG - Response for create_subnet : {'subnet': {'id': '9f9d9dfd-65e4-412d-9466-465b3ba3b4e8', 'name': '', 'tenant_id': '7aa4b54c05b040ae8815960456b4320f', 'network_id': '0c5b89db-ed86-4515-9d9f-b30502752e7f', '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': '7aa4b54c05b040ae8815960456b4320f'}}
2026-04-27 03:56:42,816 - INFO - Created VN ctest-vn2_metadata-58188750
2026-04-27 03:56:42,839 - DEBUG - VN ctest-vn2_metadata-58188750 UUID is 0c5b89db-ed86-4515-9d9f-b30502752e7f
2026-04-27 03:56:43,203 - DEBUG - Services list from nova: [, , , ]
2026-04-27 03:56:44,759 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5690-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5690-1)
2026-04-27 03:56:44,855 - DEBUG - Link local service introspect added
2026-04-27 03:56:44,921 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2026-04-27 03:56:50,030 - DEBUG - VM is in ACTIVE state now
2026-04-27 03:56:50,483 - INFO - Waiting for VM ctest-TestBasicVMVN-80087160-55907865 to be up..
2026-04-27 03:56:50,484 - INFO - VM name : ctest-TestBasicVMVN-80087160-55907865
2026-04-27 03:56:50,624 - DEBUG - VM ctest-TestBasicVMVN-80087160-55907865 ID is e46f4027-5857-4737-9193-165095cef69f
2026-04-27 03:56:50,624 - DEBUG - VM ctest-TestBasicVMVN-80087160-55907865 launched on Node an-jenkins-deploy-platform-ansible-os-5690-1
2026-04-27 03:56:50,750 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/e46f4027-5857-4737-9193-165095cef69f
2026-04-27 03:56:51,092 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/e46f4027-5857-4737-9193-165095cef69f
2026-04-27 03:56:51,143 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/9ee608d5-5fc2-450b-ab0c-cd916decfe34
2026-04-27 03:56:54,360 - DEBUG - (True, 'PING 169.254.0.5 (169.254.0.5) 56(84) bytes of data.\r\n\r\n--- 169.254.0.5 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1024ms')
2026-04-27 03:56:54,361 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-TestBasicVMVN-80087160-55907865 failed!
2026-04-27 03:56:54,403 - DEBUG - Gateway for vn default-domain:ctest-TestBasicVMVN-80087160:ctest-vn2_metadata-58188750 is 11.1.1.1 and allocation pool is NOT set
2026-04-27 03:56:58,493 - DEBUG - (True, 'PING 169.254.0.5 (169.254.0.5) 56(84) bytes of data.\r\n64 bytes from 169.254.0.5: icmp_seq=2 ttl=63 time=5.02 ms\r\n\r\n--- 169.254.0.5 ping statistics ---\r\n2 packets transmitted, 1 received, 50% packet loss, time 1025ms\r\nrtt min/avg/max/mdev = 5.021/5.021/5.021/0.000 ms')
2026-04-27 03:56:58,493 - INFO - Ping to Metadata IP 169.254.0.5 of VM ctest-TestBasicVMVN-80087160-55907865 passed
2026-04-27 03:56:58,581 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-27 03:56:58,581 - DEBUG - Waiting to SSH to VM ctest-TestBasicVMVN-80087160-55907865, IP 11.1.1.3, Port 22
2026-04-27 03:56:58,738 - DEBUG - VM ctest-TestBasicVMVN-80087160-55907865 is ready for SSH connections
2026-04-27 03:56:58,738 - INFO - Retry 0
2026-04-27 03:56:58,738 - DEBUG - Running remote_cmd, Cmd : wget http://169.254.1.2:8083 --spider && echo "Successful", host_string: cirros@169.254.0.5, password: cubswin:)gateway: ubuntu@10.0.0.33, gateway password: c0ntrail123
2026-04-27 03:56:58,738 - DEBUG - wget http://169.254.1.2:8083 --spider && echo "Successful"
2026-04-27 03:56:59,434 - DEBUG - Connecting to 169.254.1.2:8083 (169.254.1.2:8083)
Successful
2026-04-27 03:56:59,435 - INFO - Generic metadata worked
2026-04-27 03:56:59,683 - DEBUG - Link local service introspect removed
2026-04-27 03:56:59,683 - INFO - Deleting VM ctest-TestBasicVMVN-80087160-55907865
2026-04-27 03:56:59,799 - INFO - Deleting VN ctest-vn2_metadata-58188750
2026-04-27 03:56:59,867 - DEBUG - VN 0c5b89db-ed86-4515-9d9f-b30502752e7f still in use: Unable to complete operation on network 0c5b89db-ed86-4515-9d9f-b30502752e7f. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-1175c165-4bdc-46d2-8a14-59bbcf308e4f']
2026-04-27 03:56:59,867 - WARNING - Deleting VN ctest-vn2_metadata-58188750 failed..Will retry
2026-04-27 03:57:02,116 - DEBUG - Response for deleting network ()
2026-04-27 03:57:03,459 - DEBUG - No XMPP flaps were noticed during the test
2026-04-27 03:57:03,460 - INFO - END TEST : test_generic_link_local_service : PASSED[0:00:23]
2026-04-27 03:57:03,460 - INFO - --------------------------------------------------------------------------------
2026-04-27 03:57:03,462 - INFO - ================================================================================
2026-04-27 03:57:03,462 - INFO - STARTING TEST : test_metadata_service
2026-04-27 03:57:03,462 - 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-04-27 03:57:04,758 - DEBUG - Nothing to compare xmpp stats {'10.0.0.33': {'10.20.0.193': '0', '10.20.0.18': '0'}, '10.0.0.65': {'10.20.0.25': '0', '10.20.0.18': '0'}} with
2026-04-27 03:57:04,759 - INFO - Initial checks done. Running the testcase now
2026-04-27 03:57:04,759 - INFO -
2026-04-27 03:57:04,759 - DEBUG - Requesting: http://10.0.0.41:8082/global-vrouter-configs
2026-04-27 03:57:05,081 - DEBUG - Requesting: http://10.0.0.41:8082/global-vrouter-configs
2026-04-27 03:57:05,120 - DEBUG - Requesting: http://10.0.0.41:8082/global-vrouter-config/ff68bc47-0636-4fb1-bbe8-3b9c36208217
2026-04-27 03:57:05,470 - DEBUG - Response for create_network : {'network': {'id': '2f3f688b-dd0c-4dea-a6ce-d0cee36f1981', 'name': 'ctest-vn-99045466', 'tenant_id': '7aa4b54c05b040ae8815960456b4320f', 'project_id': '7aa4b54c05b040ae8815960456b4320f', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicVMVN-80087160', 'ctest-vn-99045466'], 'port_security_enabled': True, 'description': ''}}
2026-04-27 03:57:05,777 - DEBUG - Response for create_subnet : {'subnet': {'id': '14bf377d-dd3f-4948-9d61-8261608101f9', 'name': '', 'tenant_id': '7aa4b54c05b040ae8815960456b4320f', 'network_id': '2f3f688b-dd0c-4dea-a6ce-d0cee36f1981', 'ip_version': 4, 'cidr': '199.60.183.128/26', 'allocation_pools': [{'start': '199.60.183.130', 'end': '199.60.183.190'}], 'gateway_ip': '199.60.183.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '199.60.183.130', 'tags': [], 'project_id': '7aa4b54c05b040ae8815960456b4320f'}}
2026-04-27 03:57:05,806 - INFO - Created VN ctest-vn-99045466
2026-04-27 03:57:05,822 - DEBUG - VN ctest-vn-99045466 UUID is 2f3f688b-dd0c-4dea-a6ce-d0cee36f1981
2026-04-27 03:57:07,379 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5690-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5690-2)
2026-04-27 03:57:07,379 - INFO - Waiting for VM ctest-TestBasicVMVN-80087160-24326388 to be up..
2026-04-27 03:57:07,465 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2026-04-27 03:57:12,574 - DEBUG - VM is in ACTIVE state now
2026-04-27 03:57:12,574 - INFO - VM name : ctest-TestBasicVMVN-80087160-24326388
2026-04-27 03:57:12,688 - DEBUG - VM ctest-TestBasicVMVN-80087160-24326388 ID is 66b9a074-a0bc-474b-ac14-1cd325f6eb2c
2026-04-27 03:57:12,688 - DEBUG - VM ctest-TestBasicVMVN-80087160-24326388 launched on Node an-jenkins-deploy-platform-ansible-os-5690-2
2026-04-27 03:57:12,804 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/66b9a074-a0bc-474b-ac14-1cd325f6eb2c
2026-04-27 03:57:12,822 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/c6da0043-e0b5-4aed-b487-348cb2ce392f
2026-04-27 03:57:16,037 - DEBUG - (True, 'PING 169.254.0.5 (169.254.0.5) 56(84) bytes of data.\r\n\r\n--- 169.254.0.5 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1026ms')
2026-04-27 03:57:16,038 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-TestBasicVMVN-80087160-24326388 failed!
2026-04-27 03:57:16,058 - DEBUG - Gateway for vn default-domain:ctest-TestBasicVMVN-80087160:ctest-vn-99045466 is 199.60.183.129 and allocation pool is NOT set
2026-04-27 03:57:20,133 - DEBUG - (True, 'PING 169.254.0.5 (169.254.0.5) 56(84) bytes of data.\r\n\r\n--- 169.254.0.5 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1010ms')
2026-04-27 03:57:20,134 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-TestBasicVMVN-80087160-24326388 failed!
2026-04-27 03:57:20,154 - DEBUG - Gateway for vn default-domain:ctest-TestBasicVMVN-80087160:ctest-vn-99045466 is 199.60.183.129 and allocation pool is NOT set
2026-04-27 03:57:22,220 - DEBUG - (True, 'PING 169.254.0.5 (169.254.0.5) 56(84) bytes of data.\r\n64 bytes from 169.254.0.5: icmp_seq=1 ttl=63 time=3.97 ms\r\n64 bytes from 169.254.0.5: icmp_seq=2 ttl=63 time=0.417 ms\r\n\r\n--- 169.254.0.5 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 0.417/2.191/3.966/1.774 ms')
2026-04-27 03:57:22,220 - INFO - Ping to Metadata IP 169.254.0.5 of VM ctest-TestBasicVMVN-80087160-24326388 passed
2026-04-27 03:57:22,304 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-27 03:57:22,304 - DEBUG - Waiting to SSH to VM ctest-TestBasicVMVN-80087160-24326388, IP 199.60.183.131, Port 22
2026-04-27 03:57:22,475 - DEBUG - VM ctest-TestBasicVMVN-80087160-24326388 is ready for SSH connections
2026-04-27 03:57:22,475 - DEBUG - Running remote_cmd, Cmd : cat /tmp/output.txt, host_string: cirros@169.254.0.5, password: cubswin:)gateway: ubuntu@10.0.0.65, gateway password: c0ntrail123
2026-04-27 03:57:22,475 - DEBUG - cat /tmp/output.txt
2026-04-27 03:57:23,496 - DEBUG - Hello World. The time is now Sun, 26 Apr 2026 21:57:21 -0600!
2026-04-27 03:57:23,496 - DEBUG - ret : {'cat /tmp/output.txt': 'Hello World. The time is now Sun, 26 Apr 2026 21:57:21 -0600!'}
2026-04-27 03:57:23,496 - INFO - metadata_script.txt got executed in the vm
2026-04-27 03:57:23,496 - INFO - Deleting VM ctest-TestBasicVMVN-80087160-24326388
2026-04-27 03:57:23,586 - INFO - Deleting VN ctest-vn-99045466
2026-04-27 03:57:23,645 - DEBUG - VN 2f3f688b-dd0c-4dea-a6ce-d0cee36f1981 still in use: Unable to complete operation on network 2f3f688b-dd0c-4dea-a6ce-d0cee36f1981. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-0742d64d-6140-491f-b78a-ec6692695ff1']
2026-04-27 03:57:23,646 - WARNING - Deleting VN ctest-vn-99045466 failed..Will retry
2026-04-27 03:57:25,849 - DEBUG - Response for deleting network ()
2026-04-27 03:57:27,178 - DEBUG - No XMPP flaps were noticed during the test
2026-04-27 03:57:27,178 - INFO - END TEST : test_metadata_service : PASSED[0:00:24]
2026-04-27 03:57:27,178 - INFO - --------------------------------------------------------------------------------
2026-04-27 03:57:27,181 - INFO - ================================================================================
2026-04-27 03:57:27,181 - INFO - STARTING TEST : test_ping_within_vn_two_vms_two_different_subnets
2026-04-27 03:57:27,181 - 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-04-27 03:57:28,516 - DEBUG - Nothing to compare xmpp stats {'10.0.0.33': {'10.20.0.193': '0', '10.20.0.18': '0'}, '10.0.0.65': {'10.20.0.25': '0', '10.20.0.18': '0'}} with
2026-04-27 03:57:28,516 - INFO - Initial checks done. Running the testcase now
2026-04-27 03:57:28,516 - INFO -
2026-04-27 03:57:29,264 - DEBUG - Response for create_network : {'network': {'id': 'd6720480-5313-416b-8e05-e84f65b22750', 'name': 'ctest-vn-61502382', 'tenant_id': '7aa4b54c05b040ae8815960456b4320f', 'project_id': '7aa4b54c05b040ae8815960456b4320f', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicVMVN-80087160', 'ctest-vn-61502382'], 'port_security_enabled': True, 'description': ''}}
2026-04-27 03:57:29,590 - DEBUG - Response for create_subnet : {'subnet': {'id': 'a096290f-6753-440f-84dd-1552131ca503', 'name': '', 'tenant_id': '7aa4b54c05b040ae8815960456b4320f', 'network_id': 'd6720480-5313-416b-8e05-e84f65b22750', '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': '7aa4b54c05b040ae8815960456b4320f'}}
2026-04-27 03:57:29,868 - DEBUG - Response for create_subnet : {'subnet': {'id': '82d4f6d1-1871-4427-bd7a-c57d1f6fb015', 'name': '', 'tenant_id': '7aa4b54c05b040ae8815960456b4320f', 'network_id': 'd6720480-5313-416b-8e05-e84f65b22750', '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': '7aa4b54c05b040ae8815960456b4320f'}}
2026-04-27 03:57:29,906 - INFO - Created VN ctest-vn-61502382
2026-04-27 03:57:29,928 - DEBUG - VN ctest-vn-61502382 UUID is d6720480-5313-416b-8e05-e84f65b22750
2026-04-27 03:57:30,584 - DEBUG - Response for create_port : {'port': {'name': 'eea04167-bcb4-4738-98c9-97c966d5a7fb', 'id': 'eea04167-bcb4-4738-98c9-97c966d5a7fb', 'tenant_id': '7aa4b54c05b040ae8815960456b4320f', 'network_id': 'd6720480-5313-416b-8e05-e84f65b22750', 'mac_address': '02:ee:a0:41:67:bc', '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': 'a096290f-6753-440f-84dd-1552131ca503'}], 'security_groups': ['acb89544-a83e-4963-b4fe-b105aaaaf924'], 'admin_state_up': True, 'device_id': '', 'device_owner': '', 'status': 'DOWN', 'port_security_enabled': True, 'description': '', 'project_id': '7aa4b54c05b040ae8815960456b4320f'}}
2026-04-27 03:57:31,045 - DEBUG - Response for create_port : {'port': {'name': 'c1af6c1b-2a3f-4d23-a797-6f0588d477c6', 'id': 'c1af6c1b-2a3f-4d23-a797-6f0588d477c6', 'tenant_id': '7aa4b54c05b040ae8815960456b4320f', 'network_id': 'd6720480-5313-416b-8e05-e84f65b22750', 'mac_address': '02:c1:af:6c:1b:2a', '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': '82d4f6d1-1871-4427-bd7a-c57d1f6fb015'}], 'security_groups': ['acb89544-a83e-4963-b4fe-b105aaaaf924'], 'admin_state_up': True, 'device_id': '', 'device_owner': '', 'status': 'DOWN', 'port_security_enabled': True, 'description': '', 'project_id': '7aa4b54c05b040ae8815960456b4320f'}}
2026-04-27 03:57:31,742 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5690-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5690-1)
2026-04-27 03:57:32,840 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5690-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5690-2)
2026-04-27 03:57:34,510 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5690-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5690-1)
2026-04-27 03:57:34,510 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2026-04-27 03:57:34,520 - DEBUG - Requesting: http://10.0.0.41:8082/domain/3cfd3207-f874-4fca-b30c-5a5349ff35bf
2026-04-27 03:57:34,561 - DEBUG - Requesting: http://10.0.0.41:8082/project/7aa4b54c-05b0-40ae-8815-960456b4320f
2026-04-27 03:57:34,668 - DEBUG - Requesting: http://10.0.0.41:8082/network-ipam/99cde2ce-c052-492a-a668-113f45ccea08
2026-04-27 03:57:34,681 - INFO - Verifications in API Server for IPAM: ctest-ipam-23743886 passed
2026-04-27 03:57:34,687 - DEBUG - Control-node 10.0.0.41 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestBasicVMVN-80087160:ctest-ipam-23743886', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicVMVN-80087160', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11082763635646417194', 'uuid-lslong': '11990852971325614600'}, 'enable': 'true', 'created': '2026-04-27T03:57:28', 'last-modified': '2026-04-27T03:57:28', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:05.874564'}
2026-04-27 03:57:34,695 - DEBUG - Control-node 10.0.0.249 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestBasicVMVN-80087160:ctest-ipam-23743886', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicVMVN-80087160', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11082763635646417194', 'uuid-lslong': '11990852971325614600'}, 'enable': 'true', 'created': '2026-04-27T03:57:28', 'last-modified': '2026-04-27T03:57:28', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:05.880988'}
2026-04-27 03:57:34,703 - DEBUG - Control-node 10.0.0.23 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestBasicVMVN-80087160:ctest-ipam-23743886', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicVMVN-80087160', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11082763635646417194', 'uuid-lslong': '11990852971325614600'}, 'enable': 'true', 'created': '2026-04-27T03:57:28', 'last-modified': '2026-04-27T03:57:28', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:05.892331'}
2026-04-27 03:57:34,703 - INFO - Verifications in Control node for IPAM: ctest-ipam-23743886 passed
2026-04-27 03:57:34,703 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2026-04-27 03:57:34,712 - DEBUG - Requesting: http://10.0.0.41:8082/domain/3cfd3207-f874-4fca-b30c-5a5349ff35bf
2026-04-27 03:57:34,732 - DEBUG - Requesting: http://10.0.0.41:8082/project/7aa4b54c-05b0-40ae-8815-960456b4320f
2026-04-27 03:57:34,857 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/d6720480-5313-416b-8e05-e84f65b22750
2026-04-27 03:57:34,881 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/d6720480-5313-416b-8e05-e84f65b22750
2026-04-27 03:57:34,895 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/a327bbe7-6aab-4ae7-8997-680f2cb578ca
2026-04-27 03:57:34,906 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/a327bbe7-6aab-4ae7-8997-680f2cb578ca
2026-04-27 03:57:34,915 - DEBUG - Requesting: http://10.0.0.41:8082/route-target/b87de68f-4c9b-4dec-8ac5-f911a37d94e3
2026-04-27 03:57:34,924 - DEBUG - Route Targets: ['target:64512:8000007']
2026-04-27 03:57:34,924 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/d6720480-5313-416b-8e05-e84f65b22750
2026-04-27 03:57:34,937 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/a327bbe7-6aab-4ae7-8997-680f2cb578ca
2026-04-27 03:57:34,961 - INFO - Verified VN network id 17 for VN d6720480-5313-416b-8e05-e84f65b22750
2026-04-27 03:57:34,961 - INFO - Verifications in API Server for VN ctest-vn-61502382 passed
2026-04-27 03:57:34,961 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/d6720480-5313-416b-8e05-e84f65b22750
2026-04-27 03:57:34,977 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/a327bbe7-6aab-4ae7-8997-680f2cb578ca
2026-04-27 03:57:34,992 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/a327bbe7-6aab-4ae7-8997-680f2cb578ca
2026-04-27 03:57:35,006 - DEBUG - Requesting: http://10.0.0.41:8082/route-target/b87de68f-4c9b-4dec-8ac5-f911a37d94e3
2026-04-27 03:57:35,029 - DEBUG - Control-node 10.0.0.41 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicVMVN-80087160:ctest-vn-61502382', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicVMVN-80087160', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15452418220657688939', 'uuid-lslong': '10233841155975554896'}, 'enable': 'true', 'created': '2026-04-27T03:57:29', 'last-modified': '2026-04-27T03:57:29', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:05.225668'}
2026-04-27 03:57:35,039 - DEBUG - Route Targets: ['target:64512:8000007']
2026-04-27 03:57:35,055 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicVMVN-80087160:ctest-vn-61502382', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicVMVN-80087160', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15452418220657688939', 'uuid-lslong': '10233841155975554896'}, 'enable': 'true', 'created': '2026-04-27T03:57:29', 'last-modified': '2026-04-27T03:57:29', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:05.261956'}
2026-04-27 03:57:35,068 - DEBUG - Route Targets: ['target:64512:8000007']
2026-04-27 03:57:35,078 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicVMVN-80087160:ctest-vn-61502382', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicVMVN-80087160', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15452418220657688939', 'uuid-lslong': '10233841155975554896'}, 'enable': 'true', 'created': '2026-04-27T03:57:29', 'last-modified': '2026-04-27T03:57:29', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:05.286733'}
2026-04-27 03:57:35,090 - DEBUG - Route Targets: ['target:64512:8000007']
2026-04-27 03:57:35,090 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn-61502382 passed
2026-04-27 03:57:35,090 - DEBUG - ====Verifying policy data for ctest-vn-61502382 in API_Server ======
2026-04-27 03:57:35,091 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2026-04-27 03:57:35,097 - DEBUG - Requesting: http://10.0.0.41:8082/domain/3cfd3207-f874-4fca-b30c-5a5349ff35bf
2026-04-27 03:57:35,168 - DEBUG - Requesting: http://10.0.0.41:8082/project/7aa4b54c-05b0-40ae-8815-960456b4320f
2026-04-27 03:57:35,295 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/d6720480-5313-416b-8e05-e84f65b22750
2026-04-27 03:57:35,337 - DEBUG - =>VN ctest-vn-61502382 has no policy to be verified
2026-04-27 03:57:35,337 - DEBUG - Verifying the vn in opserver
2026-04-27 03:57:35,337 - DEBUG - Verifying the default-domain:ctest-TestBasicVMVN-80087160:ctest-vn-61502382 virtual network link through opserver 10.0.0.41
2026-04-27 03:57:35,337 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks
2026-04-27 03:57:35,731 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks
2026-04-27 03:57:35,845 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicVMVN-80087160:ctest-vn-61502382', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicVMVN-80087160:ctest-vn-61502382?flat'}
2026-04-27 03:57:35,845 - DEBUG - Verifying the default-domain:ctest-TestBasicVMVN-80087160:ctest-vn-61502382 virtual network link through opserver 10.0.0.249
2026-04-27 03:57:35,845 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2026-04-27 03:57:36,170 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2026-04-27 03:57:36,263 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicVMVN-80087160:ctest-vn-61502382', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicVMVN-80087160:ctest-vn-61502382?flat'}
2026-04-27 03:57:36,263 - DEBUG - Verifying the default-domain:ctest-TestBasicVMVN-80087160:ctest-vn-61502382 virtual network link through opserver 10.0.0.23
2026-04-27 03:57:36,263 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2026-04-27 03:57:36,597 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2026-04-27 03:57:36,685 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicVMVN-80087160:ctest-vn-61502382', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicVMVN-80087160:ctest-vn-61502382?flat'}
2026-04-27 03:57:36,685 - INFO - Validated that VN default-domain:ctest-TestBasicVMVN-80087160:ctest-vn-61502382 is found in opserver
2026-04-27 03:57:36,699 - DEBUG - VRF ids for VN ctest-vn-61502382: {'10.0.0.33': '4', '10.0.0.65': '4'}
2026-04-27 03:57:36,699 - INFO - Waiting for VM ctest-TestBasicVMVN-80087160-50771976 to be up..
2026-04-27 03:57:36,801 - DEBUG - VM is in ACTIVE state now
2026-04-27 03:57:36,801 - INFO - VM name : ctest-TestBasicVMVN-80087160-50771976
2026-04-27 03:57:36,915 - DEBUG - VM ctest-TestBasicVMVN-80087160-50771976 ID is 98f0c5a6-9540-4eb8-9a04-89e492a2d28e
2026-04-27 03:57:36,915 - DEBUG - VM ctest-TestBasicVMVN-80087160-50771976 launched on Node an-jenkins-deploy-platform-ansible-os-5690-1
2026-04-27 03:57:37,027 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/98f0c5a6-9540-4eb8-9a04-89e492a2d28e
2026-04-27 03:57:37,043 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/eea04167-bcb4-4738-98c9-97c966d5a7fb
2026-04-27 03:57:40,285 - DEBUG - (True, 'PING 169.254.0.5 (169.254.0.5) 56(84) bytes of data.\r\n\r\n--- 169.254.0.5 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1016ms')
2026-04-27 03:57:40,286 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-TestBasicVMVN-80087160-50771976 failed!
2026-04-27 03:57:40,303 - DEBUG - Gateway for vn default-domain:ctest-TestBasicVMVN-80087160:ctest-vn-61502382 is 31.1.1.1 and allocation pool is NOT set
2026-04-27 03:57:40,303 - DEBUG - Gateway for vn default-domain:ctest-TestBasicVMVN-80087160:ctest-vn-61502382 is 31.1.2.1 and allocation pool is NOT set
2026-04-27 03:57:44,382 - DEBUG - (True, 'PING 169.254.0.5 (169.254.0.5) 56(84) bytes of data.\r\n64 bytes from 169.254.0.5: icmp_seq=2 ttl=63 time=13.5 ms\r\n\r\n--- 169.254.0.5 ping statistics ---\r\n2 packets transmitted, 1 received, 50% packet loss, time 1011ms\r\nrtt min/avg/max/mdev = 13.543/13.543/13.543/0.000 ms')
2026-04-27 03:57:44,382 - INFO - Ping to Metadata IP 169.254.0.5 of VM ctest-TestBasicVMVN-80087160-50771976 passed
2026-04-27 03:57:44,484 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-27 03:57:44,484 - DEBUG - Waiting to SSH to VM ctest-TestBasicVMVN-80087160-50771976, IP 31.1.1.4, Port 22
2026-04-27 03:57:44,661 - DEBUG - VM ctest-TestBasicVMVN-80087160-50771976 is ready for SSH connections
2026-04-27 03:57:44,661 - INFO - Waiting for VM ctest-TestBasicVMVN-80087160-58777086 to be up..
2026-04-27 03:57:44,777 - DEBUG - VM is in ACTIVE state now
2026-04-27 03:57:44,777 - INFO - VM name : ctest-TestBasicVMVN-80087160-58777086
2026-04-27 03:57:44,903 - DEBUG - VM ctest-TestBasicVMVN-80087160-58777086 ID is cb3c68c4-fcad-4cdf-bc1f-133e587e8d51
2026-04-27 03:57:44,903 - DEBUG - VM ctest-TestBasicVMVN-80087160-58777086 launched on Node an-jenkins-deploy-platform-ansible-os-5690-2
2026-04-27 03:57:45,025 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/cb3c68c4-fcad-4cdf-bc1f-133e587e8d51
2026-04-27 03:57:45,042 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/c1af6c1b-2a3f-4d23-a797-6f0588d477c6
2026-04-27 03:57:46,225 - DEBUG - (True, 'PING 169.254.0.5 (169.254.0.5) 56(84) bytes of data.\r\n64 bytes from 169.254.0.5: icmp_seq=1 ttl=63 time=4.13 ms\r\n64 bytes from 169.254.0.5: icmp_seq=2 ttl=63 time=0.393 ms\r\n\r\n--- 169.254.0.5 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 0.393/2.263/4.133/1.870 ms')
2026-04-27 03:57:46,225 - INFO - Ping to Metadata IP 169.254.0.5 of VM ctest-TestBasicVMVN-80087160-58777086 passed
2026-04-27 03:57:46,306 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-27 03:57:46,306 - DEBUG - Waiting to SSH to VM ctest-TestBasicVMVN-80087160-58777086, IP 31.1.2.4, Port 22
2026-04-27 03:57:46,482 - DEBUG - VM ctest-TestBasicVMVN-80087160-58777086 is ready for SSH connections
2026-04-27 03:57:46,482 - INFO - Waiting for VM ctest-TestBasicVMVN-80087160-45464137 to be up..
2026-04-27 03:57:46,611 - DEBUG - VM is in ACTIVE state now
2026-04-27 03:57:46,611 - INFO - VM name : ctest-TestBasicVMVN-80087160-45464137
2026-04-27 03:57:46,739 - DEBUG - VM ctest-TestBasicVMVN-80087160-45464137 ID is 45d7babb-8720-45a8-b8bd-3468965033f4
2026-04-27 03:57:46,739 - DEBUG - VM ctest-TestBasicVMVN-80087160-45464137 launched on Node an-jenkins-deploy-platform-ansible-os-5690-1
2026-04-27 03:57:46,864 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/45d7babb-8720-45a8-b8bd-3468965033f4
2026-04-27 03:57:46,880 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/ab8d2e0f-eda9-4a40-a18a-e25a149ed2a8
2026-04-27 03:57:50,076 - DEBUG - (True, 'PING 169.254.0.6 (169.254.0.6) 56(84) bytes of data.\r\n64 bytes from 169.254.0.6: icmp_seq=2 ttl=63 time=8.31 ms\r\n\r\n--- 169.254.0.6 ping statistics ---\r\n2 packets transmitted, 1 received, 50% packet loss, time 1021ms\r\nrtt min/avg/max/mdev = 8.314/8.314/8.314/0.000 ms')
2026-04-27 03:57:50,076 - INFO - Ping to Metadata IP 169.254.0.6 of VM ctest-TestBasicVMVN-80087160-45464137 passed
2026-04-27 03:57:50,159 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-27 03:57:50,160 - DEBUG - Waiting to SSH to VM ctest-TestBasicVMVN-80087160-45464137, IP 31.1.1.3, Port 22
2026-04-27 03:57:50,325 - DEBUG - VM ctest-TestBasicVMVN-80087160-45464137 is ready for SSH connections
2026-04-27 03:57:50,326 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-27 03:57:50,326 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 31.1.2.4, host_string: cirros@169.254.0.5, password: cubswin:)gateway: ubuntu@10.0.0.33, gateway password: c0ntrail123
2026-04-27 03:57:50,326 - DEBUG - ping -s 56 -c 3 -W 1 31.1.2.4
2026-04-27 03:57:54,403 - 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.056 ms
64 bytes from 31.1.2.4: seq=1 ttl=63 time=1.550 ms
64 bytes from 31.1.2.4: seq=2 ttl=63 time=1.635 ms
--- 31.1.2.4 ping statistics ---
3 packets transmitted, 3 packets received, 0% packet loss
round-trip min/avg/max = 1.550/2.747/5.056 ms
2026-04-27 03:57:54,403 - INFO - Ping to IP 31.1.2.4 from VM ctest-TestBasicVMVN-80087160-50771976 passed
2026-04-27 03:57:54,404 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-27 03:57:54,404 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 31.1.1.4, host_string: cirros@169.254.0.5, password: cubswin:)gateway: ubuntu@10.0.0.65, gateway password: c0ntrail123
2026-04-27 03:57:54,404 - DEBUG - ping -s 56 -c 3 -W 1 31.1.1.4
2026-04-27 03:57:58,185 - 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=5.579 ms
64 bytes from 31.1.1.4: seq=1 ttl=63 time=1.622 ms
64 bytes from 31.1.1.4: seq=2 ttl=63 time=1.381 ms
--- 31.1.1.4 ping statistics ---
3 packets transmitted, 3 packets received, 0% packet loss
round-trip min/avg/max = 1.381/2.860/5.579 ms
2026-04-27 03:57:58,185 - INFO - Ping to IP 31.1.1.4 from VM ctest-TestBasicVMVN-80087160-58777086 passed
2026-04-27 03:57:58,185 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-27 03:57:58,185 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-27 03:57:58,185 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-27 03:57:58,186 - DEBUG - Running remote_cmd, Cmd : echo 0 > /proc/sys/net/ipv4/icmp_echo_ignore_broadcasts, host_string: cirros@169.254.0.5, password: cubswin:)gateway: ubuntu@10.0.0.33, gateway password: c0ntrail123
2026-04-27 03:57:58,186 - DEBUG - echo 0 > /proc/sys/net/ipv4/icmp_echo_ignore_broadcasts
2026-04-27 03:57:58,391 - DEBUG - None
2026-04-27 03:57:58,391 - DEBUG - Running remote_cmd, Cmd : echo 0 > /proc/sys/net/ipv4/icmp_echo_ignore_broadcasts, host_string: cirros@169.254.0.5, password: cubswin:)gateway: ubuntu@10.0.0.65, gateway password: c0ntrail123
2026-04-27 03:57:58,391 - DEBUG - echo 0 > /proc/sys/net/ipv4/icmp_echo_ignore_broadcasts
2026-04-27 03:57:58,590 - DEBUG - None
2026-04-27 03:57:58,590 - DEBUG - Running remote_cmd, Cmd : echo 0 > /proc/sys/net/ipv4/icmp_echo_ignore_broadcasts, host_string: cirros@169.254.0.6, password: cubswin:)gateway: ubuntu@10.0.0.33, gateway password: c0ntrail123
2026-04-27 03:57:58,590 - DEBUG - echo 0 > /proc/sys/net/ipv4/icmp_echo_ignore_broadcasts
2026-04-27 03:57:59,341 - DEBUG - None
2026-04-27 03:57:59,341 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 31.1.1.7, host_string: cirros@169.254.0.5, password: cubswin:)gateway: ubuntu@10.0.0.33, gateway password: c0ntrail123
2026-04-27 03:57:59,342 - DEBUG - ping -s 56 -c 3 -W 1 31.1.1.7
2026-04-27 03:58:01,561 - 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=1.828 ms
64 bytes from 31.1.1.3: seq=0 ttl=64 time=5.074 ms (DUP!)
64 bytes from 31.1.1.4: seq=1 ttl=64 time=1.387 ms
64 bytes from 31.1.1.3: seq=1 ttl=64 time=2.570 ms (DUP!)
64 bytes from 31.1.1.4: seq=2 ttl=64 time=0.478 ms
--- 31.1.1.7 ping statistics ---
3 packets transmitted, 3 packets received, 2 duplicates, 0% packet loss
round-trip min/avg/max = 0.478/2.267/5.074 ms
2026-04-27 03:58:01,561 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 224.0.0.1, host_string: cirros@169.254.0.5, password: cubswin:)gateway: ubuntu@10.0.0.33, gateway password: c0ntrail123
2026-04-27 03:58:01,561 - DEBUG - ping -s 56 -c 3 -W 1 224.0.0.1
2026-04-27 03:58:03,822 - 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=4.391 ms
64 bytes from 31.1.1.3: seq=0 ttl=64 time=6.683 ms (DUP!)
64 bytes from 31.1.2.4: seq=0 ttl=63 time=7.916 ms (DUP!)
64 bytes from 31.1.1.4: seq=1 ttl=64 time=0.694 ms
64 bytes from 31.1.1.3: seq=1 ttl=64 time=2.705 ms (DUP!)
64 bytes from 31.1.2.4: seq=1 ttl=63 time=4.277 ms (DUP!)
64 bytes from 31.1.1.4: seq=2 ttl=64 time=0.603 ms
--- 224.0.0.1 ping statistics ---
3 packets transmitted, 3 packets received, 4 duplicates, 0% packet loss
round-trip min/avg/max = 0.603/3.895/7.916 ms
2026-04-27 03:58:03,822 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 255.255.255.255, host_string: cirros@169.254.0.5, password: cubswin:)gateway: ubuntu@10.0.0.33, gateway password: c0ntrail123
2026-04-27 03:58:03,822 - DEBUG - ping -s 56 -c 3 -W 1 255.255.255.255
2026-04-27 03:58:06,096 - 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=1.337 ms
64 bytes from 31.1.1.3: seq=0 ttl=64 time=4.757 ms (DUP!)
64 bytes from 31.1.2.4: seq=0 ttl=63 time=5.937 ms (DUP!)
64 bytes from 31.1.1.4: seq=1 ttl=64 time=0.583 ms
64 bytes from 31.1.1.3: seq=1 ttl=64 time=2.130 ms (DUP!)
64 bytes from 31.1.2.4: seq=1 ttl=63 time=3.399 ms (DUP!)
64 bytes from 31.1.1.4: seq=2 ttl=64 time=0.424 ms
--- 255.255.255.255 ping statistics ---
3 packets transmitted, 3 packets received, 4 duplicates, 0% packet loss
round-trip min/avg/max = 0.424/2.652/5.937 ms
2026-04-27 03:58:06,096 - INFO - Deleting VM ctest-TestBasicVMVN-80087160-45464137
2026-04-27 03:58:06,205 - INFO - Detaching port c1af6c1b-2a3f-4d23-a797-6f0588d477c6 from VM ctest-TestBasicVMVN-80087160-58777086
2026-04-27 03:58:06,243 - INFO - Deleting VM ctest-TestBasicVMVN-80087160-58777086
2026-04-27 03:58:06,345 - INFO - Detaching port eea04167-bcb4-4738-98c9-97c966d5a7fb from VM ctest-TestBasicVMVN-80087160-50771976
2026-04-27 03:58:06,390 - INFO - Deleting VM ctest-TestBasicVMVN-80087160-50771976
2026-04-27 03:58:06,482 - INFO - Deleting VN ctest-vn-61502382
2026-04-27 03:58:07,032 - DEBUG - Response for delete_port : ()
2026-04-27 03:58:07,509 - DEBUG - Response for delete_port : ()
2026-04-27 03:58:07,798 - DEBUG - Response for deleting network ()
2026-04-27 03:58:07,798 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/a327bbe7-6aab-4ae7-8997-680f2cb578ca
2026-04-27 03:58:07,816 - DEBUG - Response Code: 404
2026-04-27 03:58:07,816 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2026-04-27 03:58:07,827 - DEBUG - Requesting: http://10.0.0.41:8082/domain/3cfd3207-f874-4fca-b30c-5a5349ff35bf
2026-04-27 03:58:07,846 - DEBUG - Requesting: http://10.0.0.41:8082/project/7aa4b54c-05b0-40ae-8815-960456b4320f
2026-04-27 03:58:07,995 - INFO - Validated that VN ctest-vn-61502382 is not found in API Server
2026-04-27 03:58:08,013 - DEBUG - VN ctest-vn-61502382 is not present in Agent 10.0.0.33
2026-04-27 03:58:08,029 - DEBUG - VN ctest-vn-61502382 is not present in Agent 10.0.0.65
2026-04-27 03:58:08,029 - INFO - Validated that VN ctest-vn-61502382 is not in any agent
2026-04-27 03:58:08,036 - DEBUG - VRF 4 is not seen in agent 10.0.0.33
2026-04-27 03:58:08,042 - DEBUG - Vrouter 10.0.0.33 does not have vrf 4 for VN ctest-vn-61502382
2026-04-27 03:58:08,050 - DEBUG - VRF 4 is not seen in agent 10.0.0.65
2026-04-27 03:58:08,057 - DEBUG - Vrouter 10.0.0.65 does not have vrf 4 for VN ctest-vn-61502382
2026-04-27 03:58:08,057 - INFO - Validated that all vrouters do not have the route table for VN default-domain:ctest-TestBasicVMVN-80087160:ctest-vn-61502382
2026-04-27 03:58:08,089 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn-61502382 info
2026-04-27 03:58:08,215 - INFO - IPAM: ctest-ipam-23743886 is not found in API Server
2026-04-27 03:58:08,252 - INFO - IPAM:ctest-ipam-23743886 is not found in control node
2026-04-27 03:58:09,619 - DEBUG - No XMPP flaps were noticed during the test
2026-04-27 03:58:09,619 - INFO - END TEST : test_ping_within_vn_two_vms_two_different_subnets : PASSED[0:00:42]
2026-04-27 03:58:09,619 - INFO - --------------------------------------------------------------------------------
2026-04-27 03:58:10,500 - INFO - Deleted project: ctest-TestBasicVMVN-80087160, ID : 7aa4b54c-05b0-40ae-8815-960456b4320f