is in ACTIVE state now
2025-08-18 03:37:33,441 - INFO - VM name : mltf_vm
2025-08-18 03:37:33,537 - DEBUG - VM mltf_vm ID is 205df6c7-f442-4b45-a6b8-5a53c83888da
2025-08-18 03:37:33,537 - DEBUG - VM mltf_vm launched on Node an-jenkins-deploy-platform-ansible-os-3870-1
2025-08-18 03:37:33,759 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine/205df6c7-f442-4b45-a6b8-5a53c83888da
2025-08-18 03:37:33,770 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine-interface/1635a3a4-012e-4467-9af9-48ada87ca729
2025-08-18 03:37:33,780 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine-interface/d0e6af5c-a6a0-461d-97ee-d205f3e9f595
2025-08-18 03:37:34,957 - 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=1.97 ms\r\n64 bytes from 169.254.0.5: icmp_seq=2 ttl=63 time=0.490 ms\r\n\r\n--- 169.254.0.5 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1000ms\r\nrtt min/avg/max/mdev = 0.490/1.230/1.970/0.740 ms')
2025-08-18 03:37:34,957 - INFO - Ping to Metadata IP 169.254.0.5 of VM mltf_vm passed
2025-08-18 03:37:35,027 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-18 03:37:35,028 - DEBUG - Waiting to SSH to VM mltf_vm, IP 141.192.114.5, Port 22
2025-08-18 03:37:35,189 - DEBUG - VM mltf_vm is ready for SSH connections
2025-08-18 03:37:35,263 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-18 03:37:35,263 - DEBUG - Waiting to SSH to VM mltf_vm, IP 141.192.114.5, Port 22
2025-08-18 03:37:35,432 - DEBUG - VM mltf_vm is ready for SSH connections
2025-08-18 03:37:35,434 - INFO - ================================================================================
2025-08-18 03:37:35,434 - INFO - STARTING TEST : test_verify_session_sampling_teardown
2025-08-18 03:37:35,434 - INFO - TEST DESCRIPTION :
1.query client session samples
2.query server session samples
3.query client session to get number of sessions exported
4.query session record table for teardown bytes/pkts
5.query sample count after teardown on server side
2025-08-18 03:37:36,704 - DEBUG - Skipping xmpp flap check
2025-08-18 03:37:36,705 - INFO - Initial checks done. Running the testcase now
2025-08-18 03:37:36,705 - INFO -
2025-08-18 03:37:36,834 - INFO - Setting flow export rate: 100
2025-08-18 03:37:36,886 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = icmp, src_addresses = [subnet = None, virtual_network = any, security_group = None, network_policy = None, subnet_list = []], src_ports = [start_port = -1, end_port = -1], application = [], dst_addresses = [subnet = None, virtual_network = any, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = -1, end_port = -1], action_list = simple_action = pass, gateway_name = None, apply_service = [], service_properties = None, mirror_to = None, assign_routing_instance = None, log = False, alert = False, qos_action = None, host_based_service = False, ethertype = None, created = None, last_modified = None, description = None]
2025-08-18 03:37:37,223 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': 'bfe298ea-0b9c-4b38-8cc1-73ae305f143d', 'name': 'ctest-vn1-63553540', 'tenant_id': '15a95a4e423f42e29565a347bdb71b6b', 'project_id': '15a95a4e423f42e29565a347bdb71b6b', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['3aa53cc3-0aba-4690-a821-b6f40d99216b'], 'fq_name': ['default-domain', 'ctest-AnalyticsTestSanityWithResource-91895630', 'ctest-vn1-63553540'], 'policys': [['default-domain', 'ctest-AnalyticsTestSanityWithResource-91895630', 'policy1']], 'subnet_ipam': [{'subnet_cidr': '141.192.114.0/26', 'ipam_fq_name': ['default-domain', 'default-project', 'default-network-ipam']}], 'port_security_enabled': True, 'description': ''}}
2025-08-18 03:37:37,422 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': 'd5b25ede-cf8d-4bf2-aeb8-12f2e9e47f76', 'name': 'ctest-vn2-85727533', 'tenant_id': '15a95a4e423f42e29565a347bdb71b6b', 'project_id': '15a95a4e423f42e29565a347bdb71b6b', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['d978c42c-f352-40c1-8326-7b57c772059d'], 'fq_name': ['default-domain', 'ctest-AnalyticsTestSanityWithResource-91895630', 'ctest-vn2-85727533'], 'policys': [['default-domain', 'ctest-AnalyticsTestSanityWithResource-91895630', 'policy1']], 'subnet_ipam': [{'subnet_cidr': '112.10.24.0/26', 'ipam_fq_name': ['default-domain', 'default-project', 'default-network-ipam']}], 'port_security_enabled': True, 'description': ''}}
2025-08-18 03:37:37,516 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-18 03:37:37,523 - DEBUG - Requesting: http://10.0.0.27:8082/domain/edf943fc-0622-4564-8821-4de9bd04e8a3
2025-08-18 03:37:37,538 - DEBUG - Requesting: http://10.0.0.27:8082/project/15a95a4e-423f-42e2-9565-a347bdb71b6b
2025-08-18 03:37:37,632 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/bfe298ea-0b9c-4b38-8cc1-73ae305f143d
2025-08-18 03:37:37,645 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/bfe298ea-0b9c-4b38-8cc1-73ae305f143d
2025-08-18 03:37:37,657 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/625894ca-32ab-4e81-b515-759b4bb55ed3
2025-08-18 03:37:37,666 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/625894ca-32ab-4e81-b515-759b4bb55ed3
2025-08-18 03:37:37,675 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/12d2e2fb-f2c6-480d-8f0f-e73c271a7ad5
2025-08-18 03:37:37,736 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-18 03:37:37,736 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/bfe298ea-0b9c-4b38-8cc1-73ae305f143d
2025-08-18 03:37:37,750 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/625894ca-32ab-4e81-b515-759b4bb55ed3
2025-08-18 03:37:37,770 - INFO - Verified VN network id 13 for VN bfe298ea-0b9c-4b38-8cc1-73ae305f143d
2025-08-18 03:37:37,770 - INFO - Verifications in API Server for VN ctest-vn1-63553540 passed
2025-08-18 03:37:37,770 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/bfe298ea-0b9c-4b38-8cc1-73ae305f143d
2025-08-18 03:37:37,781 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/625894ca-32ab-4e81-b515-759b4bb55ed3
2025-08-18 03:37:37,790 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/625894ca-32ab-4e81-b515-759b4bb55ed3
2025-08-18 03:37:37,799 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/12d2e2fb-f2c6-480d-8f0f-e73c271a7ad5
2025-08-18 03:37:37,815 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-91895630:ctest-vn1-63553540', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-91895630', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13826781936965405496', 'uuid-lslong': '10142515027788108861'}, 'enable': 'true', 'created': '2025-08-18T03:36:16', 'last-modified': '2025-08-18T03:37:37', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.635337'}
2025-08-18 03:37:37,842 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-18 03:37:37,853 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-91895630:ctest-vn1-63553540', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-91895630', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13826781936965405496', 'uuid-lslong': '10142515027788108861'}, 'enable': 'true', 'created': '2025-08-18T03:36:16', 'last-modified': '2025-08-18T03:37:37', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.658716'}
2025-08-18 03:37:37,877 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-18 03:37:37,895 - DEBUG - Control-node 10.0.0.19 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-91895630:ctest-vn1-63553540', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-91895630', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13826781936965405496', 'uuid-lslong': '10142515027788108861'}, 'enable': 'true', 'created': '2025-08-18T03:36:16', 'last-modified': '2025-08-18T03:37:37', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.716619'}
2025-08-18 03:37:37,903 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-18 03:37:37,903 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn1-63553540 passed
2025-08-18 03:37:37,904 - DEBUG - ====Verifying policy data for ctest-vn1-63553540 in API_Server ======
2025-08-18 03:37:37,905 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-18 03:37:37,916 - DEBUG - Requesting: http://10.0.0.27:8082/domain/edf943fc-0622-4564-8821-4de9bd04e8a3
2025-08-18 03:37:37,930 - DEBUG - Requesting: http://10.0.0.27:8082/project/15a95a4e-423f-42e2-9565-a347bdb71b6b
2025-08-18 03:37:38,021 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/bfe298ea-0b9c-4b38-8cc1-73ae305f143d
2025-08-18 03:37:38,038 - DEBUG - ==>Verifying data for policy with id: 071edcc6-908c-466f-a538-456f73b1a128, fqn: ['default-domain', 'ctest-AnalyticsTestSanityWithResource-91895630', 'policy1']
2025-08-18 03:37:38,038 - INFO - VN ctest-vn1-63553540 Policy verification: verify_vn_policy_in_api_server, status: True
2025-08-18 03:37:38,038 - DEBUG - Verifying the vn in opserver
2025-08-18 03:37:38,038 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-91895630:ctest-vn1-63553540 virtual network link through opserver 10.0.0.27
2025-08-18 03:37:38,039 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-08-18 03:37:38,052 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-91895630:ctest-vn1-63553540', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-91895630:ctest-vn1-63553540?flat'}
2025-08-18 03:37:38,052 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-91895630:ctest-vn1-63553540 virtual network link through opserver 10.0.0.38
2025-08-18 03:37:38,052 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-18 03:37:38,067 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-91895630:ctest-vn1-63553540', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-91895630:ctest-vn1-63553540?flat'}
2025-08-18 03:37:38,067 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-91895630:ctest-vn1-63553540 virtual network link through opserver 10.0.0.19
2025-08-18 03:37:38,067 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks
2025-08-18 03:37:38,079 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-91895630:ctest-vn1-63553540', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-91895630:ctest-vn1-63553540?flat'}
2025-08-18 03:37:38,079 - INFO - Validated that VN default-domain:ctest-AnalyticsTestSanityWithResource-91895630:ctest-vn1-63553540 is found in opserver
2025-08-18 03:37:38,090 - DEBUG - VRF ids for VN ctest-vn1-63553540: {'10.0.0.241': '2'}
2025-08-18 03:37:38,090 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-18 03:37:38,096 - DEBUG - Requesting: http://10.0.0.27:8082/domain/edf943fc-0622-4564-8821-4de9bd04e8a3
2025-08-18 03:37:38,107 - DEBUG - Requesting: http://10.0.0.27:8082/project/15a95a4e-423f-42e2-9565-a347bdb71b6b
2025-08-18 03:37:38,193 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/d5b25ede-cf8d-4bf2-aeb8-12f2e9e47f76
2025-08-18 03:37:38,209 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/d5b25ede-cf8d-4bf2-aeb8-12f2e9e47f76
2025-08-18 03:37:38,224 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/961bcbd1-cc00-4e47-b556-9561c4680531
2025-08-18 03:37:38,232 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/961bcbd1-cc00-4e47-b556-9561c4680531
2025-08-18 03:37:38,240 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/92db1c00-0f43-41d1-b0dc-4e7f8510823f
2025-08-18 03:37:38,247 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-18 03:37:38,247 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/d5b25ede-cf8d-4bf2-aeb8-12f2e9e47f76
2025-08-18 03:37:38,259 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/961bcbd1-cc00-4e47-b556-9561c4680531
2025-08-18 03:37:38,275 - INFO - Verified VN network id 14 for VN d5b25ede-cf8d-4bf2-aeb8-12f2e9e47f76
2025-08-18 03:37:38,275 - INFO - Verifications in API Server for VN ctest-vn2-85727533 passed
2025-08-18 03:37:38,275 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/d5b25ede-cf8d-4bf2-aeb8-12f2e9e47f76
2025-08-18 03:37:38,286 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/961bcbd1-cc00-4e47-b556-9561c4680531
2025-08-18 03:37:38,295 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/961bcbd1-cc00-4e47-b556-9561c4680531
2025-08-18 03:37:38,304 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/92db1c00-0f43-41d1-b0dc-4e7f8510823f
2025-08-18 03:37:38,317 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-91895630:ctest-vn2-85727533', 'interests': '0-1', 'advertised': '0-1', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-91895630', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15398474386991041522', 'uuid-lslong': '12589833592829673334'}, 'enable': 'true', 'created': '2025-08-18T03:36:54', 'last-modified': '2025-08-18T03:37:37', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.937920'}
2025-08-18 03:37:38,324 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-18 03:37:38,329 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-91895630:ctest-vn2-85727533', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-91895630', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15398474386991041522', 'uuid-lslong': '12589833592829673334'}, 'enable': 'true', 'created': '2025-08-18T03:36:54', 'last-modified': '2025-08-18T03:37:37', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.956600'}
2025-08-18 03:37:38,336 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-18 03:37:38,342 - DEBUG - Control-node 10.0.0.19 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-91895630:ctest-vn2-85727533', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-91895630', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15398474386991041522', 'uuid-lslong': '12589833592829673334'}, 'enable': 'true', 'created': '2025-08-18T03:36:54', 'last-modified': '2025-08-18T03:37:37', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.963919'}
2025-08-18 03:37:38,349 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-18 03:37:38,349 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn2-85727533 passed
2025-08-18 03:37:38,349 - DEBUG - ====Verifying policy data for ctest-vn2-85727533 in API_Server ======
2025-08-18 03:37:38,349 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-18 03:37:38,355 - DEBUG - Requesting: http://10.0.0.27:8082/domain/edf943fc-0622-4564-8821-4de9bd04e8a3
2025-08-18 03:37:38,366 - DEBUG - Requesting: http://10.0.0.27:8082/project/15a95a4e-423f-42e2-9565-a347bdb71b6b
2025-08-18 03:37:38,458 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/d5b25ede-cf8d-4bf2-aeb8-12f2e9e47f76
2025-08-18 03:37:38,471 - DEBUG - ==>Verifying data for policy with id: 071edcc6-908c-466f-a538-456f73b1a128, fqn: ['default-domain', 'ctest-AnalyticsTestSanityWithResource-91895630', 'policy1']
2025-08-18 03:37:38,471 - INFO - VN ctest-vn2-85727533 Policy verification: verify_vn_policy_in_api_server, status: True
2025-08-18 03:37:38,471 - DEBUG - Verifying the vn in opserver
2025-08-18 03:37:38,471 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-91895630:ctest-vn2-85727533 virtual network link through opserver 10.0.0.27
2025-08-18 03:37:38,472 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-08-18 03:37:38,482 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-91895630:ctest-vn2-85727533', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-91895630:ctest-vn2-85727533?flat'}
2025-08-18 03:37:38,482 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-91895630:ctest-vn2-85727533 virtual network link through opserver 10.0.0.38
2025-08-18 03:37:38,482 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-18 03:37:38,494 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-91895630:ctest-vn2-85727533', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-91895630:ctest-vn2-85727533?flat'}
2025-08-18 03:37:38,494 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-91895630:ctest-vn2-85727533 virtual network link through opserver 10.0.0.19
2025-08-18 03:37:38,494 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks
2025-08-18 03:37:38,504 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-91895630:ctest-vn2-85727533', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-91895630:ctest-vn2-85727533?flat'}
2025-08-18 03:37:38,504 - INFO - Validated that VN default-domain:ctest-AnalyticsTestSanityWithResource-91895630:ctest-vn2-85727533 is found in opserver
2025-08-18 03:37:38,515 - DEBUG - VRF ids for VN ctest-vn2-85727533: {'10.0.0.241': '3', '10.0.0.41': '2'}
2025-08-18 03:37:38,515 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-18 03:37:38,521 - DEBUG - Requesting: http://10.0.0.27:8082/domain/edf943fc-0622-4564-8821-4de9bd04e8a3
2025-08-18 03:37:38,532 - DEBUG - Requesting: http://10.0.0.27:8082/project/15a95a4e-423f-42e2-9565-a347bdb71b6b
2025-08-18 03:37:38,624 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/bfe298ea-0b9c-4b38-8cc1-73ae305f143d
2025-08-18 03:37:38,635 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/bfe298ea-0b9c-4b38-8cc1-73ae305f143d
2025-08-18 03:37:38,645 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/625894ca-32ab-4e81-b515-759b4bb55ed3
2025-08-18 03:37:38,654 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/625894ca-32ab-4e81-b515-759b4bb55ed3
2025-08-18 03:37:38,662 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/12d2e2fb-f2c6-480d-8f0f-e73c271a7ad5
2025-08-18 03:37:38,669 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-18 03:37:38,669 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/bfe298ea-0b9c-4b38-8cc1-73ae305f143d
2025-08-18 03:37:38,679 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/625894ca-32ab-4e81-b515-759b4bb55ed3
2025-08-18 03:37:38,694 - INFO - Verified VN network id 13 for VN bfe298ea-0b9c-4b38-8cc1-73ae305f143d
2025-08-18 03:37:38,694 - INFO - Verifications in API Server for VN ctest-vn1-63553540 passed
2025-08-18 03:37:38,694 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/bfe298ea-0b9c-4b38-8cc1-73ae305f143d
2025-08-18 03:37:38,703 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/625894ca-32ab-4e81-b515-759b4bb55ed3
2025-08-18 03:37:38,711 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/625894ca-32ab-4e81-b515-759b4bb55ed3
2025-08-18 03:37:38,718 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/12d2e2fb-f2c6-480d-8f0f-e73c271a7ad5
2025-08-18 03:37:38,730 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-91895630:ctest-vn1-63553540', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-91895630', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13826781936965405496', 'uuid-lslong': '10142515027788108861'}, 'enable': 'true', 'created': '2025-08-18T03:36:16', 'last-modified': '2025-08-18T03:37:37', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.551882'}
2025-08-18 03:37:38,736 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-18 03:37:38,742 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-91895630:ctest-vn1-63553540', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-91895630', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13826781936965405496', 'uuid-lslong': '10142515027788108861'}, 'enable': 'true', 'created': '2025-08-18T03:36:16', 'last-modified': '2025-08-18T03:37:37', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.550749'}
2025-08-18 03:37:38,748 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-18 03:37:38,753 - DEBUG - Control-node 10.0.0.19 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-91895630:ctest-vn1-63553540', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-91895630', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13826781936965405496', 'uuid-lslong': '10142515027788108861'}, 'enable': 'true', 'created': '2025-08-18T03:36:16', 'last-modified': '2025-08-18T03:37:37', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.575199'}
2025-08-18 03:37:38,759 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-18 03:37:38,760 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn1-63553540 passed
2025-08-18 03:37:38,760 - DEBUG - ====Verifying policy data for ctest-vn1-63553540 in API_Server ======
2025-08-18 03:37:38,760 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-18 03:37:38,765 - DEBUG - Requesting: http://10.0.0.27:8082/domain/edf943fc-0622-4564-8821-4de9bd04e8a3
2025-08-18 03:37:38,776 - DEBUG - Requesting: http://10.0.0.27:8082/project/15a95a4e-423f-42e2-9565-a347bdb71b6b
2025-08-18 03:37:38,876 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/bfe298ea-0b9c-4b38-8cc1-73ae305f143d
2025-08-18 03:37:38,886 - DEBUG - ==>Verifying data for policy with id: 071edcc6-908c-466f-a538-456f73b1a128, fqn: ['default-domain', 'ctest-AnalyticsTestSanityWithResource-91895630', 'policy1']
2025-08-18 03:37:38,886 - INFO - VN ctest-vn1-63553540 Policy verification: verify_vn_policy_in_api_server, status: True
2025-08-18 03:37:38,886 - DEBUG - Verifying the vn in opserver
2025-08-18 03:37:38,886 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-91895630:ctest-vn1-63553540 virtual network link through opserver 10.0.0.27
2025-08-18 03:37:38,886 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-08-18 03:37:38,899 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-91895630:ctest-vn1-63553540', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-91895630:ctest-vn1-63553540?flat'}
2025-08-18 03:37:38,899 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-91895630:ctest-vn1-63553540 virtual network link through opserver 10.0.0.38
2025-08-18 03:37:38,899 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-18 03:37:38,912 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-91895630:ctest-vn1-63553540', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-91895630:ctest-vn1-63553540?flat'}
2025-08-18 03:37:38,912 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-91895630:ctest-vn1-63553540 virtual network link through opserver 10.0.0.19
2025-08-18 03:37:38,912 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks
2025-08-18 03:37:38,923 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-91895630:ctest-vn1-63553540', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-91895630:ctest-vn1-63553540?flat'}
2025-08-18 03:37:38,923 - INFO - Validated that VN default-domain:ctest-AnalyticsTestSanityWithResource-91895630:ctest-vn1-63553540 is found in opserver
2025-08-18 03:37:38,934 - DEBUG - VRF ids for VN ctest-vn1-63553540: {'10.0.0.241': '2'}
2025-08-18 03:37:38,934 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-18 03:37:38,939 - DEBUG - Requesting: http://10.0.0.27:8082/domain/edf943fc-0622-4564-8821-4de9bd04e8a3
2025-08-18 03:37:38,950 - DEBUG - Requesting: http://10.0.0.27:8082/project/15a95a4e-423f-42e2-9565-a347bdb71b6b
2025-08-18 03:37:39,036 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/d5b25ede-cf8d-4bf2-aeb8-12f2e9e47f76
2025-08-18 03:37:39,047 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/d5b25ede-cf8d-4bf2-aeb8-12f2e9e47f76
2025-08-18 03:37:39,056 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/961bcbd1-cc00-4e47-b556-9561c4680531
2025-08-18 03:37:39,063 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/961bcbd1-cc00-4e47-b556-9561c4680531
2025-08-18 03:37:39,070 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/92db1c00-0f43-41d1-b0dc-4e7f8510823f
2025-08-18 03:37:39,076 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-18 03:37:39,076 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/d5b25ede-cf8d-4bf2-aeb8-12f2e9e47f76
2025-08-18 03:37:39,085 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/961bcbd1-cc00-4e47-b556-9561c4680531
2025-08-18 03:37:39,099 - INFO - Verified VN network id 14 for VN d5b25ede-cf8d-4bf2-aeb8-12f2e9e47f76
2025-08-18 03:37:39,099 - INFO - Verifications in API Server for VN ctest-vn2-85727533 passed
2025-08-18 03:37:39,099 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/d5b25ede-cf8d-4bf2-aeb8-12f2e9e47f76
2025-08-18 03:37:39,109 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/961bcbd1-cc00-4e47-b556-9561c4680531
2025-08-18 03:37:39,116 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/961bcbd1-cc00-4e47-b556-9561c4680531
2025-08-18 03:37:39,123 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/92db1c00-0f43-41d1-b0dc-4e7f8510823f
2025-08-18 03:37:39,136 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-91895630:ctest-vn2-85727533', 'interests': '0-1', 'advertised': '0-1', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-91895630', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15398474386991041522', 'uuid-lslong': '12589833592829673334'}, 'enable': 'true', 'created': '2025-08-18T03:36:54', 'last-modified': '2025-08-18T03:37:37', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.757679'}
2025-08-18 03:37:39,142 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-18 03:37:39,148 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-91895630:ctest-vn2-85727533', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-91895630', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15398474386991041522', 'uuid-lslong': '12589833592829673334'}, 'enable': 'true', 'created': '2025-08-18T03:36:54', 'last-modified': '2025-08-18T03:37:37', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.775280'}
2025-08-18 03:37:39,154 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-18 03:37:39,160 - DEBUG - Control-node 10.0.0.19 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-91895630:ctest-vn2-85727533', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-91895630', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15398474386991041522', 'uuid-lslong': '12589833592829673334'}, 'enable': 'true', 'created': '2025-08-18T03:36:54', 'last-modified': '2025-08-18T03:37:37', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.782159'}
2025-08-18 03:37:39,166 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-18 03:37:39,166 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn2-85727533 passed
2025-08-18 03:37:39,166 - DEBUG - ====Verifying policy data for ctest-vn2-85727533 in API_Server ======
2025-08-18 03:37:39,166 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-18 03:37:39,172 - DEBUG - Requesting: http://10.0.0.27:8082/domain/edf943fc-0622-4564-8821-4de9bd04e8a3
2025-08-18 03:37:39,182 - DEBUG - Requesting: http://10.0.0.27:8082/project/15a95a4e-423f-42e2-9565-a347bdb71b6b
2025-08-18 03:37:39,276 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/d5b25ede-cf8d-4bf2-aeb8-12f2e9e47f76
2025-08-18 03:37:39,286 - DEBUG - ==>Verifying data for policy with id: 071edcc6-908c-466f-a538-456f73b1a128, fqn: ['default-domain', 'ctest-AnalyticsTestSanityWithResource-91895630', 'policy1']
2025-08-18 03:37:39,286 - INFO - VN ctest-vn2-85727533 Policy verification: verify_vn_policy_in_api_server, status: True
2025-08-18 03:37:39,286 - DEBUG - Verifying the vn in opserver
2025-08-18 03:37:39,286 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-91895630:ctest-vn2-85727533 virtual network link through opserver 10.0.0.27
2025-08-18 03:37:39,286 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-08-18 03:37:39,297 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-91895630:ctest-vn2-85727533', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-91895630:ctest-vn2-85727533?flat'}
2025-08-18 03:37:39,297 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-91895630:ctest-vn2-85727533 virtual network link through opserver 10.0.0.38
2025-08-18 03:37:39,297 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-18 03:37:39,308 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-91895630:ctest-vn2-85727533', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-91895630:ctest-vn2-85727533?flat'}
2025-08-18 03:37:39,308 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-91895630:ctest-vn2-85727533 virtual network link through opserver 10.0.0.19
2025-08-18 03:37:39,308 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks
2025-08-18 03:37:39,318 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-91895630:ctest-vn2-85727533', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-91895630:ctest-vn2-85727533?flat'}
2025-08-18 03:37:39,318 - INFO - Validated that VN default-domain:ctest-AnalyticsTestSanityWithResource-91895630:ctest-vn2-85727533 is found in opserver
2025-08-18 03:37:39,329 - DEBUG - VRF ids for VN ctest-vn2-85727533: {'10.0.0.241': '3', '10.0.0.41': '2'}
2025-08-18 03:37:39,329 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-18 03:37:39,335 - DEBUG - Requesting: http://10.0.0.27:8082/domain/edf943fc-0622-4564-8821-4de9bd04e8a3
2025-08-18 03:37:39,346 - DEBUG - Requesting: http://10.0.0.27:8082/project/15a95a4e-423f-42e2-9565-a347bdb71b6b
2025-08-18 03:37:39,443 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/5503ba9c-3bca-4cf9-8be1-58012e589499
2025-08-18 03:37:39,453 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/5503ba9c-3bca-4cf9-8be1-58012e589499
2025-08-18 03:37:39,463 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/ec2fc853-ee95-4597-8c28-d778c312803d
2025-08-18 03:37:39,470 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/ec2fc853-ee95-4597-8c28-d778c312803d
2025-08-18 03:37:39,476 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/b9e2e21b-087d-45b0-99fc-43343c9c3ce3
2025-08-18 03:37:39,484 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-18 03:37:39,484 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/5503ba9c-3bca-4cf9-8be1-58012e589499
2025-08-18 03:37:39,493 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/ec2fc853-ee95-4597-8c28-d778c312803d
2025-08-18 03:37:39,509 - INFO - Verified VN network id 15 for VN 5503ba9c-3bca-4cf9-8be1-58012e589499
2025-08-18 03:37:39,510 - INFO - Verifications in API Server for VN ctest-fip_vn-21835261 passed
2025-08-18 03:37:39,510 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/5503ba9c-3bca-4cf9-8be1-58012e589499
2025-08-18 03:37:39,519 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/ec2fc853-ee95-4597-8c28-d778c312803d
2025-08-18 03:37:39,526 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/ec2fc853-ee95-4597-8c28-d778c312803d
2025-08-18 03:37:39,533 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/b9e2e21b-087d-45b0-99fc-43343c9c3ce3
2025-08-18 03:37:39,547 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-91895630:ctest-fip_vn-21835261', 'interests': '1', 'advertised': '1', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-91895630', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6125945098334784761', 'uuid-lslong': '10079434203127649433'}, 'enable': 'true', 'created': '2025-08-18T03:36:55', 'last-modified': '2025-08-18T03:36:55', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:44.241482'}
2025-08-18 03:37:39,555 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-18 03:37:39,561 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-91895630:ctest-fip_vn-21835261', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-91895630', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6125945098334784761', 'uuid-lslong': '10079434203127649433'}, 'enable': 'true', 'created': '2025-08-18T03:36:55', 'last-modified': '2025-08-18T03:36:55', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:44.259865'}
2025-08-18 03:37:39,567 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-18 03:37:39,573 - DEBUG - Control-node 10.0.0.19 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-91895630:ctest-fip_vn-21835261', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-91895630', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6125945098334784761', 'uuid-lslong': '10079434203127649433'}, 'enable': 'true', 'created': '2025-08-18T03:36:55', 'last-modified': '2025-08-18T03:36:55', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:44.271687'}
2025-08-18 03:37:39,581 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-18 03:37:39,581 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-fip_vn-21835261 passed
2025-08-18 03:37:39,582 - DEBUG - ====Verifying policy data for ctest-fip_vn-21835261 in API_Server ======
2025-08-18 03:37:39,582 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-18 03:37:39,589 - DEBUG - Requesting: http://10.0.0.27:8082/domain/edf943fc-0622-4564-8821-4de9bd04e8a3
2025-08-18 03:37:39,601 - DEBUG - Requesting: http://10.0.0.27:8082/project/15a95a4e-423f-42e2-9565-a347bdb71b6b
2025-08-18 03:37:39,701 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/5503ba9c-3bca-4cf9-8be1-58012e589499
2025-08-18 03:37:39,712 - DEBUG - =>VN ctest-fip_vn-21835261 has no policy to be verified
2025-08-18 03:37:39,712 - DEBUG - Verifying the vn in opserver
2025-08-18 03:37:39,712 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-91895630:ctest-fip_vn-21835261 virtual network link through opserver 10.0.0.27
2025-08-18 03:37:39,712 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-08-18 03:37:39,724 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-91895630:ctest-fip_vn-21835261', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-91895630:ctest-fip_vn-21835261?flat'}
2025-08-18 03:37:39,724 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-91895630:ctest-fip_vn-21835261 virtual network link through opserver 10.0.0.38
2025-08-18 03:37:39,724 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-18 03:37:39,735 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-91895630:ctest-fip_vn-21835261', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-91895630:ctest-fip_vn-21835261?flat'}
2025-08-18 03:37:39,735 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-91895630:ctest-fip_vn-21835261 virtual network link through opserver 10.0.0.19
2025-08-18 03:37:39,735 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks
2025-08-18 03:37:39,746 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-91895630:ctest-fip_vn-21835261', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-91895630:ctest-fip_vn-21835261?flat'}
2025-08-18 03:37:39,746 - INFO - Validated that VN default-domain:ctest-AnalyticsTestSanityWithResource-91895630:ctest-fip_vn-21835261 is found in opserver
2025-08-18 03:37:39,757 - DEBUG - VRF ids for VN ctest-fip_vn-21835261: {'10.0.0.41': '3'}
2025-08-18 03:37:39,757 - INFO - start time= 1755488259757746
2025-08-18 03:37:39,757 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 112.10.24.3, host_string: cirros@169.254.0.3, password: gocubsgogateway: ubuntu@10.0.0.241, gateway password: c0ntrail123
2025-08-18 03:37:39,758 - DEBUG - ping -s 56 -c 3 -W 1 112.10.24.3
2025-08-18 03:37:43,577 - DEBUG - PING 112.10.24.3 (112.10.24.3): 56 data bytes
64 bytes from 112.10.24.3: seq=0 ttl=63 time=6.111 ms
64 bytes from 112.10.24.3: seq=1 ttl=63 time=1.267 ms
64 bytes from 112.10.24.3: seq=2 ttl=63 time=1.164 ms
--- 112.10.24.3 ping statistics ---
3 packets transmitted, 3 packets received, 0% packet loss
round-trip min/avg/max = 1.164/2.847/6.111 ms
2025-08-18 03:37:43,578 - INFO - Ping to IP 112.10.24.3 from VM ctest-vn1_vm1-64723882 passed
2025-08-18 03:37:53,579 - INFO - Verify session samples and teardown pkts
2025-08-18 03:37:53,579 - INFO - Verifying SessionSeriesTable through opserver 10.0.0.27
2025-08-18 03:37:53,583 - DEBUG - Posting: http://10.0.0.27:8081/analytics/query, payload {'table': 'SessionSeriesTable', 'start_time': 1755488259757746, 'end_time': 1755488273583525, 'select_fields': ['T'], 'where': [[{'name': 'vn', 'value': 'default-domain:ctest-AnalyticsTestSanityWithResource-91895630:ctest-vn1-63553540', 'op': 1, 'value2': None}, {'name': 'remote_vn', 'value': 'default-domain:ctest-AnalyticsTestSanityWithResource-91895630:ctest-vn2-85727533', 'op': 1, 'value2': None}, {'name': 'protocol', 'value': '1', 'op': 1, 'value2': None}]], 'session_type': 'client'}
2025-08-18 03:37:54,605 - DEBUG - [{'T': 1755488262283707}, {'T': 1755488263283753}, {'T': 1755488264283770}]
2025-08-18 03:37:54,605 - DEBUG - Posting: http://10.0.0.27:8081/analytics/query, payload {'table': 'SessionSeriesTable', 'start_time': 1755488259757746, 'end_time': 1755488274605568, 'select_fields': ['T'], 'where': [[{'name': 'vn', 'value': 'default-domain:ctest-AnalyticsTestSanityWithResource-91895630:ctest-vn2-85727533', 'op': 1, 'value2': None}, {'name': 'remote_vn', 'value': 'default-domain:ctest-AnalyticsTestSanityWithResource-91895630:ctest-vn1-63553540', 'op': 1, 'value2': None}, {'name': 'protocol', 'value': '1', 'op': 1, 'value2': None}]], 'session_type': 'server'}
2025-08-18 03:37:55,628 - DEBUG - [{'T': 1755488262472312}, {'T': 1755488263472402}, {'T': 1755488264472499}]
2025-08-18 03:37:55,629 - DEBUG - Posting: http://10.0.0.27:8081/analytics/query, payload {'table': 'SessionSeriesTable', 'start_time': 1755488259757746, 'end_time': 1755488275628926, 'select_fields': ['vn', 'remote_vn', 'sample_count'], 'where': [[{'name': 'vn', 'value': 'default-domain:ctest-AnalyticsTestSanityWithResource-91895630:ctest-vn1-63553540', 'op': 1, 'value2': None}, {'name': 'remote_vn', 'value': 'default-domain:ctest-AnalyticsTestSanityWithResource-91895630:ctest-vn2-85727533', 'op': 1, 'value2': None}, {'name': 'protocol', 'value': '1', 'op': 1, 'value2': None}]], 'session_type': 'client'}
2025-08-18 03:37:56,646 - DEBUG - [{'remote_vn': 'default-domain:ctest-AnalyticsTestSanityWithResource-91895630:ctest-vn2-85727533', 'vn': 'default-domain:ctest-AnalyticsTestSanityWithResource-91895630:ctest-vn1-63553540', 'sample_count': 3}]
2025-08-18 03:37:56,646 - INFO - wait for the flows to get expire
2025-08-18 03:41:16,647 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3870-1:Compute:contrail-vrouter-agent:0?flat
2025-08-18 03:41:16,695 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3870-1?flat
2025-08-18 03:41:16,732 - DEBUG - Posting: http://10.0.0.27:8081/analytics/query, payload {'table': 'SessionRecordTable', 'start_time': 1755488259757746, 'end_time': 1755488476732412, 'select_fields': ['vn', 'remote_vn', 'forward_teardown_pkts', 'reverse_teardown_pkts'], 'where': [[{'name': 'vn', 'value': 'default-domain:ctest-AnalyticsTestSanityWithResource-91895630:ctest-vn1-63553540', 'op': 1, 'value2': None}, {'name': 'remote_vn', 'value': 'default-domain:ctest-AnalyticsTestSanityWithResource-91895630:ctest-vn2-85727533', 'op': 1, 'value2': None}, {'name': 'protocol', 'value': '1', 'op': 1, 'value2': None}]], 'session_type': 'client'}
2025-08-18 03:41:17,751 - DEBUG - [{'forward_flow_uuid': 'f88874dc-1f08-4204-b8e8-486ad2604e5d', 'remote_vn': 'default-domain:ctest-AnalyticsTestSanityWithResource-91895630:ctest-vn2-85727533', 'reverse_flow_uuid': '164e09cd-30ca-4875-a8bf-fc17ed7d52d9', 'vn': 'default-domain:ctest-AnalyticsTestSanityWithResource-91895630:ctest-vn1-63553540', 'forward_teardown_pkts': 3, 'reverse_teardown_pkts': 3}]
2025-08-18 03:41:17,751 - DEBUG - Posting: http://10.0.0.27:8081/analytics/query, payload {'table': 'SessionSeriesTable', 'start_time': 1755488259757746, 'end_time': 1755488477751257, 'select_fields': ['vn', 'remote_vn', 'sample_count'], 'where': [[{'name': 'vn', 'value': 'default-domain:ctest-AnalyticsTestSanityWithResource-91895630:ctest-vn1-63553540', 'op': 1, 'value2': None}, {'name': 'remote_vn', 'value': 'default-domain:ctest-AnalyticsTestSanityWithResource-91895630:ctest-vn2-85727533', 'op': 1, 'value2': None}, {'name': 'protocol', 'value': '1', 'op': 1, 'value2': None}]], 'session_type': 'client'}
2025-08-18 03:41:18,778 - DEBUG - Posting: http://10.0.0.27:8081/analytics/query, payload {'table': 'SessionSeriesTable', 'start_time': 1755488259757746, 'end_time': 1755488478778177, 'select_fields': ['vn', 'remote_vn', 'sample_count'], 'where': [[{'name': 'vn', 'value': 'default-domain:ctest-AnalyticsTestSanityWithResource-91895630:ctest-vn2-85727533', 'op': 1, 'value2': None}, {'name': 'remote_vn', 'value': 'default-domain:ctest-AnalyticsTestSanityWithResource-91895630:ctest-vn1-63553540', 'op': 1, 'value2': None}, {'name': 'protocol', 'value': '1', 'op': 1, 'value2': None}]], 'session_type': 'server'}
2025-08-18 03:41:19,800 - DEBUG - [{'remote_vn': 'default-domain:ctest-AnalyticsTestSanityWithResource-91895630:ctest-vn1-63553540', 'vn': 'default-domain:ctest-AnalyticsTestSanityWithResource-91895630:ctest-vn2-85727533', 'sample_count': 4}]
2025-08-18 03:41:20,262 - INFO - Deleted policy policy1
2025-08-18 03:41:20,378 - INFO - Setting flow export rate: None
2025-08-18 03:41:21,628 - DEBUG - Skipping xmpp flap check
2025-08-18 03:41:21,628 - INFO - END TEST : test_verify_session_sampling_teardown : PASSED[0:03:46]
2025-08-18 03:41:21,628 - INFO - --------------------------------------------------------------------------------
2025-08-18 03:41:21,630 - INFO - Deleting VM mltf_vm
2025-08-18 03:41:21,696 - INFO - Deleting VM ctest-fvn_vm1-14926706
2025-08-18 03:41:21,762 - INFO - Deleting VM ctest-vn2_vm2-06459200
2025-08-18 03:41:21,827 - INFO - Deleting VN ctest-fip_vn-21835261
2025-08-18 03:41:21,870 - DEBUG - VN 5503ba9c-3bca-4cf9-8be1-58012e589499 still in use: Unable to complete operation on network 5503ba9c-3bca-4cf9-8be1-58012e589499. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-efeadd57-e731-43ad-8ebf-6846dd4f7d76']
2025-08-18 03:41:21,870 - WARNING - Deleting VN ctest-fip_vn-21835261 failed..Will retry
2025-08-18 03:41:24,070 - DEBUG - Response for deleting network ()
2025-08-18 03:41:24,070 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/ec2fc853-ee95-4597-8c28-d778c312803d
2025-08-18 03:41:24,097 - DEBUG - Response Code: 404
2025-08-18 03:41:24,097 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-18 03:41:24,102 - DEBUG - Requesting: http://10.0.0.27:8082/domain/edf943fc-0622-4564-8821-4de9bd04e8a3
2025-08-18 03:41:24,135 - DEBUG - Requesting: http://10.0.0.27:8082/project/15a95a4e-423f-42e2-9565-a347bdb71b6b
2025-08-18 03:41:24,231 - INFO - Validated that VN ctest-fip_vn-21835261 is not found in API Server
2025-08-18 03:41:24,242 - DEBUG - VN ctest-fip_vn-21835261 is not present in Agent 10.0.0.241
2025-08-18 03:41:24,254 - DEBUG - VN ctest-fip_vn-21835261 is not present in Agent 10.0.0.41
2025-08-18 03:41:24,255 - INFO - Validated that VN ctest-fip_vn-21835261 is not in any agent
2025-08-18 03:41:24,260 - DEBUG - VRF 3 is not seen in agent 10.0.0.41
2025-08-18 03:41:24,266 - DEBUG - Vrouter 10.0.0.41 does not have vrf 3 for VN ctest-fip_vn-21835261
2025-08-18 03:41:24,266 - INFO - Validated that all vrouters do not have the route table for VN default-domain:ctest-AnalyticsTestSanityWithResource-91895630:ctest-fip_vn-21835261
2025-08-18 03:41:24,289 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-fip_vn-21835261 info
2025-08-18 03:41:24,289 - INFO - Deleting VN ctest-vn2-85727533
2025-08-18 03:41:24,454 - DEBUG - Response for deleting network ()
2025-08-18 03:41:24,454 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/961bcbd1-cc00-4e47-b556-9561c4680531
2025-08-18 03:41:24,469 - DEBUG - Response Code: 404
2025-08-18 03:41:24,470 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-18 03:41:24,476 - DEBUG - Requesting: http://10.0.0.27:8082/domain/edf943fc-0622-4564-8821-4de9bd04e8a3
2025-08-18 03:41:24,540 - DEBUG - Requesting: http://10.0.0.27:8082/project/15a95a4e-423f-42e2-9565-a347bdb71b6b
2025-08-18 03:41:24,649 - INFO - Validated that VN ctest-vn2-85727533 is not found in API Server
2025-08-18 03:41:24,661 - DEBUG - VN ctest-vn2-85727533 is not present in Agent 10.0.0.241
2025-08-18 03:41:24,675 - DEBUG - VN ctest-vn2-85727533 is not present in Agent 10.0.0.41
2025-08-18 03:41:24,675 - INFO - Validated that VN ctest-vn2-85727533 is not in any agent
2025-08-18 03:41:24,688 - DEBUG - VRF 3 is not seen in agent 10.0.0.241
2025-08-18 03:41:24,695 - DEBUG - Vrouter 10.0.0.241 does not have vrf 3 for VN ctest-vn2-85727533
2025-08-18 03:41:24,701 - DEBUG - VRF 2 is not seen in agent 10.0.0.41
2025-08-18 03:41:24,707 - DEBUG - Vrouter 10.0.0.41 does not have vrf 2 for VN ctest-vn2-85727533
2025-08-18 03:41:24,707 - INFO - Validated that all vrouters do not have the route table for VN default-domain:ctest-AnalyticsTestSanityWithResource-91895630:ctest-vn2-85727533
2025-08-18 03:41:24,730 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn2-85727533 info
2025-08-18 03:41:24,730 - INFO - Deleting VM ctest-vn1_vm2-70256699
2025-08-18 03:41:24,798 - INFO - Deleting VM ctest-vn1_vm1-64723882
2025-08-18 03:41:24,865 - INFO - Deleting VN ctest-vn1-63553540
2025-08-18 03:41:24,914 - DEBUG - VN bfe298ea-0b9c-4b38-8cc1-73ae305f143d still in use: Unable to complete operation on network bfe298ea-0b9c-4b38-8cc1-73ae305f143d. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-760b174e-8020-4848-b8fd-b8ffca420f75']
2025-08-18 03:41:24,914 - WARNING - Deleting VN ctest-vn1-63553540 failed..Will retry
2025-08-18 03:41:27,074 - DEBUG - Response for deleting network ()
2025-08-18 03:41:27,074 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/625894ca-32ab-4e81-b515-759b4bb55ed3
2025-08-18 03:41:27,085 - DEBUG - Response Code: 404
2025-08-18 03:41:27,085 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-18 03:41:27,095 - DEBUG - Requesting: http://10.0.0.27:8082/domain/edf943fc-0622-4564-8821-4de9bd04e8a3
2025-08-18 03:41:27,113 - DEBUG - Requesting: http://10.0.0.27:8082/project/15a95a4e-423f-42e2-9565-a347bdb71b6b
2025-08-18 03:41:27,219 - INFO - Validated that VN ctest-vn1-63553540 is not found in API Server
2025-08-18 03:41:27,232 - DEBUG - VN ctest-vn1-63553540 is not present in Agent 10.0.0.241
2025-08-18 03:41:27,243 - DEBUG - VN ctest-vn1-63553540 is not present in Agent 10.0.0.41
2025-08-18 03:41:27,243 - INFO - Validated that VN ctest-vn1-63553540 is not in any agent
2025-08-18 03:41:27,249 - DEBUG - VRF 2 is not seen in agent 10.0.0.241
2025-08-18 03:41:27,254 - DEBUG - Vrouter 10.0.0.241 does not have vrf 2 for VN ctest-vn1-63553540
2025-08-18 03:41:27,254 - INFO - Validated that all vrouters do not have the route table for VN default-domain:ctest-AnalyticsTestSanityWithResource-91895630:ctest-vn1-63553540
2025-08-18 03:41:27,277 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn1-63553540 info
2025-08-18 03:41:28,038 - INFO - Deleted project: ctest-AnalyticsTestSanityWithResource-91895630, ID : 15a95a4e-423f-42e2-9565-a347bdb71b6b