2025-09-18 10:16:36,295 - INFO - Domain Default found not creating 2025-09-18 10:16:36,439 - INFO - Project ctest-AnalyticsBasicTestSanity-04076698 not found, creating it 2025-09-18 10:16:36,873 - INFO - Created Project:ctest-AnalyticsBasicTestSanity-04076698, ID : 2b41efd7-bfa8-4a46-89c1-bb14e6c8e39c 2025-09-18 10:16:38,250 - INFO - ================================================================================ 2025-09-18 10:16:38,250 - INFO - STARTING TEST : test_verify_object_logs 2025-09-18 10:16:38,250 - INFO - TEST DESCRIPTION : Description: Test to validate object logs 1.Create vn/vm and verify object log tables updated with those vn, vm and routing-instance - fails otherwise Maintainer: sandipd@juniper.net 2025-09-18 10:16:38,382 - DEBUG - Nothing to compare xmpp stats {'10.0.0.254': {'10.20.0.17': '0'}} with 2025-09-18 10:16:38,382 - INFO - Initial checks done. Running the testcase now 2025-09-18 10:16:38,382 - INFO - 2025-09-18 10:16:39,258 - DEBUG - Response for create_network : {'network': {'id': 'c2b1c12b-aae6-4293-b46b-12561b1bf875', 'name': 'ctest-vn22-08630266', 'tenant_id': '2b41efd7bfa84a4689c1bb14e6c8e39c', 'project_id': '2b41efd7bfa84a4689c1bb14e6c8e39c', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-AnalyticsBasicTestSanity-04076698', 'ctest-vn22-08630266'], 'port_security_enabled': True, 'description': ''}} 2025-09-18 10:16:39,417 - DEBUG - Response for create_subnet : {'subnet': {'id': '50e09e99-e0db-4e25-ab83-01a3eea7c01d', 'name': '', 'tenant_id': '2b41efd7bfa84a4689c1bb14e6c8e39c', 'network_id': 'c2b1c12b-aae6-4293-b46b-12561b1bf875', 'ip_version': 4, 'cidr': '81.134.41.128/26', 'allocation_pools': [{'start': '81.134.41.130', 'end': '81.134.41.190'}], 'gateway_ip': '81.134.41.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '81.134.41.130', 'tags': [], 'project_id': '2b41efd7bfa84a4689c1bb14e6c8e39c'}} 2025-09-18 10:16:39,433 - INFO - Created VN ctest-vn22-08630266 2025-09-18 10:16:39,484 - DEBUG - VN ctest-vn22-08630266 UUID is c2b1c12b-aae6-4293-b46b-12561b1bf875 2025-09-18 10:16:39,581 - DEBUG - Requesting: http://10.0.0.254:8082/domains 2025-09-18 10:16:39,869 - DEBUG - Requesting: http://10.0.0.254:8082/domains 2025-09-18 10:16:39,894 - DEBUG - Requesting: http://10.0.0.254:8082/domain/cefcf260-3738-4f46-b0c5-1a5d683f924c 2025-09-18 10:16:39,911 - DEBUG - Requesting: http://10.0.0.254:8082/project/2b41efd7-bfa8-4a46-89c1-bb14e6c8e39c 2025-09-18 10:16:39,983 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/c2b1c12b-aae6-4293-b46b-12561b1bf875 2025-09-18 10:16:39,993 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/c2b1c12b-aae6-4293-b46b-12561b1bf875 2025-09-18 10:16:40,001 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/57c16a48-8d6b-4768-8a3b-0e86d3b9cbd0 2025-09-18 10:16:40,008 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/57c16a48-8d6b-4768-8a3b-0e86d3b9cbd0 2025-09-18 10:16:40,015 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/0700b068-3a4c-4e65-9157-2154c7e8ab28 2025-09-18 10:16:40,021 - DEBUG - Route Targets: ['target:64512:8000004'] 2025-09-18 10:16:40,021 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/c2b1c12b-aae6-4293-b46b-12561b1bf875 2025-09-18 10:16:40,029 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/57c16a48-8d6b-4768-8a3b-0e86d3b9cbd0 2025-09-18 10:16:40,041 - INFO - Verified VN network id 5 for VN c2b1c12b-aae6-4293-b46b-12561b1bf875 2025-09-18 10:16:40,041 - INFO - Verifications in API Server for VN ctest-vn22-08630266 passed 2025-09-18 10:16:40,041 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/c2b1c12b-aae6-4293-b46b-12561b1bf875 2025-09-18 10:16:40,049 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/57c16a48-8d6b-4768-8a3b-0e86d3b9cbd0 2025-09-18 10:16:40,055 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/57c16a48-8d6b-4768-8a3b-0e86d3b9cbd0 2025-09-18 10:16:40,060 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/0700b068-3a4c-4e65-9157-2154c7e8ab28 2025-09-18 10:16:40,065 - DEBUG - Requesting: http://10.0.0.254:8083/Snh_IFMapTableShowReq?table_name=virtual-network&search_string=virtual-network:default-domain:ctest-AnalyticsBasicTestSanity-04076698:ctest-vn22-08630266 2025-09-18 10:16:40,070 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsBasicTestSanity-04076698:ctest-vn22-08630266', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsBasicTestSanity-04076698', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '14029206707530777235', 'uuid-lslong': '13000504910366374005'}, 'enable': 'true', 'created': '2025-09-18T10:16:39', 'last-modified': '2025-09-18T10:16:39', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.683889'} 2025-09-18 10:16:40,070 - DEBUG - Requesting: http://10.0.0.254:8083/Snh_ShowRoutingInstanceReq?name=default-domain:ctest-AnalyticsBasicTestSanity-04076698:ctest-vn22-08630266:ctest-vn22-08630266 2025-09-18 10:16:40,076 - DEBUG - Route Targets: ['target:64512:8000004'] 2025-09-18 10:16:40,076 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn22-08630266 passed 2025-09-18 10:16:40,076 - DEBUG - ====Verifying policy data for ctest-vn22-08630266 in API_Server ====== 2025-09-18 10:16:40,076 - DEBUG - Requesting: http://10.0.0.254:8082/domains 2025-09-18 10:16:40,081 - DEBUG - Requesting: http://10.0.0.254:8082/domain/cefcf260-3738-4f46-b0c5-1a5d683f924c 2025-09-18 10:16:40,091 - DEBUG - Requesting: http://10.0.0.254:8082/project/2b41efd7-bfa8-4a46-89c1-bb14e6c8e39c 2025-09-18 10:16:40,162 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/c2b1c12b-aae6-4293-b46b-12561b1bf875 2025-09-18 10:16:40,170 - DEBUG - =>VN ctest-vn22-08630266 has no policy to be verified 2025-09-18 10:16:40,170 - DEBUG - Verifying the vn in opserver 2025-09-18 10:16:40,170 - DEBUG - Verifying the default-domain:ctest-AnalyticsBasicTestSanity-04076698:ctest-vn22-08630266 virtual network link through opserver 10.0.0.254 2025-09-18 10:16:40,170 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks 2025-09-18 10:16:40,459 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks 2025-09-18 10:16:40,506 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsBasicTestSanity-04076698:ctest-vn22-08630266', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsBasicTestSanity-04076698:ctest-vn22-08630266?flat'} 2025-09-18 10:16:40,506 - INFO - Validated that VN default-domain:ctest-AnalyticsBasicTestSanity-04076698:ctest-vn22-08630266 is found in opserver 2025-09-18 10:16:40,516 - DEBUG - Do not have enough data to verify VN in agent 2025-09-18 10:16:40,521 - DEBUG - VRF ids for VN ctest-vn22-08630266: {} 2025-09-18 10:16:40,624 - DEBUG - Services list from nova: [, , ] 2025-09-18 10:16:40,712 - DEBUG - Image by name cirros not found 2025-09-18 10:16:40,712 - DEBUG - Installing image cirros 2025-09-18 10:16:40,712 - DEBUG - Image info {'name': 'cirros-0.3.0-x86_64-disk.vmdk.gz', 'webserver': '', 'location': '/images/converts/', 'username': 'cirros', 'password': 'cubswin:)', 'type': 'os', 'flavor': 'contrail_flavor_tiny', 'params': '--container-format bare --disk-format vmdk --property vmware_disktype="sparse" --property vmware_adaptertype="ide" --property hw_scsi_model=virtio-scsi --property hw_disk_bus_model=virtio-scsi --property hw_disk_bus=virtio', 'name_docker': 'phusion-baseimage-enablesshd'} 2025-09-18 10:16:40,712 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/converts//cirros-0.3.0-x86_64-disk.vmdk.gz 2025-09-18 10:16:40,712 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/converts//cirros-0.3.0-x86_64-disk.vmdk.gz 2025-09-18 10:16:40,726 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: wget -nv http://nexus.opensdn.io/repository///images/converts//cirros-0.3.0-x86_64-disk.vmdk.gz -O /contrail-test/images/cirros-0.3.0-x86_64-disk.vmdk.gz 2025-09-18 10:16:40,810 - DEBUG - Image has been downloaded to /contrail-test/images/cirros-0.3.0-x86_64-disk.vmdk.gz 2025-09-18 10:16:40,810 - DEBUG - Unzip image 2025-09-18 10:16:40,810 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/cirros-0.3.0-x86_64-disk.vmdk.gz 2025-09-18 10:16:40,930 - DEBUG - Try to create image in user context 2025-09-18 10:16:41,124 - DEBUG - Image has been created in user context 2025-09-18 10:16:41,124 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: rm -f /contrail-test/images/cirros-0.3.0-x86_64-disk.vmdk 2025-09-18 10:16:41,137 - DEBUG - Downloaded image has been removed 2025-09-18 10:16:41,157 - DEBUG - Uploaded image status = active 2025-09-18 10:16:43,534 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-4136-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-4136-1) 2025-09-18 10:16:43,576 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-09-18 10:16:48,653 - DEBUG - VM is in ERROR state now 2025-09-18 10:16:48,653 - ERROR - VM in error state. 2025-09-18 10:16:48,657 - INFO - Skip interface_detach for VM ctest-AnalyticsBasicTestSanity-04076698-52783610 in state ERROR 2025-09-18 10:16:48,657 - INFO - Deleting VM ctest-AnalyticsBasicTestSanity-04076698-52783610 2025-09-18 10:16:48,677 - ERROR - VM ctest-AnalyticsBasicTestSanity-04076698-52783610 has failed to come up 2025-09-18 10:16:48,677 - ERROR - Fault seen in nova show is: {'code': 500, 'created': '2025-09-18T10:16:48Z', 'message': "Failure running os_vif plugin plug method: Failed to plug VIF VIFGeneric(active=True,address=02:2c:9d:03:ce:77,has_traffic_filtering=True,id=2c9d03ce-7787-4069-a526-76750e14dbba,network=Network(c2b1c12b-aae6-4293-b46b-12561b1bf875),plugin='vrouter',port_p", 'details': 'Traceback (most recent call last):\n File "/var/lib/kolla/venv/lib/python3.6/site-packages/os_vif/__init__.py", line 77, in plug\n plugin.plug(vif, instance_info)\n File "/opt/plugin/site-packages/vif_plug_vrouter/vrouter.py", line 271, in plug\n self._vrouter_port_add(instance_info, vif)\n File "/opt/plugin/site-packages/vif_plug_vrouter/vrouter.py", line 263, in _vrouter_port_add\n vhostuser_socket, vhostuser_mode)\n File "/var/lib/kolla/venv/lib/python3.6/site-packages/oslo_privsep/priv_context.py", line 272, in _wrap\n r_call_timeout)\n File "/var/lib/kolla/venv/lib/python3.6/site-packages/oslo_privsep/daemon.py", line 215, in remote_call\n raise exc_type(*result[2])\nvif_plug_vrouter.exception.VrouterPortControlError: Error during the call to vrouter-port-control: (\'vrouter-port-control\', \'--oper=add\', \'--uuid=2c9d03ce-7787-4069-a526-76750e14dbba\', \'--instance_uuid=9dab8600-0279-496b-8000-a4471cac7f1b\', \'--vn_uuid=c2b1c12b-aae6-4293-b46b-12561b1bf875\', \'--vm_project_uuid=2b41efd7bfa84a4689c1bb14e6c8e39c\', \'--ip_address=81.134.41.131\', \'--ipv6_address=None\', \'--vm_name=ctest-AnalyticsBasicTestSanity-04076698-52783610\', \'--mac=02:2c:9d:03:ce:77\', \'--tap_name=tap2c9d03ce-77\', \'--port_type=NovaVMPort\', \'--tx_vlan_id=-1\', \'--rx_vlan_id=-1\')\n\nDuring handling of the above exception, another exception occurred:\n\nTraceback (most recent call last):\n File "/var/lib/kolla/venv/lib/python3.6/site-packages/nova/virt/libvirt/vif.py", line 699, in _plug_os_vif\n os_vif.plug(vif, instance_info)\n File "/var/lib/kolla/venv/lib/python3.6/site-packages/os_vif/__init__.py", line 82, in plug\n raise os_vif.exception.PlugException(vif=vif, err=err)\nos_vif.exception.PlugException: Failed to plug VIF VIFGeneric(active=True,address=02:2c:9d:03:ce:77,has_traffic_filtering=True,id=2c9d03ce-7787-4069-a526-76750e14dbba,network=Network(c2b1c12b-aae6-4293-b46b-12561b1bf875),plugin=\'vrouter\',port_profile=,preserve_on_delete=False,vif_name=\'tap2c9d03ce-77\'). Got error: Error during the call to vrouter-port-control: (\'vrouter-port-control\', \'--oper=add\', \'--uuid=2c9d03ce-7787-4069-a526-76750e14dbba\', \'--instance_uuid=9dab8600-0279-496b-8000-a4471cac7f1b\', \'--vn_uuid=c2b1c12b-aae6-4293-b46b-12561b1bf875\', \'--vm_project_uuid=2b41efd7bfa84a4689c1bb14e6c8e39c\', \'--ip_address=81.134.41.131\', \'--ipv6_address=None\', \'--vm_name=ctest-AnalyticsBasicTestSanity-04076698-52783610\', \'--mac=02:2c:9d:03:ce:77\', \'--tap_name=tap2c9d03ce-77\', \'--port_type=NovaVMPort\', \'--tx_vlan_id=-1\', \'--rx_vlan_id=-1\')\n\nDuring handling of the above exception, another exception occurred:\n\nTraceback (most recent call last):\n File "/var/lib/kolla/venv/lib/python3.6/site-packages/nova/compute/manager.py", line 2521, in _build_and_run_instance\n accel_info=accel_info)\n File "/var/lib/kolla/venv/lib/python3.6/site-packages/nova/virt/libvirt/driver.py", line 4339, in spawn\n cleanup_instance_disks=created_disks)\n File "/var/lib/kolla/venv/lib/python3.6/site-packages/nova/virt/libvirt/driver.py", line 7523, in _create_guest_with_network\n cleanup_instance_disks=cleanup_instance_disks)\n File "/var/lib/kolla/venv/lib/python3.6/site-packages/oslo_utils/excutils.py", line 227, in __exit__\n self.force_reraise()\n File "/var/lib/kolla/venv/lib/python3.6/site-packages/oslo_utils/excutils.py", line 200, in force_reraise\n raise self.value\n File "/var/lib/kolla/venv/lib/python3.6/site-packages/nova/virt/libvirt/driver.py", line 7492, in _create_guest_with_network\n self.plug_vifs(instance, network_info)\n File "/var/lib/kolla/venv/lib/python3.6/site-packages/nova/virt/libvirt/driver.py", line 1386, in plug_vifs\n self.vif_driver.plug(instance, vif)\n File "/var/lib/kolla/venv/lib/python3.6/site-packages/nova/virt/libvirt/vif.py", line 723, in plug\n self._plug_os_vif(instance, vif_obj)\n File "/var/lib/kolla/venv/lib/python3.6/site-packages/nova/virt/libvirt/vif.py", line 703, in _plug_os_vif\n raise exception.InternalError(msg)\nnova.exception.InternalError: Failure running os_vif plugin plug method: Failed to plug VIF VIFGeneric(active=True,address=02:2c:9d:03:ce:77,has_traffic_filtering=True,id=2c9d03ce-7787-4069-a526-76750e14dbba,network=Network(c2b1c12b-aae6-4293-b46b-12561b1bf875),plugin=\'vrouter\',port_profile=,preserve_on_delete=False,vif_name=\'tap2c9d03ce-77\'). Got error: Error during the call to vrouter-port-control: (\'vrouter-port-control\', \'--oper=add\', \'--uuid=2c9d03ce-7787-4069-a526-76750e14dbba\', \'--instance_uuid=9dab8600-0279-496b-8000-a4471cac7f1b\', \'--vn_uuid=c2b1c12b-aae6-4293-b46b-12561b1bf875\', \'--vm_project_uuid=2b41efd7bfa84a4689c1bb14e6c8e39c\', \'--ip_address=81.134.41.131\', \'--ipv6_address=None\', \'--vm_name=ctest-AnalyticsBasicTestSanity-04076698-52783610\', \'--mac=02:2c:9d:03:ce:77\', \'--tap_name=tap2c9d03ce-77\', \'--port_type=NovaVMPort\', \'--tx_vlan_id=-1\', \'--rx_vlan_id=-1\')\n\nDuring handling of the above exception, another exception occurred:\n\nTraceback (most recent call last):\n File "/var/lib/kolla/venv/lib/python3.6/site-packages/nova/compute/manager.py", line 2337, in _do_build_and_run_instance\n filter_properties, request_spec, accel_uuids)\n File "/var/lib/kolla/venv/lib/python3.6/site-packages/nova/compute/manager.py", line 2625, in _build_and_run_instance\n instance_uuid=instance.uuid, reason=str(e))\nnova.exception.RescheduledException: Build of instance 9dab8600-0279-496b-8000-a4471cac7f1b was re-scheduled: Failure running os_vif plugin plug method: Failed to plug VIF VIFGeneric(active=True,address=02:2c:9d:03:ce:77,has_traffic_filtering=True,id=2c9d03ce-7787-4069-a526-76750e14dbba,network=Network(c2b1c12b-aae6-4293-b46b-12561b1bf875),plugin=\'vrouter\',port_profile=,preserve_on_delete=False,vif_name=\'tap2c9d03ce-77\'). Got error: Error during the call to vrouter-port-control: (\'vrouter-port-control\', \'--oper=add\', \'--uuid=2c9d03ce-7787-4069-a526-76750e14dbba\', \'--instance_uuid=9dab8600-0279-496b-8000-a4471cac7f1b\', \'--vn_uuid=c2b1c12b-aae6-4293-b46b-12561b1bf875\', \'--vm_project_uuid=2b41efd7bfa84a4689c1bb14e6c8e39c\', \'--ip_address=81.134.41.131\', \'--ipv6_address=None\', \'--vm_name=ctest-AnalyticsBasicTestSanity-04076698-52783610\', \'--mac=02:2c:9d:03:ce:77\', \'--tap_name=tap2c9d03ce-77\', \'--port_type=NovaVMPort\', \'--tx_vlan_id=-1\', \'--rx_vlan_id=-1\')\n'} 2025-09-18 10:16:48,678 - INFO - Deleting VN ctest-vn22-08630266 2025-09-18 10:16:48,778 - DEBUG - Response for deleting network () 2025-09-18 10:16:48,779 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/57c16a48-8d6b-4768-8a3b-0e86d3b9cbd0 2025-09-18 10:16:48,784 - DEBUG - Response Code: 404 2025-09-18 10:16:48,784 - DEBUG - Requesting: http://10.0.0.254:8082/domains 2025-09-18 10:16:48,793 - DEBUG - Requesting: http://10.0.0.254:8082/domain/cefcf260-3738-4f46-b0c5-1a5d683f924c 2025-09-18 10:16:48,849 - DEBUG - Requesting: http://10.0.0.254:8082/project/2b41efd7-bfa8-4a46-89c1-bb14e6c8e39c 2025-09-18 10:16:48,925 - INFO - Validated that VN ctest-vn22-08630266 is not found in API Server 2025-09-18 10:16:48,937 - DEBUG - VN ctest-vn22-08630266 is not present in Agent 10.0.0.254 2025-09-18 10:16:48,938 - INFO - Validated that VN ctest-vn22-08630266 is not in any agent 2025-09-18 10:16:48,942 - DEBUG - Requesting: http://10.0.0.254:8083/Snh_ShowRoutingInstanceReq?name=default-domain:ctest-AnalyticsBasicTestSanity-04076698:ctest-vn22-08630266:ctest-vn22-08630266 2025-09-18 10:16:48,946 - DEBUG - Requesting: http://10.0.0.254:8083/Snh_IFMapTableShowReq?table_name=virtual-network&search_string=virtual-network:default-domain:ctest-AnalyticsBasicTestSanity-04076698:ctest-vn22-08630266 2025-09-18 10:16:48,951 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn22-08630266 info 2025-09-18 10:16:49,069 - ERROR - AssertionError Python 3.9.21: /usr/bin/python3 Thu Sep 18 10:16:48 2025 A problem occurred in a Python script. Here is the sequence of function calls leading up to the error, in the order they occurred. /contrail-test/tcutils/wrappers.py in wrapper(self=, *args=(), **kwargs={}) 78 log.info('Initial checks done. Running the testcase now') 79 log.info('') 80 result = function(self, *args, **kwargs) 81 if self.inputs.upgrade: 82 pid = os.getpid() result = None function = self = args = () kwargs = {} /contrail-test/scripts/analytics/test_analytics_basic.py in test_verify_object_logs(self=) 32 vn_fixture.verify_on_setup() 33 vm1_fixture = self.create_vm(vn_fixture=vn_fixture, image_name='cirros') 34 assert vm1_fixture.wait_till_vm_is_active() 35 vm_uuid=vm1_fixture.vm_id 36 query='('+'ObjectId=%s)'%vn_fixture.vn_fq_name vm1_fixture = vm1_fixture.wait_till_vm_is_active = > AssertionError: __cause__ = None __class__ = __context__ = None __delattr__ = __dict__ = {} __dir__ = __doc__ = 'Assertion failed.' __eq__ = __format__ = __ge__ = __getattribute__ = __gt__ = __hash__ = __init__ = __init_subclass__ = __le__ = __lt__ = __ne__ = __new__ = __reduce__ = __reduce_ex__ = __repr__ = __setattr__ = __setstate__ = __sizeof__ = __str__ = __subclasshook__ = __suppress_context__ = False __traceback__ = args = () with_traceback = The above is a description of an error in a Python program. Here is the original traceback: Traceback (most recent call last): File "/contrail-test/tcutils/wrappers.py", line 80, in wrapper result = function(self, *args, **kwargs) File "/contrail-test/scripts/analytics/test_analytics_basic.py", line 34, in test_verify_object_logs assert vm1_fixture.wait_till_vm_is_active() AssertionError 2025-09-18 10:16:49,076 - DEBUG - No XMPP flaps were noticed during the test 2025-09-18 10:16:49,076 - INFO -
2025-09-18 10:16:49,076 - INFO - END TEST : test_verify_object_logs : FAILED[0:00:11]
2025-09-18 10:16:49,076 - INFO - -------------------------------------------------------------------------------- 2025-09-18 10:16:49,747 - INFO - Deleted project: ctest-AnalyticsBasicTestSanity-04076698, ID : 2b41efd7-bfa8-4a46-89c1-bb14e6c8e39c 2025-09-18 10:16:58,560 - DEBUG - Requesting: http://10.0.0.254:8083/Snh_IFMapTableShowReq?table_name=virtual-network&search_string=virtual-network:default-domain:ctest-FloatingipBasicTestSanity-69436727:ctest-fvn-96799699 2025-09-18 10:16:58,564 - DEBUG - Requesting: http://10.0.0.254:8083/Snh_ShowRoutingInstanceReq?name=default-domain:ctest-FloatingipBasicTestSanity-69436727:ctest-fvn-96799699:ctest-fvn-96799699 2025-09-18 10:16:59,679 - DEBUG - Requesting: http://10.0.0.254:8083/Snh_IFMapTableShowReq?table_name=virtual-network&search_string=virtual-network:default-domain:ctest-FloatingipBasicTestSanity-69436727:ctest-vn1-12105077 2025-09-18 10:16:59,684 - DEBUG - Requesting: http://10.0.0.254:8083/Snh_ShowRoutingInstanceReq?name=default-domain:ctest-FloatingipBasicTestSanity-69436727:ctest-vn1-12105077:ctest-vn1-12105077 2025-09-18 10:17:02,916 - DEBUG - Requesting: http://10.0.0.254:8083/Snh_IFMapTableShowReq?table_name=floating-ip-pool&search_string=floating-ip-pool:default-domain:ctest-FloatingipBasicTestSanity-69436727:ctest-fvn-96799699:ctest-some-pool-37456504 2025-09-18 10:17:08,213 - DEBUG - Requesting: http://10.0.0.254:8083/Snh_IFMapTableShowReq?table_name=floating-ip-pool&search_string=floating-ip-pool:default-domain:ctest-FloatingipBasicTestSanity-69436727:ctest-fvn-96799699:ctest-some-pool-37456504 2025-09-18 10:17:08,707 - DEBUG - Requesting: http://10.0.0.254:8083/Snh_ShowRoutingInstanceReq?name=default-domain:ctest-FloatingipBasicTestSanity-69436727:ctest-vn1-12105077:ctest-vn1-12105077 2025-09-18 10:17:08,710 - DEBUG - Requesting: http://10.0.0.254:8083/Snh_IFMapTableShowReq?table_name=virtual-network&search_string=virtual-network:default-domain:ctest-FloatingipBasicTestSanity-69436727:ctest-vn1-12105077 2025-09-18 10:17:08,969 - DEBUG - Requesting: http://10.0.0.254:8083/Snh_ShowRoutingInstanceReq?name=default-domain:ctest-FloatingipBasicTestSanity-69436727:ctest-fvn-96799699:ctest-fvn-96799699 2025-09-18 10:17:08,974 - DEBUG - Requesting: http://10.0.0.254:8083/Snh_IFMapTableShowReq?table_name=virtual-network&search_string=virtual-network:default-domain:ctest-FloatingipBasicTestSanity-69436727:ctest-fvn-96799699 2025-09-18 10:23:33,192 - DEBUG - Requesting: http://10.0.0.254:8083/Snh_IFMapTableShowReq?table_name=virtual-network&search_string=virtual-network:default-domain:ctest-TestBasicPolicyIpv4v6-44352396:ctest-vn-47579489 2025-09-18 10:23:33,198 - DEBUG - Requesting: http://10.0.0.254:8083/Snh_ShowRoutingInstanceReq?name=default-domain:ctest-TestBasicPolicyIpv4v6-44352396:ctest-vn-47579489:ctest-vn-47579489 2025-09-18 10:23:33,830 - DEBUG - Requesting: http://10.0.0.254:8083/Snh_IFMapTableShowReq?table_name=virtual-network&search_string=virtual-network:default-domain:ctest-TestBasicPolicyIpv4v6-44352396:ctest-vn-14074639 2025-09-18 10:23:33,834 - DEBUG - Requesting: http://10.0.0.254:8083/Snh_ShowRoutingInstanceReq?name=default-domain:ctest-TestBasicPolicyIpv4v6-44352396:ctest-vn-14074639:ctest-vn-14074639 2025-09-18 10:23:34,492 - DEBUG - Requesting: http://10.0.0.254:8083/Snh_ShowRoutingInstanceReq?name=default-domain:ctest-TestBasicPolicyIpv4v6-44352396:ctest-vn-14074639:ctest-vn-14074639 2025-09-18 10:23:34,496 - DEBUG - Requesting: http://10.0.0.254:8083/Snh_IFMapTableShowReq?table_name=virtual-network&search_string=virtual-network:default-domain:ctest-TestBasicPolicyIpv4v6-44352396:ctest-vn-14074639 2025-09-18 10:23:34,743 - DEBUG - Requesting: http://10.0.0.254:8083/Snh_ShowRoutingInstanceReq?name=default-domain:ctest-TestBasicPolicyIpv4v6-44352396:ctest-vn-47579489:ctest-vn-47579489 2025-09-18 10:23:34,747 - DEBUG - Requesting: http://10.0.0.254:8083/Snh_IFMapTableShowReq?table_name=virtual-network&search_string=virtual-network:default-domain:ctest-TestBasicPolicyIpv4v6-44352396:ctest-vn-47579489 2025-09-18 10:23:40,161 - DEBUG - Requesting: http://10.0.0.254:8083/Snh_IFMapTableShowReq?table_name=virtual-network&search_string=virtual-network:default-domain:ctest-TestBasicPolicyNegativeIpv4v6-85053043:ctest-vn4-27203763 2025-09-18 10:23:40,166 - DEBUG - Requesting: http://10.0.0.254:8083/Snh_ShowRoutingInstanceReq?name=default-domain:ctest-TestBasicPolicyNegativeIpv4v6-85053043:ctest-vn4-27203763:ctest-vn4-27203763 2025-09-18 10:23:41,012 - DEBUG - Requesting: http://10.0.0.254:8083/Snh_ShowRoutingInstanceReq?name=default-domain:ctest-TestBasicPolicyNegativeIpv4v6-85053043:ctest-vn4-27203763:ctest-vn4-27203763 2025-09-18 10:23:41,017 - DEBUG - Requesting: http://10.0.0.254:8083/Snh_IFMapTableShowReq?table_name=virtual-network&search_string=virtual-network:default-domain:ctest-TestBasicPolicyNegativeIpv4v6-85053043:ctest-vn4-27203763 2025-09-18 10:23:47,391 - DEBUG - Requesting: http://10.0.0.254:8083/Snh_IFMapTableShowReq?table_name=virtual-network&search_string=virtual-network:default-domain:ctest-TestBasicPolicy-42088927:ctest-vn-84346847 2025-09-18 10:23:47,396 - DEBUG - Requesting: http://10.0.0.254:8083/Snh_ShowRoutingInstanceReq?name=default-domain:ctest-TestBasicPolicy-42088927:ctest-vn-84346847:ctest-vn-84346847 2025-09-18 10:23:48,021 - DEBUG - Requesting: http://10.0.0.254:8083/Snh_IFMapTableShowReq?table_name=virtual-network&search_string=virtual-network:default-domain:ctest-TestBasicPolicy-42088927:ctest-vn-55781753 2025-09-18 10:23:48,029 - DEBUG - Requesting: http://10.0.0.254:8083/Snh_ShowRoutingInstanceReq?name=default-domain:ctest-TestBasicPolicy-42088927:ctest-vn-55781753:ctest-vn-55781753 2025-09-18 10:29:20,611 - DEBUG - Requesting: http://10.0.0.254:8083/Snh_ShowRoutingInstanceReq?name=default-domain:ctest-TestBasicPolicy-42088927:ctest-vn-55781753:ctest-vn-55781753 2025-09-18 10:29:20,615 - DEBUG - Requesting: http://10.0.0.254:8083/Snh_IFMapTableShowReq?table_name=virtual-network&search_string=virtual-network:default-domain:ctest-TestBasicPolicy-42088927:ctest-vn-55781753 2025-09-18 10:29:25,620 - DEBUG - Requesting: http://10.0.0.254:8083/Snh_ShowRoutingInstanceReq?name=default-domain:ctest-TestBasicPolicy-42088927:ctest-vn-55781753:ctest-vn-55781753 2025-09-18 10:29:25,625 - DEBUG - Requesting: http://10.0.0.254:8083/Snh_IFMapTableShowReq?table_name=virtual-network&search_string=virtual-network:default-domain:ctest-TestBasicPolicy-42088927:ctest-vn-55781753 2025-09-18 10:29:25,871 - DEBUG - Requesting: http://10.0.0.254:8083/Snh_ShowRoutingInstanceReq?name=default-domain:ctest-TestBasicPolicy-42088927:ctest-vn-84346847:ctest-vn-84346847 2025-09-18 10:29:25,875 - DEBUG - Requesting: http://10.0.0.254:8083/Snh_IFMapTableShowReq?table_name=virtual-network&search_string=virtual-network:default-domain:ctest-TestBasicPolicy-42088927:ctest-vn-84346847 2025-09-18 10:29:43,163 - DEBUG - Requesting: http://10.0.0.254:8083/Snh_IFMapTableShowReq?table_name=virtual-network&search_string=virtual-network:default-domain:ctest-TestSvcRegr-57156291:ctest-mgmt_vn-09262782 2025-09-18 10:29:43,169 - DEBUG - Requesting: http://10.0.0.254:8083/Snh_ShowRoutingInstanceReq?name=default-domain:ctest-TestSvcRegr-57156291:ctest-mgmt_vn-09262782:ctest-mgmt_vn-09262782 2025-09-18 10:29:44,282 - DEBUG - Requesting: http://10.0.0.254:8083/Snh_IFMapTableShowReq?table_name=virtual-network&search_string=virtual-network:default-domain:ctest-TestSvcRegr-57156291:ctest-left_vn-74996558 2025-09-18 10:29:44,287 - DEBUG - Requesting: http://10.0.0.254:8083/Snh_ShowRoutingInstanceReq?name=default-domain:ctest-TestSvcRegr-57156291:ctest-left_vn-74996558:ctest-left_vn-74996558 2025-09-18 10:29:45,102 - DEBUG - Requesting: http://10.0.0.254:8083/Snh_IFMapTableShowReq?table_name=virtual-network&search_string=virtual-network:default-domain:ctest-TestSvcRegr-57156291:ctest-right_vn-60974941 2025-09-18 10:29:45,107 - DEBUG - Requesting: http://10.0.0.254:8083/Snh_ShowRoutingInstanceReq?name=default-domain:ctest-TestSvcRegr-57156291:ctest-right_vn-60974941:ctest-right_vn-60974941 2025-09-18 10:29:55,698 - DEBUG - Requesting: http://10.0.0.254:8083/Snh_ShowRoutingInstanceReq?name=default-domain:ctest-TestSvcRegr-57156291:ctest-right_vn-60974941:ctest-right_vn-60974941 2025-09-18 10:29:55,702 - DEBUG - Requesting: http://10.0.0.254:8083/Snh_IFMapTableShowReq?table_name=virtual-network&search_string=virtual-network:default-domain:ctest-TestSvcRegr-57156291:ctest-right_vn-60974941 2025-09-18 10:29:55,939 - DEBUG - Requesting: http://10.0.0.254:8083/Snh_ShowRoutingInstanceReq?name=default-domain:ctest-TestSvcRegr-57156291:ctest-left_vn-74996558:ctest-left_vn-74996558 2025-09-18 10:29:55,943 - DEBUG - Requesting: http://10.0.0.254:8083/Snh_IFMapTableShowReq?table_name=virtual-network&search_string=virtual-network:default-domain:ctest-TestSvcRegr-57156291:ctest-left_vn-74996558 2025-09-18 10:29:56,177 - DEBUG - Requesting: http://10.0.0.254:8083/Snh_ShowRoutingInstanceReq?name=default-domain:ctest-TestSvcRegr-57156291:ctest-mgmt_vn-09262782:ctest-mgmt_vn-09262782 2025-09-18 10:29:56,181 - DEBUG - Requesting: http://10.0.0.254:8083/Snh_IFMapTableShowReq?table_name=virtual-network&search_string=virtual-network:default-domain:ctest-TestSvcRegr-57156291:ctest-mgmt_vn-09262782 2025-09-18 10:30:00,128 - DEBUG - Requesting: http://10.0.0.254:8083/Snh_IFMapTableShowReq?table_name=virtual-DNS&search_string=virtual-DNS:default-domain:ctest-vdns1-52181661 2025-09-18 10:30:08,045 - DEBUG - Requesting: http://10.0.0.254:8083/Snh_IFMapTableShowReq?table_name=virtual-DNS&search_string=virtual-DNS:default-domain:ctest-vdns1-52181661 2025-09-18 10:30:42,049 - DEBUG - Requesting: http://10.0.0.254:8083/Snh_IFMapTableShowReq?table_name=network-ipam&search_string=network-ipam:default-domain:ctest-TestBasicVMVN-85622688:ctest-ipam-65916617 2025-09-18 10:30:42,291 - DEBUG - Requesting: http://10.0.0.254:8083/Snh_IFMapTableShowReq?table_name=virtual-network&search_string=virtual-network:default-domain:ctest-TestBasicVMVN-85622688:ctest-vn-66945209 2025-09-18 10:30:42,300 - DEBUG - Requesting: http://10.0.0.254:8083/Snh_ShowRoutingInstanceReq?name=default-domain:ctest-TestBasicVMVN-85622688:ctest-vn-66945209:ctest-vn-66945209 2025-09-18 10:30:43,696 - DEBUG - Requesting: http://10.0.0.254:8083/Snh_ShowRoutingInstanceReq?name=default-domain:ctest-TestBasicVMVN-85622688:ctest-vn-66945209:ctest-vn-66945209 2025-09-18 10:30:43,700 - DEBUG - Requesting: http://10.0.0.254:8083/Snh_IFMapTableShowReq?table_name=virtual-network&search_string=virtual-network:default-domain:ctest-TestBasicVMVN-85622688:ctest-vn-66945209 2025-09-18 10:30:43,775 - DEBUG - Requesting: http://10.0.0.254:8083/Snh_ShowRoutingInstanceReq?name=default-domain:ctest-TestBasicVMVN-85622688:ctest-ipam-65916617:ctest-ipam-65916617 2025-09-18 10:30:43,780 - DEBUG - Requesting: http://10.0.0.254:8083/Snh_IFMapTableShowReq?table_name=network-ipam&search_string=network-ipam:default-domain:ctest-TestBasicVMVN-85622688:ctest-ipam-65916617