2025-08-06 15:46:41,208 - INFO - Domain Default found not creating
2025-08-06 15:46:41,370 - INFO - Project ctest-TestSvcHC-94221478 not found, creating it
2025-08-06 15:46:41,886 - INFO - Created Project:ctest-TestSvcHC-94221478, ID : 5410abda-656d-4bdb-bbd1-2a4e5562512a
2025-08-06 15:46:43,301 - INFO - ================================================================================
2025-08-06 15:46:43,302 - INFO - STARTING TEST : test_svc_hc_basic
2025-08-06 15:46:44,560 - DEBUG - Nothing to compare xmpp stats {'10.0.0.49': {'10.20.0.25': '0', '10.20.0.14': '0'}, '10.0.0.57': {'10.20.0.25': '0', '10.20.0.14': '0'}} with
2025-08-06 15:46:44,560 - INFO - Initial checks done. Running the testcase now
2025-08-06 15:46:44,560 - INFO -
2025-08-06 15:46:45,283 - DEBUG - Response for create_network : {'network': {'id': 'd0d7dd7f-9374-4408-826e-cdf1bd956919', 'name': 'ctest-mgmt_vn-46638368', 'tenant_id': '5410abda656d4bdbbbd12a4e5562512a', 'project_id': '5410abda656d4bdbbbd12a4e5562512a', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcHC-94221478', 'ctest-mgmt_vn-46638368'], 'port_security_enabled': True, 'description': ''}}
2025-08-06 15:46:45,582 - DEBUG - Response for create_subnet : {'subnet': {'id': '9bdab668-5d3c-4053-8729-01b8f7168169', 'name': '', 'tenant_id': '5410abda656d4bdbbbd12a4e5562512a', 'network_id': 'd0d7dd7f-9374-4408-826e-cdf1bd956919', 'ip_version': 4, 'cidr': '70.65.3.128/26', 'allocation_pools': [{'start': '70.65.3.130', 'end': '70.65.3.190'}], 'gateway_ip': '70.65.3.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '70.65.3.130', 'tags': [], 'project_id': '5410abda656d4bdbbbd12a4e5562512a'}}
2025-08-06 15:46:45,614 - INFO - Created VN ctest-mgmt_vn-46638368
2025-08-06 15:46:45,628 - DEBUG - VN ctest-mgmt_vn-46638368 UUID is d0d7dd7f-9374-4408-826e-cdf1bd956919
2025-08-06 15:46:45,711 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-06 15:46:46,020 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-06 15:46:46,048 - DEBUG - Requesting: http://10.0.0.254:8082/domain/024378da-7408-4369-ac73-51fa8d4d8d77
2025-08-06 15:46:46,081 - DEBUG - Requesting: http://10.0.0.254:8082/project/5410abda-656d-4bdb-bbd1-2a4e5562512a
2025-08-06 15:46:46,183 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/d0d7dd7f-9374-4408-826e-cdf1bd956919
2025-08-06 15:46:46,202 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/d0d7dd7f-9374-4408-826e-cdf1bd956919
2025-08-06 15:46:46,217 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/0b2aef4b-2fd6-44f4-94d9-7cf6f34527e2
2025-08-06 15:46:46,227 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/0b2aef4b-2fd6-44f4-94d9-7cf6f34527e2
2025-08-06 15:46:46,238 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/f05e488e-8fcd-4a33-a5b1-c7673cc2fd66
2025-08-06 15:46:46,247 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-06 15:46:46,247 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/d0d7dd7f-9374-4408-826e-cdf1bd956919
2025-08-06 15:46:46,259 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/0b2aef4b-2fd6-44f4-94d9-7cf6f34527e2
2025-08-06 15:46:46,277 - INFO - Verified VN network id 9 for VN d0d7dd7f-9374-4408-826e-cdf1bd956919
2025-08-06 15:46:46,278 - INFO - Verifications in API Server for VN ctest-mgmt_vn-46638368 passed
2025-08-06 15:46:46,278 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/d0d7dd7f-9374-4408-826e-cdf1bd956919
2025-08-06 15:46:46,291 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/0b2aef4b-2fd6-44f4-94d9-7cf6f34527e2
2025-08-06 15:46:46,299 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/0b2aef4b-2fd6-44f4-94d9-7cf6f34527e2
2025-08-06 15:46:46,308 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/f05e488e-8fcd-4a33-a5b1-c7673cc2fd66
2025-08-06 15:46:46,325 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcHC-94221478:ctest-mgmt_vn-46638368', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcHC-94221478', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15048740219886257160', 'uuid-lslong': '9398675910520301849'}, 'enable': 'true', 'created': '2025-08-06T15:46:45', 'last-modified': '2025-08-06T15:46:45', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.819727'}
2025-08-06 15:46:46,331 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-06 15:46:46,337 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcHC-94221478:ctest-mgmt_vn-46638368', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcHC-94221478', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15048740219886257160', 'uuid-lslong': '9398675910520301849'}, 'enable': 'true', 'created': '2025-08-06T15:46:45', 'last-modified': '2025-08-06T15:46:45', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.830215'}
2025-08-06 15:46:46,344 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-06 15:46:46,350 - DEBUG - Control-node 10.0.0.48 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcHC-94221478:ctest-mgmt_vn-46638368', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcHC-94221478', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15048740219886257160', 'uuid-lslong': '9398675910520301849'}, 'enable': 'true', 'created': '2025-08-06T15:46:45', 'last-modified': '2025-08-06T15:46:45', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.844774'}
2025-08-06 15:46:46,357 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-06 15:46:46,358 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-46638368 passed
2025-08-06 15:46:46,358 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-46638368 in API_Server ======
2025-08-06 15:46:46,358 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-06 15:46:46,370 - DEBUG - Requesting: http://10.0.0.254:8082/domain/024378da-7408-4369-ac73-51fa8d4d8d77
2025-08-06 15:46:46,393 - DEBUG - Requesting: http://10.0.0.254:8082/project/5410abda-656d-4bdb-bbd1-2a4e5562512a
2025-08-06 15:46:46,493 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/d0d7dd7f-9374-4408-826e-cdf1bd956919
2025-08-06 15:46:46,507 - DEBUG - =>VN ctest-mgmt_vn-46638368 has no policy to be verified
2025-08-06 15:46:46,507 - DEBUG - Verifying the vn in opserver
2025-08-06 15:46:46,508 - DEBUG - Verifying the default-domain:ctest-TestSvcHC-94221478:ctest-mgmt_vn-46638368 virtual network link through opserver 10.0.0.254
2025-08-06 15:46:46,508 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-08-06 15:46:46,811 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-08-06 15:46:46,870 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcHC-94221478:ctest-mgmt_vn-46638368', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcHC-94221478:ctest-mgmt_vn-46638368?flat'}
2025-08-06 15:46:46,870 - DEBUG - Verifying the default-domain:ctest-TestSvcHC-94221478:ctest-mgmt_vn-46638368 virtual network link through opserver 10.0.0.38
2025-08-06 15:46:46,870 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-06 15:46:47,166 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-06 15:46:47,222 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcHC-94221478:ctest-mgmt_vn-46638368', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcHC-94221478:ctest-mgmt_vn-46638368?flat'}
2025-08-06 15:46:47,222 - DEBUG - Verifying the default-domain:ctest-TestSvcHC-94221478:ctest-mgmt_vn-46638368 virtual network link through opserver 10.0.0.48
2025-08-06 15:46:47,222 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/virtual-networks
2025-08-06 15:46:47,522 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/virtual-networks
2025-08-06 15:46:47,589 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcHC-94221478:ctest-mgmt_vn-46638368', 'href': 'http://10.0.0.48:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcHC-94221478:ctest-mgmt_vn-46638368?flat'}
2025-08-06 15:46:47,589 - INFO - Validated that VN default-domain:ctest-TestSvcHC-94221478:ctest-mgmt_vn-46638368 is found in opserver
2025-08-06 15:46:47,619 - DEBUG - Do not have enough data to verify VN in agent
2025-08-06 15:46:47,632 - DEBUG - VRF ids for VN ctest-mgmt_vn-46638368: {}
2025-08-06 15:46:47,924 - DEBUG - Response for create_network : {'network': {'id': 'd8f1eb33-8498-450c-899a-353a9953eea1', 'name': 'ctest-left_vn-48004533', 'tenant_id': '5410abda656d4bdbbbd12a4e5562512a', 'project_id': '5410abda656d4bdbbbd12a4e5562512a', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcHC-94221478', 'ctest-left_vn-48004533'], 'port_security_enabled': True, 'description': ''}}
2025-08-06 15:46:48,220 - DEBUG - Response for create_subnet : {'subnet': {'id': '65eb4a09-373f-4871-8ef4-1e46f19c61ca', 'name': '', 'tenant_id': '5410abda656d4bdbbbd12a4e5562512a', 'network_id': 'd8f1eb33-8498-450c-899a-353a9953eea1', 'ip_version': 4, 'cidr': '74.95.0.0/26', 'allocation_pools': [{'start': '74.95.0.2', 'end': '74.95.0.62'}], 'gateway_ip': '74.95.0.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '74.95.0.2', 'tags': [], 'project_id': '5410abda656d4bdbbbd12a4e5562512a'}}
2025-08-06 15:46:48,249 - INFO - Created VN ctest-left_vn-48004533
2025-08-06 15:46:48,263 - DEBUG - VN ctest-left_vn-48004533 UUID is d8f1eb33-8498-450c-899a-353a9953eea1
2025-08-06 15:46:48,349 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-06 15:46:48,358 - DEBUG - Requesting: http://10.0.0.254:8082/domain/024378da-7408-4369-ac73-51fa8d4d8d77
2025-08-06 15:46:48,371 - DEBUG - Requesting: http://10.0.0.254:8082/project/5410abda-656d-4bdb-bbd1-2a4e5562512a
2025-08-06 15:46:48,466 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/d8f1eb33-8498-450c-899a-353a9953eea1
2025-08-06 15:46:48,485 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/d8f1eb33-8498-450c-899a-353a9953eea1
2025-08-06 15:46:48,497 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/a7ee476d-09da-4984-83fd-3c80e9ab488e
2025-08-06 15:46:48,509 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/a7ee476d-09da-4984-83fd-3c80e9ab488e
2025-08-06 15:46:48,519 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/f0238e13-7cf4-4004-a16f-654595eda886
2025-08-06 15:46:48,528 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-06 15:46:48,528 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/d8f1eb33-8498-450c-899a-353a9953eea1
2025-08-06 15:46:48,543 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/a7ee476d-09da-4984-83fd-3c80e9ab488e
2025-08-06 15:46:48,570 - INFO - Verified VN network id 10 for VN d8f1eb33-8498-450c-899a-353a9953eea1
2025-08-06 15:46:48,570 - INFO - Verifications in API Server for VN ctest-left_vn-48004533 passed
2025-08-06 15:46:48,570 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/d8f1eb33-8498-450c-899a-353a9953eea1
2025-08-06 15:46:48,582 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/a7ee476d-09da-4984-83fd-3c80e9ab488e
2025-08-06 15:46:48,591 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/a7ee476d-09da-4984-83fd-3c80e9ab488e
2025-08-06 15:46:48,601 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/f0238e13-7cf4-4004-a16f-654595eda886
2025-08-06 15:46:48,624 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcHC-94221478:ctest-left_vn-48004533', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcHC-94221478', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15632534388080133388', 'uuid-lslong': '9915296055406358177'}, 'enable': 'true', 'created': '2025-08-06T15:46:47', 'last-modified': '2025-08-06T15:46:48', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.523982'}
2025-08-06 15:46:48,636 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-06 15:46:48,643 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcHC-94221478:ctest-left_vn-48004533', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcHC-94221478', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15632534388080133388', 'uuid-lslong': '9915296055406358177'}, 'enable': 'true', 'created': '2025-08-06T15:46:47', 'last-modified': '2025-08-06T15:46:48', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.549169'}
2025-08-06 15:46:48,651 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-06 15:46:48,657 - DEBUG - Control-node 10.0.0.48 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcHC-94221478:ctest-left_vn-48004533', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcHC-94221478', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15632534388080133388', 'uuid-lslong': '9915296055406358177'}, 'enable': 'true', 'created': '2025-08-06T15:46:47', 'last-modified': '2025-08-06T15:46:48', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.561028'}
2025-08-06 15:46:48,663 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-06 15:46:48,664 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-48004533 passed
2025-08-06 15:46:48,664 - DEBUG - ====Verifying policy data for ctest-left_vn-48004533 in API_Server ======
2025-08-06 15:46:48,664 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-06 15:46:48,671 - DEBUG - Requesting: http://10.0.0.254:8082/domain/024378da-7408-4369-ac73-51fa8d4d8d77
2025-08-06 15:46:48,684 - DEBUG - Requesting: http://10.0.0.254:8082/project/5410abda-656d-4bdb-bbd1-2a4e5562512a
2025-08-06 15:46:48,792 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/d8f1eb33-8498-450c-899a-353a9953eea1
2025-08-06 15:46:48,807 - DEBUG - =>VN ctest-left_vn-48004533 has no policy to be verified
2025-08-06 15:46:48,807 - DEBUG - Verifying the vn in opserver
2025-08-06 15:46:48,807 - DEBUG - Verifying the default-domain:ctest-TestSvcHC-94221478:ctest-left_vn-48004533 virtual network link through opserver 10.0.0.254
2025-08-06 15:46:48,807 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-08-06 15:46:48,877 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcHC-94221478:ctest-left_vn-48004533', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcHC-94221478:ctest-left_vn-48004533?flat'}
2025-08-06 15:46:48,877 - DEBUG - Verifying the default-domain:ctest-TestSvcHC-94221478:ctest-left_vn-48004533 virtual network link through opserver 10.0.0.38
2025-08-06 15:46:48,877 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-06 15:46:48,933 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcHC-94221478:ctest-left_vn-48004533', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcHC-94221478:ctest-left_vn-48004533?flat'}
2025-08-06 15:46:48,933 - DEBUG - Verifying the default-domain:ctest-TestSvcHC-94221478:ctest-left_vn-48004533 virtual network link through opserver 10.0.0.48
2025-08-06 15:46:48,933 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/virtual-networks
2025-08-06 15:46:48,995 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcHC-94221478:ctest-left_vn-48004533', 'href': 'http://10.0.0.48:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcHC-94221478:ctest-left_vn-48004533?flat'}
2025-08-06 15:46:48,995 - INFO - Validated that VN default-domain:ctest-TestSvcHC-94221478:ctest-left_vn-48004533 is found in opserver
2025-08-06 15:46:49,021 - DEBUG - Do not have enough data to verify VN in agent
2025-08-06 15:46:49,034 - DEBUG - VRF ids for VN ctest-left_vn-48004533: {}
2025-08-06 15:46:49,325 - DEBUG - Response for create_network : {'network': {'id': 'ff98d91a-4a04-4ceb-8998-6e9fe2338a8c', 'name': 'ctest-right_vn-68852583', 'tenant_id': '5410abda656d4bdbbbd12a4e5562512a', 'project_id': '5410abda656d4bdbbbd12a4e5562512a', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcHC-94221478', 'ctest-right_vn-68852583'], 'port_security_enabled': True, 'description': ''}}
2025-08-06 15:46:49,573 - DEBUG - Response for create_subnet : {'subnet': {'id': '9804a345-1192-4f6b-b237-8097466505bb', 'name': '', 'tenant_id': '5410abda656d4bdbbbd12a4e5562512a', 'network_id': 'ff98d91a-4a04-4ceb-8998-6e9fe2338a8c', 'ip_version': 4, 'cidr': '183.216.136.64/26', 'allocation_pools': [{'start': '183.216.136.66', 'end': '183.216.136.126'}], 'gateway_ip': '183.216.136.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '183.216.136.66', 'tags': [], 'project_id': '5410abda656d4bdbbbd12a4e5562512a'}}
2025-08-06 15:46:49,597 - INFO - Created VN ctest-right_vn-68852583
2025-08-06 15:46:49,613 - DEBUG - VN ctest-right_vn-68852583 UUID is ff98d91a-4a04-4ceb-8998-6e9fe2338a8c
2025-08-06 15:46:49,696 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-06 15:46:49,703 - DEBUG - Requesting: http://10.0.0.254:8082/domain/024378da-7408-4369-ac73-51fa8d4d8d77
2025-08-06 15:46:49,716 - DEBUG - Requesting: http://10.0.0.254:8082/project/5410abda-656d-4bdb-bbd1-2a4e5562512a
2025-08-06 15:46:49,808 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/ff98d91a-4a04-4ceb-8998-6e9fe2338a8c
2025-08-06 15:46:49,831 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/ff98d91a-4a04-4ceb-8998-6e9fe2338a8c
2025-08-06 15:46:49,843 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/ea72a112-c100-45e4-bb06-bc56e3166a4f
2025-08-06 15:46:49,852 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/ea72a112-c100-45e4-bb06-bc56e3166a4f
2025-08-06 15:46:49,861 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/ea9421aa-71c6-48cd-9867-be99e7307e1e
2025-08-06 15:46:49,869 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-06 15:46:49,869 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/ff98d91a-4a04-4ceb-8998-6e9fe2338a8c
2025-08-06 15:46:49,887 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/ea72a112-c100-45e4-bb06-bc56e3166a4f
2025-08-06 15:46:49,908 - INFO - Verified VN network id 11 for VN ff98d91a-4a04-4ceb-8998-6e9fe2338a8c
2025-08-06 15:46:49,908 - INFO - Verifications in API Server for VN ctest-right_vn-68852583 passed
2025-08-06 15:46:49,908 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/ff98d91a-4a04-4ceb-8998-6e9fe2338a8c
2025-08-06 15:46:49,921 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/ea72a112-c100-45e4-bb06-bc56e3166a4f
2025-08-06 15:46:49,931 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/ea72a112-c100-45e4-bb06-bc56e3166a4f
2025-08-06 15:46:49,939 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/ea9421aa-71c6-48cd-9867-be99e7307e1e
2025-08-06 15:46:49,952 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcHC-94221478:ctest-right_vn-68852583', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcHC-94221478', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '18417709383065816299', 'uuid-lslong': '9914796212630030988'}, 'enable': 'true', 'created': '2025-08-06T15:46:49', 'last-modified': '2025-08-06T15:46:49', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.445718'}
2025-08-06 15:46:49,959 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-06 15:46:49,965 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcHC-94221478:ctest-right_vn-68852583', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcHC-94221478', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '18417709383065816299', 'uuid-lslong': '9914796212630030988'}, 'enable': 'true', 'created': '2025-08-06T15:46:49', 'last-modified': '2025-08-06T15:46:49', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.467628'}
2025-08-06 15:46:49,973 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-06 15:46:49,979 - DEBUG - Control-node 10.0.0.48 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcHC-94221478:ctest-right_vn-68852583', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcHC-94221478', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '18417709383065816299', 'uuid-lslong': '9914796212630030988'}, 'enable': 'true', 'created': '2025-08-06T15:46:49', 'last-modified': '2025-08-06T15:46:49', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.477400'}
2025-08-06 15:46:49,985 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-06 15:46:49,985 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-68852583 passed
2025-08-06 15:46:49,985 - DEBUG - ====Verifying policy data for ctest-right_vn-68852583 in API_Server ======
2025-08-06 15:46:49,986 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-06 15:46:49,992 - DEBUG - Requesting: http://10.0.0.254:8082/domain/024378da-7408-4369-ac73-51fa8d4d8d77
2025-08-06 15:46:50,006 - DEBUG - Requesting: http://10.0.0.254:8082/project/5410abda-656d-4bdb-bbd1-2a4e5562512a
2025-08-06 15:46:50,097 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/ff98d91a-4a04-4ceb-8998-6e9fe2338a8c
2025-08-06 15:46:50,109 - DEBUG - =>VN ctest-right_vn-68852583 has no policy to be verified
2025-08-06 15:46:50,109 - DEBUG - Verifying the vn in opserver
2025-08-06 15:46:50,109 - DEBUG - Verifying the default-domain:ctest-TestSvcHC-94221478:ctest-right_vn-68852583 virtual network link through opserver 10.0.0.254
2025-08-06 15:46:50,109 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-08-06 15:46:50,166 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcHC-94221478:ctest-right_vn-68852583', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcHC-94221478:ctest-right_vn-68852583?flat'}
2025-08-06 15:46:50,166 - DEBUG - Verifying the default-domain:ctest-TestSvcHC-94221478:ctest-right_vn-68852583 virtual network link through opserver 10.0.0.38
2025-08-06 15:46:50,166 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-06 15:46:50,218 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcHC-94221478:ctest-right_vn-68852583', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcHC-94221478:ctest-right_vn-68852583?flat'}
2025-08-06 15:46:50,218 - DEBUG - Verifying the default-domain:ctest-TestSvcHC-94221478:ctest-right_vn-68852583 virtual network link through opserver 10.0.0.48
2025-08-06 15:46:50,218 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/virtual-networks
2025-08-06 15:46:50,270 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcHC-94221478:ctest-right_vn-68852583', 'href': 'http://10.0.0.48:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcHC-94221478:ctest-right_vn-68852583?flat'}
2025-08-06 15:46:50,270 - INFO - Validated that VN default-domain:ctest-TestSvcHC-94221478:ctest-right_vn-68852583 is found in opserver
2025-08-06 15:46:50,299 - DEBUG - Do not have enough data to verify VN in agent
2025-08-06 15:46:50,311 - DEBUG - VRF ids for VN ctest-right_vn-68852583: {}
2025-08-06 15:46:50,484 - DEBUG - Services list from nova: [, , , ]
2025-08-06 15:46:51,503 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3763-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3763-1)
2025-08-06 15:46:52,834 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3763-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3763-2)
2025-08-06 15:46:52,835 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-00424749']
2025-08-06 15:46:53,130 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-00424749'] created succesfully
2025-08-06 15:46:53,546 - DEBUG - Image by name tiny_nat_fw not found
2025-08-06 15:46:53,546 - DEBUG - Installing image tiny_nat_fw
2025-08-06 15:46:53,546 - DEBUG - Image info {'name': 'tinycore-in-network-nat.qcow2.gz', 'webserver': '', 'location': '/images/tinycore/', 'username': 'tc', 'password': 'secret', 'type': 'os', 'flavor': 'contrail_flavor_tiny', 'params': '--container-format bare --disk-format vmdk --property vmware_disktype="sparse" --property vmware_adaptertype="ide" --property hypervisor_type=qemu', 'name_docker': 'phusion-baseimage-enablesshd', 'vmdk': 'service_chain_images/nat_service/tiny_nat_fw/tinycore-in-network-nat-flat.vmdk', 'shrinked_vmdk': "'no'"}
2025-08-06 15:46:53,547 - DEBUG - Image build path file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-08-06 15:46:53,547 - DEBUG - Download image from local file file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-08-06 15:46:53,547 - DEBUG - Unzip image
2025-08-06 15:46:53,547 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-08-06 15:46:53,567 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-08-06 15:46:53,568 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-00424749']
2025-08-06 15:46:53,675 - INFO - Service template: ['default-domain', 'ctest-service_template_1-00424749'] deleted successfully.
2025-08-06 15:46:53,675 - INFO - Skip interface_detach for VM ctest-right_vm-79669337 in state BUILD
2025-08-06 15:46:53,675 - INFO - Deleting VM ctest-right_vm-79669337
2025-08-06 15:46:53,735 - ERROR - VM ctest-right_vm-79669337 has failed to come up
2025-08-06 15:46:53,735 - ERROR - Nova failed to get host of the VM
2025-08-06 15:46:53,735 - ERROR - VM ctest-right_vm-79669337 has failed to come up
2025-08-06 15:46:53,735 - ERROR - Nova failed to get host of the VM
2025-08-06 15:46:53,824 - INFO - Skip interface_detach for VM ctest-left_vm-90410609 in state BUILD
2025-08-06 15:46:53,824 - INFO - Deleting VM ctest-left_vm-90410609
2025-08-06 15:46:53,824 - ERROR - VM ctest-left_vm-90410609 has failed to come up
2025-08-06 15:46:53,824 - ERROR - Nova failed to get host of the VM
2025-08-06 15:46:53,824 - ERROR - VM ctest-left_vm-90410609 has failed to come up
2025-08-06 15:46:53,824 - ERROR - Nova failed to get host of the VM
2025-08-06 15:46:53,901 - INFO - Deleting VN ctest-right_vn-68852583
2025-08-06 15:46:53,943 - DEBUG - VN ff98d91a-4a04-4ceb-8998-6e9fe2338a8c still in use: Unable to complete operation on network ff98d91a-4a04-4ceb-8998-6e9fe2338a8c. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-f7ddb522-07c7-4d1b-9ab5-43d63e26aa09']
2025-08-06 15:46:53,943 - WARNING - Deleting VN ctest-right_vn-68852583 failed..Will retry
2025-08-06 15:46:56,124 - DEBUG - Response for deleting network ()
2025-08-06 15:46:56,124 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/ea72a112-c100-45e4-bb06-bc56e3166a4f
2025-08-06 15:46:56,132 - DEBUG - Response Code: 404
2025-08-06 15:46:56,133 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-06 15:46:56,142 - DEBUG - Requesting: http://10.0.0.254:8082/domain/024378da-7408-4369-ac73-51fa8d4d8d77
2025-08-06 15:46:56,180 - DEBUG - Requesting: http://10.0.0.254:8082/project/5410abda-656d-4bdb-bbd1-2a4e5562512a
2025-08-06 15:46:56,280 - INFO - Validated that VN ctest-right_vn-68852583 is not found in API Server
2025-08-06 15:46:56,294 - DEBUG - VN ctest-right_vn-68852583 is not present in Agent 10.0.0.49
2025-08-06 15:46:56,306 - DEBUG - VN ctest-right_vn-68852583 is not present in Agent 10.0.0.57
2025-08-06 15:46:56,306 - INFO - Validated that VN ctest-right_vn-68852583 is not in any agent
2025-08-06 15:46:56,343 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-68852583 info
2025-08-06 15:46:56,343 - INFO - Deleting VN ctest-left_vn-48004533
2025-08-06 15:46:56,541 - DEBUG - Response for deleting network ()
2025-08-06 15:46:56,541 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/a7ee476d-09da-4984-83fd-3c80e9ab488e
2025-08-06 15:46:56,555 - DEBUG - Response Code: 404
2025-08-06 15:46:56,555 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-06 15:46:56,566 - DEBUG - Requesting: http://10.0.0.254:8082/domain/024378da-7408-4369-ac73-51fa8d4d8d77
2025-08-06 15:46:56,579 - DEBUG - Requesting: http://10.0.0.254:8082/project/5410abda-656d-4bdb-bbd1-2a4e5562512a
2025-08-06 15:46:56,664 - INFO - Validated that VN ctest-left_vn-48004533 is not found in API Server
2025-08-06 15:46:56,684 - DEBUG - VN ctest-left_vn-48004533 is not present in Agent 10.0.0.49
2025-08-06 15:46:56,701 - DEBUG - VN ctest-left_vn-48004533 is not present in Agent 10.0.0.57
2025-08-06 15:46:56,701 - INFO - Validated that VN ctest-left_vn-48004533 is not in any agent
2025-08-06 15:46:56,736 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-48004533 info
2025-08-06 15:46:56,736 - INFO - Deleting VN ctest-mgmt_vn-46638368
2025-08-06 15:46:56,872 - DEBUG - Response for deleting network ()
2025-08-06 15:46:56,872 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/0b2aef4b-2fd6-44f4-94d9-7cf6f34527e2
2025-08-06 15:46:56,880 - DEBUG - Response Code: 404
2025-08-06 15:46:56,880 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-06 15:46:56,894 - DEBUG - Requesting: http://10.0.0.254:8082/domain/024378da-7408-4369-ac73-51fa8d4d8d77
2025-08-06 15:46:56,909 - DEBUG - Requesting: http://10.0.0.254:8082/project/5410abda-656d-4bdb-bbd1-2a4e5562512a
2025-08-06 15:46:57,006 - INFO - Validated that VN ctest-mgmt_vn-46638368 is not found in API Server
2025-08-06 15:46:57,023 - DEBUG - VN ctest-mgmt_vn-46638368 is not present in Agent 10.0.0.49
2025-08-06 15:46:57,038 - DEBUG - VN ctest-mgmt_vn-46638368 is not present in Agent 10.0.0.57
2025-08-06 15:46:57,038 - INFO - Validated that VN ctest-mgmt_vn-46638368 is not in any agent
2025-08-06 15:46:57,075 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-46638368 info
2025-08-06 15:46:58,357 - DEBUG - No XMPP flaps were noticed during the test
2025-08-06 15:46:58,357 - INFO -
2025-08-06 15:46:58,357 - INFO - END TEST : test_svc_hc_basic : SKIPPED[0:00:15]
2025-08-06 15:46:58,357 - INFO - --------------------------------------------------------------------------------
2025-08-06 15:46:58,364 - INFO - ================================================================================
2025-08-06 15:46:58,364 - INFO - STARTING TEST : test_svc_trans_segment_right_hc_fail_svm
2025-08-06 15:46:59,648 - DEBUG - Nothing to compare xmpp stats {'10.0.0.49': {'10.20.0.25': '0', '10.20.0.14': '0'}, '10.0.0.57': {'10.20.0.25': '0', '10.20.0.14': '0'}} with
2025-08-06 15:46:59,648 - INFO - Initial checks done. Running the testcase now
2025-08-06 15:46:59,648 - INFO -
2025-08-06 15:46:59,927 - DEBUG - Response for create_network : {'network': {'id': '68784f96-b340-4a53-9e07-eaf862c38d77', 'name': 'ctest-mgmt_vn-47198297', 'tenant_id': '5410abda656d4bdbbbd12a4e5562512a', 'project_id': '5410abda656d4bdbbbd12a4e5562512a', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcHC-94221478', 'ctest-mgmt_vn-47198297'], 'port_security_enabled': True, 'description': ''}}
2025-08-06 15:47:00,139 - DEBUG - Response for create_subnet : {'subnet': {'id': '46b58c53-bd15-436f-a2b4-510e913d6e09', 'name': '', 'tenant_id': '5410abda656d4bdbbbd12a4e5562512a', 'network_id': '68784f96-b340-4a53-9e07-eaf862c38d77', 'ip_version': 4, 'cidr': '168.49.214.128/26', 'allocation_pools': [{'start': '168.49.214.130', 'end': '168.49.214.190'}], 'gateway_ip': '168.49.214.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '168.49.214.130', 'tags': [], 'project_id': '5410abda656d4bdbbbd12a4e5562512a'}}
2025-08-06 15:47:00,167 - INFO - Created VN ctest-mgmt_vn-47198297
2025-08-06 15:47:00,178 - DEBUG - VN ctest-mgmt_vn-47198297 UUID is 68784f96-b340-4a53-9e07-eaf862c38d77
2025-08-06 15:47:00,263 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-06 15:47:00,273 - DEBUG - Requesting: http://10.0.0.254:8082/domain/024378da-7408-4369-ac73-51fa8d4d8d77
2025-08-06 15:47:00,291 - DEBUG - Requesting: http://10.0.0.254:8082/project/5410abda-656d-4bdb-bbd1-2a4e5562512a
2025-08-06 15:47:00,388 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/68784f96-b340-4a53-9e07-eaf862c38d77
2025-08-06 15:47:00,401 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/68784f96-b340-4a53-9e07-eaf862c38d77
2025-08-06 15:47:00,411 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/14e71bf9-5b58-4dae-aa1e-ce4dbe7f6556
2025-08-06 15:47:00,421 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/14e71bf9-5b58-4dae-aa1e-ce4dbe7f6556
2025-08-06 15:47:00,431 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/e539ee3d-27ce-4895-86e7-ee8d4d36fb69
2025-08-06 15:47:00,441 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-06 15:47:00,441 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/68784f96-b340-4a53-9e07-eaf862c38d77
2025-08-06 15:47:00,452 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/14e71bf9-5b58-4dae-aa1e-ce4dbe7f6556
2025-08-06 15:47:00,471 - INFO - Verified VN network id 9 for VN 68784f96-b340-4a53-9e07-eaf862c38d77
2025-08-06 15:47:00,472 - INFO - Verifications in API Server for VN ctest-mgmt_vn-47198297 passed
2025-08-06 15:47:00,472 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/68784f96-b340-4a53-9e07-eaf862c38d77
2025-08-06 15:47:00,487 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/14e71bf9-5b58-4dae-aa1e-ce4dbe7f6556
2025-08-06 15:47:00,497 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/14e71bf9-5b58-4dae-aa1e-ce4dbe7f6556
2025-08-06 15:47:00,505 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/e539ee3d-27ce-4895-86e7-ee8d4d36fb69
2025-08-06 15:47:00,518 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcHC-94221478:ctest-mgmt_vn-47198297', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcHC-94221478', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7527854285820807763', 'uuid-lslong': '11387328535359360375'}, 'enable': 'true', 'created': '2025-08-06T15:46:59', 'last-modified': '2025-08-06T15:47:00', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.439682'}
2025-08-06 15:47:00,525 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-06 15:47:00,531 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcHC-94221478:ctest-mgmt_vn-47198297', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcHC-94221478', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7527854285820807763', 'uuid-lslong': '11387328535359360375'}, 'enable': 'true', 'created': '2025-08-06T15:46:59', 'last-modified': '2025-08-06T15:47:00', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.442066'}
2025-08-06 15:47:00,537 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-06 15:47:00,543 - DEBUG - Control-node 10.0.0.48 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcHC-94221478:ctest-mgmt_vn-47198297', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcHC-94221478', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7527854285820807763', 'uuid-lslong': '11387328535359360375'}, 'enable': 'true', 'created': '2025-08-06T15:46:59', 'last-modified': '2025-08-06T15:47:00', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.463487'}
2025-08-06 15:47:00,550 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-06 15:47:00,550 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-47198297 passed
2025-08-06 15:47:00,550 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-47198297 in API_Server ======
2025-08-06 15:47:00,550 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-06 15:47:00,556 - DEBUG - Requesting: http://10.0.0.254:8082/domain/024378da-7408-4369-ac73-51fa8d4d8d77
2025-08-06 15:47:00,570 - DEBUG - Requesting: http://10.0.0.254:8082/project/5410abda-656d-4bdb-bbd1-2a4e5562512a
2025-08-06 15:47:00,664 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/68784f96-b340-4a53-9e07-eaf862c38d77
2025-08-06 15:47:00,679 - DEBUG - =>VN ctest-mgmt_vn-47198297 has no policy to be verified
2025-08-06 15:47:00,679 - DEBUG - Verifying the vn in opserver
2025-08-06 15:47:00,679 - DEBUG - Verifying the default-domain:ctest-TestSvcHC-94221478:ctest-mgmt_vn-47198297 virtual network link through opserver 10.0.0.254
2025-08-06 15:47:00,679 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-08-06 15:47:00,699 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcHC-94221478:ctest-mgmt_vn-47198297', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcHC-94221478:ctest-mgmt_vn-47198297?flat'}
2025-08-06 15:47:00,699 - DEBUG - Verifying the default-domain:ctest-TestSvcHC-94221478:ctest-mgmt_vn-47198297 virtual network link through opserver 10.0.0.38
2025-08-06 15:47:00,699 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-06 15:47:00,712 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcHC-94221478:ctest-mgmt_vn-47198297', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcHC-94221478:ctest-mgmt_vn-47198297?flat'}
2025-08-06 15:47:00,713 - DEBUG - Verifying the default-domain:ctest-TestSvcHC-94221478:ctest-mgmt_vn-47198297 virtual network link through opserver 10.0.0.48
2025-08-06 15:47:00,713 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/virtual-networks
2025-08-06 15:47:00,726 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcHC-94221478:ctest-mgmt_vn-47198297', 'href': 'http://10.0.0.48:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcHC-94221478:ctest-mgmt_vn-47198297?flat'}
2025-08-06 15:47:00,726 - INFO - Validated that VN default-domain:ctest-TestSvcHC-94221478:ctest-mgmt_vn-47198297 is found in opserver
2025-08-06 15:47:00,759 - DEBUG - Do not have enough data to verify VN in agent
2025-08-06 15:47:00,776 - DEBUG - VRF ids for VN ctest-mgmt_vn-47198297: {}
2025-08-06 15:47:01,047 - DEBUG - Response for create_network : {'network': {'id': 'bf9cd28a-98f6-4a34-9b65-527a2a5b2845', 'name': 'ctest-left_vn-77068927', 'tenant_id': '5410abda656d4bdbbbd12a4e5562512a', 'project_id': '5410abda656d4bdbbbd12a4e5562512a', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcHC-94221478', 'ctest-left_vn-77068927'], 'port_security_enabled': True, 'description': ''}}
2025-08-06 15:47:01,261 - DEBUG - Response for create_subnet : {'subnet': {'id': '6b3f6da0-5ab2-4bad-8c0c-ec4768257cda', 'name': '', 'tenant_id': '5410abda656d4bdbbbd12a4e5562512a', 'network_id': 'bf9cd28a-98f6-4a34-9b65-527a2a5b2845', 'ip_version': 4, 'cidr': '43.199.197.0/26', 'allocation_pools': [{'start': '43.199.197.2', 'end': '43.199.197.62'}], 'gateway_ip': '43.199.197.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '43.199.197.2', 'tags': [], 'project_id': '5410abda656d4bdbbbd12a4e5562512a'}}
2025-08-06 15:47:01,284 - INFO - Created VN ctest-left_vn-77068927
2025-08-06 15:47:01,295 - DEBUG - VN ctest-left_vn-77068927 UUID is bf9cd28a-98f6-4a34-9b65-527a2a5b2845
2025-08-06 15:47:01,368 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-06 15:47:01,374 - DEBUG - Requesting: http://10.0.0.254:8082/domain/024378da-7408-4369-ac73-51fa8d4d8d77
2025-08-06 15:47:01,387 - DEBUG - Requesting: http://10.0.0.254:8082/project/5410abda-656d-4bdb-bbd1-2a4e5562512a
2025-08-06 15:47:01,478 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/bf9cd28a-98f6-4a34-9b65-527a2a5b2845
2025-08-06 15:47:01,494 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/bf9cd28a-98f6-4a34-9b65-527a2a5b2845
2025-08-06 15:47:01,508 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/4276d094-a63a-4578-8e24-7e4051d8f278
2025-08-06 15:47:01,518 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/4276d094-a63a-4578-8e24-7e4051d8f278
2025-08-06 15:47:01,527 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/7c85b166-c8ba-467d-be2c-3055c341c534
2025-08-06 15:47:01,535 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-06 15:47:01,535 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/bf9cd28a-98f6-4a34-9b65-527a2a5b2845
2025-08-06 15:47:01,545 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/4276d094-a63a-4578-8e24-7e4051d8f278
2025-08-06 15:47:01,564 - INFO - Verified VN network id 10 for VN bf9cd28a-98f6-4a34-9b65-527a2a5b2845
2025-08-06 15:47:01,564 - INFO - Verifications in API Server for VN ctest-left_vn-77068927 passed
2025-08-06 15:47:01,564 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/bf9cd28a-98f6-4a34-9b65-527a2a5b2845
2025-08-06 15:47:01,583 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/4276d094-a63a-4578-8e24-7e4051d8f278
2025-08-06 15:47:01,591 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/4276d094-a63a-4578-8e24-7e4051d8f278
2025-08-06 15:47:01,599 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/7c85b166-c8ba-467d-be2c-3055c341c534
2025-08-06 15:47:01,613 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcHC-94221478:ctest-left_vn-77068927', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcHC-94221478', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13807142050324695604', 'uuid-lslong': '11197446733176711237'}, 'enable': 'true', 'created': '2025-08-06T15:47:00', 'last-modified': '2025-08-06T15:47:01', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.407913'}
2025-08-06 15:47:01,621 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-06 15:47:01,628 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcHC-94221478:ctest-left_vn-77068927', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcHC-94221478', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13807142050324695604', 'uuid-lslong': '11197446733176711237'}, 'enable': 'true', 'created': '2025-08-06T15:47:00', 'last-modified': '2025-08-06T15:47:01', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.429830'}
2025-08-06 15:47:01,634 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-06 15:47:01,641 - DEBUG - Control-node 10.0.0.48 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcHC-94221478:ctest-left_vn-77068927', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcHC-94221478', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13807142050324695604', 'uuid-lslong': '11197446733176711237'}, 'enable': 'true', 'created': '2025-08-06T15:47:00', 'last-modified': '2025-08-06T15:47:01', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.435327'}
2025-08-06 15:47:01,649 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-06 15:47:01,649 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-77068927 passed
2025-08-06 15:47:01,649 - DEBUG - ====Verifying policy data for ctest-left_vn-77068927 in API_Server ======
2025-08-06 15:47:01,649 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-06 15:47:01,657 - DEBUG - Requesting: http://10.0.0.254:8082/domain/024378da-7408-4369-ac73-51fa8d4d8d77
2025-08-06 15:47:01,673 - DEBUG - Requesting: http://10.0.0.254:8082/project/5410abda-656d-4bdb-bbd1-2a4e5562512a
2025-08-06 15:47:01,772 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/bf9cd28a-98f6-4a34-9b65-527a2a5b2845
2025-08-06 15:47:01,782 - DEBUG - =>VN ctest-left_vn-77068927 has no policy to be verified
2025-08-06 15:47:01,782 - DEBUG - Verifying the vn in opserver
2025-08-06 15:47:01,782 - DEBUG - Verifying the default-domain:ctest-TestSvcHC-94221478:ctest-left_vn-77068927 virtual network link through opserver 10.0.0.254
2025-08-06 15:47:01,782 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-08-06 15:47:01,794 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcHC-94221478:ctest-left_vn-77068927', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcHC-94221478:ctest-left_vn-77068927?flat'}
2025-08-06 15:47:01,794 - DEBUG - Verifying the default-domain:ctest-TestSvcHC-94221478:ctest-left_vn-77068927 virtual network link through opserver 10.0.0.38
2025-08-06 15:47:01,794 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-06 15:47:01,807 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcHC-94221478:ctest-left_vn-77068927', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcHC-94221478:ctest-left_vn-77068927?flat'}
2025-08-06 15:47:01,807 - DEBUG - Verifying the default-domain:ctest-TestSvcHC-94221478:ctest-left_vn-77068927 virtual network link through opserver 10.0.0.48
2025-08-06 15:47:01,807 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/virtual-networks
2025-08-06 15:47:01,819 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcHC-94221478:ctest-left_vn-77068927', 'href': 'http://10.0.0.48:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcHC-94221478:ctest-left_vn-77068927?flat'}
2025-08-06 15:47:01,819 - INFO - Validated that VN default-domain:ctest-TestSvcHC-94221478:ctest-left_vn-77068927 is found in opserver
2025-08-06 15:47:01,848 - DEBUG - Do not have enough data to verify VN in agent
2025-08-06 15:47:01,865 - DEBUG - VRF ids for VN ctest-left_vn-77068927: {}
2025-08-06 15:47:02,128 - DEBUG - Response for create_network : {'network': {'id': '7921bf34-1959-4064-ba50-c85b7d4a6b3a', 'name': 'ctest-right_vn-65011406', 'tenant_id': '5410abda656d4bdbbbd12a4e5562512a', 'project_id': '5410abda656d4bdbbbd12a4e5562512a', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcHC-94221478', 'ctest-right_vn-65011406'], 'port_security_enabled': True, 'description': ''}}
2025-08-06 15:47:02,400 - DEBUG - Response for create_subnet : {'subnet': {'id': '801f556f-529c-46d4-bb10-1a52bc9d71a8', 'name': '', 'tenant_id': '5410abda656d4bdbbbd12a4e5562512a', 'network_id': '7921bf34-1959-4064-ba50-c85b7d4a6b3a', 'ip_version': 4, 'cidr': '31.218.65.64/26', 'allocation_pools': [{'start': '31.218.65.66', 'end': '31.218.65.126'}], 'gateway_ip': '31.218.65.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '31.218.65.66', 'tags': [], 'project_id': '5410abda656d4bdbbbd12a4e5562512a'}}
2025-08-06 15:47:02,421 - INFO - Created VN ctest-right_vn-65011406
2025-08-06 15:47:02,435 - DEBUG - VN ctest-right_vn-65011406 UUID is 7921bf34-1959-4064-ba50-c85b7d4a6b3a
2025-08-06 15:47:02,504 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-06 15:47:02,511 - DEBUG - Requesting: http://10.0.0.254:8082/domain/024378da-7408-4369-ac73-51fa8d4d8d77
2025-08-06 15:47:02,525 - DEBUG - Requesting: http://10.0.0.254:8082/project/5410abda-656d-4bdb-bbd1-2a4e5562512a
2025-08-06 15:47:02,611 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/7921bf34-1959-4064-ba50-c85b7d4a6b3a
2025-08-06 15:47:02,625 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/7921bf34-1959-4064-ba50-c85b7d4a6b3a
2025-08-06 15:47:02,637 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/085a2d06-4694-40c9-8f9d-40bc1dca40a9
2025-08-06 15:47:02,647 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/085a2d06-4694-40c9-8f9d-40bc1dca40a9
2025-08-06 15:47:02,656 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/286b6c6b-adea-40ad-aed0-379d3ed2dfd8
2025-08-06 15:47:02,664 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-06 15:47:02,664 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/7921bf34-1959-4064-ba50-c85b7d4a6b3a
2025-08-06 15:47:02,674 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/085a2d06-4694-40c9-8f9d-40bc1dca40a9
2025-08-06 15:47:02,691 - INFO - Verified VN network id 11 for VN 7921bf34-1959-4064-ba50-c85b7d4a6b3a
2025-08-06 15:47:02,691 - INFO - Verifications in API Server for VN ctest-right_vn-65011406 passed
2025-08-06 15:47:02,691 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/7921bf34-1959-4064-ba50-c85b7d4a6b3a
2025-08-06 15:47:02,704 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/085a2d06-4694-40c9-8f9d-40bc1dca40a9
2025-08-06 15:47:02,714 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/085a2d06-4694-40c9-8f9d-40bc1dca40a9
2025-08-06 15:47:02,723 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/286b6c6b-adea-40ad-aed0-379d3ed2dfd8
2025-08-06 15:47:02,738 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcHC-94221478:ctest-right_vn-65011406', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcHC-94221478', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8728467783305216100', 'uuid-lslong': '13425450784461056826'}, 'enable': 'true', 'created': '2025-08-06T15:47:02', 'last-modified': '2025-08-06T15:47:02', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.384350'}
2025-08-06 15:47:02,748 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-06 15:47:02,754 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcHC-94221478:ctest-right_vn-65011406', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcHC-94221478', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8728467783305216100', 'uuid-lslong': '13425450784461056826'}, 'enable': 'true', 'created': '2025-08-06T15:47:02', 'last-modified': '2025-08-06T15:47:02', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.406827'}
2025-08-06 15:47:02,761 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-06 15:47:02,767 - DEBUG - Control-node 10.0.0.48 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcHC-94221478:ctest-right_vn-65011406', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcHC-94221478', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8728467783305216100', 'uuid-lslong': '13425450784461056826'}, 'enable': 'true', 'created': '2025-08-06T15:47:02', 'last-modified': '2025-08-06T15:47:02', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.416961'}
2025-08-06 15:47:02,774 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-06 15:47:02,774 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-65011406 passed
2025-08-06 15:47:02,774 - DEBUG - ====Verifying policy data for ctest-right_vn-65011406 in API_Server ======
2025-08-06 15:47:02,774 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-06 15:47:02,781 - DEBUG - Requesting: http://10.0.0.254:8082/domain/024378da-7408-4369-ac73-51fa8d4d8d77
2025-08-06 15:47:02,798 - DEBUG - Requesting: http://10.0.0.254:8082/project/5410abda-656d-4bdb-bbd1-2a4e5562512a
2025-08-06 15:47:02,891 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/7921bf34-1959-4064-ba50-c85b7d4a6b3a
2025-08-06 15:47:02,903 - DEBUG - =>VN ctest-right_vn-65011406 has no policy to be verified
2025-08-06 15:47:02,903 - DEBUG - Verifying the vn in opserver
2025-08-06 15:47:02,903 - DEBUG - Verifying the default-domain:ctest-TestSvcHC-94221478:ctest-right_vn-65011406 virtual network link through opserver 10.0.0.254
2025-08-06 15:47:02,903 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-08-06 15:47:02,915 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcHC-94221478:ctest-right_vn-65011406', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcHC-94221478:ctest-right_vn-65011406?flat'}
2025-08-06 15:47:02,916 - DEBUG - Verifying the default-domain:ctest-TestSvcHC-94221478:ctest-right_vn-65011406 virtual network link through opserver 10.0.0.38
2025-08-06 15:47:02,916 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-06 15:47:02,929 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcHC-94221478:ctest-right_vn-65011406', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcHC-94221478:ctest-right_vn-65011406?flat'}
2025-08-06 15:47:02,929 - DEBUG - Verifying the default-domain:ctest-TestSvcHC-94221478:ctest-right_vn-65011406 virtual network link through opserver 10.0.0.48
2025-08-06 15:47:02,929 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/virtual-networks
2025-08-06 15:47:02,944 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcHC-94221478:ctest-right_vn-65011406', 'href': 'http://10.0.0.48:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcHC-94221478:ctest-right_vn-65011406?flat'}
2025-08-06 15:47:02,944 - INFO - Validated that VN default-domain:ctest-TestSvcHC-94221478:ctest-right_vn-65011406 is found in opserver
2025-08-06 15:47:02,969 - DEBUG - Do not have enough data to verify VN in agent
2025-08-06 15:47:02,980 - DEBUG - VRF ids for VN ctest-right_vn-65011406: {}
2025-08-06 15:47:03,245 - DEBUG - Response for create_network : {'network': {'id': 'ea09cb8e-0b55-4712-b61d-3406bee8a0a5', 'name': 'ctest-trans_left_vn-30923323', 'tenant_id': '5410abda656d4bdbbbd12a4e5562512a', 'project_id': '5410abda656d4bdbbbd12a4e5562512a', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcHC-94221478', 'ctest-trans_left_vn-30923323'], 'port_security_enabled': True, 'description': ''}}
2025-08-06 15:47:03,472 - DEBUG - Response for create_subnet : {'subnet': {'id': '2a1b1f65-88b9-4e06-a93e-f7f35878308c', 'name': '', 'tenant_id': '5410abda656d4bdbbbd12a4e5562512a', 'network_id': 'ea09cb8e-0b55-4712-b61d-3406bee8a0a5', 'ip_version': 4, 'cidr': '198.144.95.0/26', 'allocation_pools': [{'start': '198.144.95.2', 'end': '198.144.95.62'}], 'gateway_ip': '198.144.95.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '198.144.95.2', 'tags': [], 'project_id': '5410abda656d4bdbbbd12a4e5562512a'}}
2025-08-06 15:47:03,493 - INFO - Created VN ctest-trans_left_vn-30923323
2025-08-06 15:47:03,507 - DEBUG - VN ctest-trans_left_vn-30923323 UUID is ea09cb8e-0b55-4712-b61d-3406bee8a0a5
2025-08-06 15:47:03,581 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-06 15:47:03,588 - DEBUG - Requesting: http://10.0.0.254:8082/domain/024378da-7408-4369-ac73-51fa8d4d8d77
2025-08-06 15:47:03,600 - DEBUG - Requesting: http://10.0.0.254:8082/project/5410abda-656d-4bdb-bbd1-2a4e5562512a
2025-08-06 15:47:03,693 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/ea09cb8e-0b55-4712-b61d-3406bee8a0a5
2025-08-06 15:47:03,704 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/ea09cb8e-0b55-4712-b61d-3406bee8a0a5
2025-08-06 15:47:03,715 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/4687d88d-0e07-49e1-a2d4-bf22ae5c0826
2025-08-06 15:47:03,723 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/4687d88d-0e07-49e1-a2d4-bf22ae5c0826
2025-08-06 15:47:03,730 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/7c94b794-1f54-4032-a613-dbc3879311d4
2025-08-06 15:47:03,737 - DEBUG - Route Targets: ['target:64512:8000008']
2025-08-06 15:47:03,737 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/ea09cb8e-0b55-4712-b61d-3406bee8a0a5
2025-08-06 15:47:03,748 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/4687d88d-0e07-49e1-a2d4-bf22ae5c0826
2025-08-06 15:47:03,764 - INFO - Verified VN network id 12 for VN ea09cb8e-0b55-4712-b61d-3406bee8a0a5
2025-08-06 15:47:03,764 - INFO - Verifications in API Server for VN ctest-trans_left_vn-30923323 passed
2025-08-06 15:47:03,765 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/ea09cb8e-0b55-4712-b61d-3406bee8a0a5
2025-08-06 15:47:03,776 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/4687d88d-0e07-49e1-a2d4-bf22ae5c0826
2025-08-06 15:47:03,785 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/4687d88d-0e07-49e1-a2d4-bf22ae5c0826
2025-08-06 15:47:03,793 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/7c94b794-1f54-4032-a613-dbc3879311d4
2025-08-06 15:47:03,806 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcHC-94221478:ctest-trans_left_vn-30923323', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcHC-94221478', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '16864234090601465618', 'uuid-lslong': '13122702092804858021'}, 'enable': 'true', 'created': '2025-08-06T15:47:03', 'last-modified': '2025-08-06T15:47:03', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.383540'}
2025-08-06 15:47:03,814 - DEBUG - Route Targets: ['target:64512:8000008']
2025-08-06 15:47:03,822 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcHC-94221478:ctest-trans_left_vn-30923323', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcHC-94221478', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '16864234090601465618', 'uuid-lslong': '13122702092804858021'}, 'enable': 'true', 'created': '2025-08-06T15:47:03', 'last-modified': '2025-08-06T15:47:03', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.391896'}
2025-08-06 15:47:03,828 - DEBUG - Route Targets: ['target:64512:8000008']
2025-08-06 15:47:03,834 - DEBUG - Control-node 10.0.0.48 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcHC-94221478:ctest-trans_left_vn-30923323', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcHC-94221478', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '16864234090601465618', 'uuid-lslong': '13122702092804858021'}, 'enable': 'true', 'created': '2025-08-06T15:47:03', 'last-modified': '2025-08-06T15:47:03', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.412021'}
2025-08-06 15:47:03,842 - DEBUG - Route Targets: ['target:64512:8000008']
2025-08-06 15:47:03,842 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-trans_left_vn-30923323 passed
2025-08-06 15:47:03,842 - DEBUG - ====Verifying policy data for ctest-trans_left_vn-30923323 in API_Server ======
2025-08-06 15:47:03,842 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-06 15:47:03,849 - DEBUG - Requesting: http://10.0.0.254:8082/domain/024378da-7408-4369-ac73-51fa8d4d8d77
2025-08-06 15:47:03,863 - DEBUG - Requesting: http://10.0.0.254:8082/project/5410abda-656d-4bdb-bbd1-2a4e5562512a
2025-08-06 15:47:03,972 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/ea09cb8e-0b55-4712-b61d-3406bee8a0a5
2025-08-06 15:47:03,983 - DEBUG - =>VN ctest-trans_left_vn-30923323 has no policy to be verified
2025-08-06 15:47:03,983 - DEBUG - Verifying the vn in opserver
2025-08-06 15:47:03,983 - DEBUG - Verifying the default-domain:ctest-TestSvcHC-94221478:ctest-trans_left_vn-30923323 virtual network link through opserver 10.0.0.254
2025-08-06 15:47:03,983 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-08-06 15:47:03,996 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcHC-94221478:ctest-trans_left_vn-30923323', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcHC-94221478:ctest-trans_left_vn-30923323?flat'}
2025-08-06 15:47:03,996 - DEBUG - Verifying the default-domain:ctest-TestSvcHC-94221478:ctest-trans_left_vn-30923323 virtual network link through opserver 10.0.0.38
2025-08-06 15:47:03,996 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-06 15:47:04,008 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcHC-94221478:ctest-trans_left_vn-30923323', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcHC-94221478:ctest-trans_left_vn-30923323?flat'}
2025-08-06 15:47:04,009 - DEBUG - Verifying the default-domain:ctest-TestSvcHC-94221478:ctest-trans_left_vn-30923323 virtual network link through opserver 10.0.0.48
2025-08-06 15:47:04,009 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/virtual-networks
2025-08-06 15:47:04,022 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcHC-94221478:ctest-trans_left_vn-30923323', 'href': 'http://10.0.0.48:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcHC-94221478:ctest-trans_left_vn-30923323?flat'}
2025-08-06 15:47:04,022 - INFO - Validated that VN default-domain:ctest-TestSvcHC-94221478:ctest-trans_left_vn-30923323 is found in opserver
2025-08-06 15:47:04,047 - DEBUG - Do not have enough data to verify VN in agent
2025-08-06 15:47:04,060 - DEBUG - VRF ids for VN ctest-trans_left_vn-30923323: {}
2025-08-06 15:47:04,315 - DEBUG - Response for create_network : {'network': {'id': '46d9da7a-01e6-4967-9842-4342fc893a12', 'name': 'ctest-trans_right_vn-61201268', 'tenant_id': '5410abda656d4bdbbbd12a4e5562512a', 'project_id': '5410abda656d4bdbbbd12a4e5562512a', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcHC-94221478', 'ctest-trans_right_vn-61201268'], 'port_security_enabled': True, 'description': ''}}
2025-08-06 15:47:04,544 - DEBUG - Response for create_subnet : {'subnet': {'id': '8ae692ba-69ee-4bd6-971f-21a596eaf58c', 'name': '', 'tenant_id': '5410abda656d4bdbbbd12a4e5562512a', 'network_id': '46d9da7a-01e6-4967-9842-4342fc893a12', 'ip_version': 4, 'cidr': '14.140.105.192/26', 'allocation_pools': [{'start': '14.140.105.194', 'end': '14.140.105.254'}], 'gateway_ip': '14.140.105.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '14.140.105.194', 'tags': [], 'project_id': '5410abda656d4bdbbbd12a4e5562512a'}}
2025-08-06 15:47:04,564 - INFO - Created VN ctest-trans_right_vn-61201268
2025-08-06 15:47:04,576 - DEBUG - VN ctest-trans_right_vn-61201268 UUID is 46d9da7a-01e6-4967-9842-4342fc893a12
2025-08-06 15:47:04,649 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-06 15:47:04,655 - DEBUG - Requesting: http://10.0.0.254:8082/domain/024378da-7408-4369-ac73-51fa8d4d8d77
2025-08-06 15:47:04,669 - DEBUG - Requesting: http://10.0.0.254:8082/project/5410abda-656d-4bdb-bbd1-2a4e5562512a
2025-08-06 15:47:04,761 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/46d9da7a-01e6-4967-9842-4342fc893a12
2025-08-06 15:47:04,777 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/46d9da7a-01e6-4967-9842-4342fc893a12
2025-08-06 15:47:04,789 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/67d49a60-6125-499f-805f-41fccd4466a9
2025-08-06 15:47:04,804 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/67d49a60-6125-499f-805f-41fccd4466a9
2025-08-06 15:47:04,812 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/307b2078-78df-49ab-9ec2-912b8a957625
2025-08-06 15:47:04,819 - DEBUG - Route Targets: ['target:64512:8000009']
2025-08-06 15:47:04,820 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/46d9da7a-01e6-4967-9842-4342fc893a12
2025-08-06 15:47:04,830 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/67d49a60-6125-499f-805f-41fccd4466a9
2025-08-06 15:47:04,845 - INFO - Verified VN network id 13 for VN 46d9da7a-01e6-4967-9842-4342fc893a12
2025-08-06 15:47:04,845 - INFO - Verifications in API Server for VN ctest-trans_right_vn-61201268 passed
2025-08-06 15:47:04,846 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/46d9da7a-01e6-4967-9842-4342fc893a12
2025-08-06 15:47:04,858 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/67d49a60-6125-499f-805f-41fccd4466a9
2025-08-06 15:47:04,865 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/67d49a60-6125-499f-805f-41fccd4466a9
2025-08-06 15:47:04,875 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/307b2078-78df-49ab-9ec2-912b8a957625
2025-08-06 15:47:04,889 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcHC-94221478:ctest-trans_right_vn-61201268', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcHC-94221478', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5105351870153902439', 'uuid-lslong': '10971405597211703826'}, 'enable': 'true', 'created': '2025-08-06T15:47:04', 'last-modified': '2025-08-06T15:47:04', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.399362'}
2025-08-06 15:47:04,896 - DEBUG - Route Targets: ['target:64512:8000009']
2025-08-06 15:47:04,902 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcHC-94221478:ctest-trans_right_vn-61201268', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcHC-94221478', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5105351870153902439', 'uuid-lslong': '10971405597211703826'}, 'enable': 'true', 'created': '2025-08-06T15:47:04', 'last-modified': '2025-08-06T15:47:04', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.413794'}
2025-08-06 15:47:04,909 - DEBUG - Route Targets: ['target:64512:8000009']
2025-08-06 15:47:04,915 - DEBUG - Control-node 10.0.0.48 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcHC-94221478:ctest-trans_right_vn-61201268', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcHC-94221478', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5105351870153902439', 'uuid-lslong': '10971405597211703826'}, 'enable': 'true', 'created': '2025-08-06T15:47:04', 'last-modified': '2025-08-06T15:47:04', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.426458'}
2025-08-06 15:47:04,922 - DEBUG - Route Targets: ['target:64512:8000009']
2025-08-06 15:47:04,923 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-trans_right_vn-61201268 passed
2025-08-06 15:47:04,923 - DEBUG - ====Verifying policy data for ctest-trans_right_vn-61201268 in API_Server ======
2025-08-06 15:47:04,923 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-06 15:47:04,930 - DEBUG - Requesting: http://10.0.0.254:8082/domain/024378da-7408-4369-ac73-51fa8d4d8d77
2025-08-06 15:47:04,943 - DEBUG - Requesting: http://10.0.0.254:8082/project/5410abda-656d-4bdb-bbd1-2a4e5562512a
2025-08-06 15:47:05,048 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/46d9da7a-01e6-4967-9842-4342fc893a12
2025-08-06 15:47:05,059 - DEBUG - =>VN ctest-trans_right_vn-61201268 has no policy to be verified
2025-08-06 15:47:05,059 - DEBUG - Verifying the vn in opserver
2025-08-06 15:47:05,059 - DEBUG - Verifying the default-domain:ctest-TestSvcHC-94221478:ctest-trans_right_vn-61201268 virtual network link through opserver 10.0.0.254
2025-08-06 15:47:05,059 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-08-06 15:47:05,072 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcHC-94221478:ctest-trans_right_vn-61201268', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcHC-94221478:ctest-trans_right_vn-61201268?flat'}
2025-08-06 15:47:05,072 - DEBUG - Verifying the default-domain:ctest-TestSvcHC-94221478:ctest-trans_right_vn-61201268 virtual network link through opserver 10.0.0.38
2025-08-06 15:47:05,072 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-06 15:47:05,085 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcHC-94221478:ctest-trans_right_vn-61201268', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcHC-94221478:ctest-trans_right_vn-61201268?flat'}
2025-08-06 15:47:05,085 - DEBUG - Verifying the default-domain:ctest-TestSvcHC-94221478:ctest-trans_right_vn-61201268 virtual network link through opserver 10.0.0.48
2025-08-06 15:47:05,085 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/virtual-networks
2025-08-06 15:47:05,098 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcHC-94221478:ctest-trans_right_vn-61201268', 'href': 'http://10.0.0.48:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcHC-94221478:ctest-trans_right_vn-61201268?flat'}
2025-08-06 15:47:05,098 - INFO - Validated that VN default-domain:ctest-TestSvcHC-94221478:ctest-trans_right_vn-61201268 is found in opserver
2025-08-06 15:47:05,126 - DEBUG - Do not have enough data to verify VN in agent
2025-08-06 15:47:05,140 - DEBUG - VRF ids for VN ctest-trans_right_vn-61201268: {}
2025-08-06 15:47:06,218 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3763-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3763-2)
2025-08-06 15:47:07,496 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3763-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3763-1)
2025-08-06 15:47:07,496 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-53275231']
2025-08-06 15:47:07,786 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-53275231'] created succesfully
2025-08-06 15:47:08,135 - DEBUG - Image by name tiny_trans_fw not found
2025-08-06 15:47:08,136 - DEBUG - Installing image tiny_trans_fw
2025-08-06 15:47:08,136 - DEBUG - Image info {'name': 'tinycore-net-transparent-fw.qcow2.gz', 'webserver': '', 'location': '/images/tinycore/', 'username': 'tc', 'password': 'secret', 'type': 'os', 'flavor': 'contrail_flavor_tiny', 'params': '--container-format bare --disk-format vmdk --property vmware_disktype="sparse" --property vmware_adaptertype="ide" --property hypervisor_type=qemu', 'name_docker': 'phusion-baseimage-enablesshd'}
2025-08-06 15:47:08,136 - DEBUG - Image build path file:///contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2025-08-06 15:47:08,136 - DEBUG - Download image from local file file:///contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2025-08-06 15:47:08,136 - DEBUG - Unzip image
2025-08-06 15:47:08,136 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2025-08-06 15:47:08,154 - INFO - image /contrail-test/images/tinycore-net-transparent-fw.qcow2 is not found
2025-08-06 15:47:08,154 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-53275231']
2025-08-06 15:47:08,282 - INFO - Service template: ['default-domain', 'ctest-service_template_1-53275231'] deleted successfully.
2025-08-06 15:47:08,283 - INFO - Skip interface_detach for VM ctest-right_vm-69338786 in state BUILD
2025-08-06 15:47:08,283 - INFO - Deleting VM ctest-right_vm-69338786
2025-08-06 15:47:08,284 - ERROR - VM ctest-right_vm-69338786 has failed to come up
2025-08-06 15:47:08,284 - ERROR - Nova failed to get host of the VM
2025-08-06 15:47:08,284 - ERROR - VM ctest-right_vm-69338786 has failed to come up
2025-08-06 15:47:08,285 - ERROR - Nova failed to get host of the VM
2025-08-06 15:47:08,357 - INFO - Skip interface_detach for VM ctest-left_vm-06154328 in state BUILD
2025-08-06 15:47:08,357 - INFO - Deleting VM ctest-left_vm-06154328
2025-08-06 15:47:08,357 - ERROR - VM ctest-left_vm-06154328 has failed to come up
2025-08-06 15:47:08,357 - ERROR - Nova failed to get host of the VM
2025-08-06 15:47:08,357 - ERROR - VM ctest-left_vm-06154328 has failed to come up
2025-08-06 15:47:08,357 - ERROR - Nova failed to get host of the VM
2025-08-06 15:47:08,434 - INFO - Deleting VN ctest-trans_right_vn-61201268
2025-08-06 15:47:08,582 - DEBUG - Response for deleting network ()
2025-08-06 15:47:08,583 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/67d49a60-6125-499f-805f-41fccd4466a9
2025-08-06 15:47:08,597 - DEBUG - Response Code: 404
2025-08-06 15:47:08,597 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-06 15:47:08,606 - DEBUG - Requesting: http://10.0.0.254:8082/domain/024378da-7408-4369-ac73-51fa8d4d8d77
2025-08-06 15:47:08,622 - DEBUG - Requesting: http://10.0.0.254:8082/project/5410abda-656d-4bdb-bbd1-2a4e5562512a
2025-08-06 15:47:08,721 - INFO - Validated that VN ctest-trans_right_vn-61201268 is not found in API Server
2025-08-06 15:47:08,763 - DEBUG - VN ctest-trans_right_vn-61201268 is not present in Agent 10.0.0.49
2025-08-06 15:47:08,776 - DEBUG - VN ctest-trans_right_vn-61201268 is not present in Agent 10.0.0.57
2025-08-06 15:47:08,776 - INFO - Validated that VN ctest-trans_right_vn-61201268 is not in any agent
2025-08-06 15:47:08,820 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-trans_right_vn-61201268 info
2025-08-06 15:47:08,820 - INFO - Deleting VN ctest-trans_left_vn-30923323
2025-08-06 15:47:08,966 - DEBUG - Response for deleting network ()
2025-08-06 15:47:08,966 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/4687d88d-0e07-49e1-a2d4-bf22ae5c0826
2025-08-06 15:47:08,972 - DEBUG - Response Code: 404
2025-08-06 15:47:08,973 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-06 15:47:08,979 - DEBUG - Requesting: http://10.0.0.254:8082/domain/024378da-7408-4369-ac73-51fa8d4d8d77
2025-08-06 15:47:09,008 - DEBUG - Requesting: http://10.0.0.254:8082/project/5410abda-656d-4bdb-bbd1-2a4e5562512a
2025-08-06 15:47:09,144 - INFO - Validated that VN ctest-trans_left_vn-30923323 is not found in API Server
2025-08-06 15:47:09,178 - DEBUG - VN ctest-trans_left_vn-30923323 is not present in Agent 10.0.0.49
2025-08-06 15:47:09,196 - DEBUG - VN ctest-trans_left_vn-30923323 is not present in Agent 10.0.0.57
2025-08-06 15:47:09,196 - INFO - Validated that VN ctest-trans_left_vn-30923323 is not in any agent
2025-08-06 15:47:09,244 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-trans_left_vn-30923323 info
2025-08-06 15:47:09,244 - INFO - Deleting VN ctest-right_vn-65011406
2025-08-06 15:47:09,298 - DEBUG - VN 7921bf34-1959-4064-ba50-c85b7d4a6b3a still in use: Unable to complete operation on network 7921bf34-1959-4064-ba50-c85b7d4a6b3a. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-e1c88d0e-0719-4edf-a168-a2f3521bd8b9']
2025-08-06 15:47:09,298 - WARNING - Deleting VN ctest-right_vn-65011406 failed..Will retry
2025-08-06 15:47:11,458 - DEBUG - Response for deleting network ()
2025-08-06 15:47:11,458 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/085a2d06-4694-40c9-8f9d-40bc1dca40a9
2025-08-06 15:47:11,464 - DEBUG - Response Code: 404
2025-08-06 15:47:11,464 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-06 15:47:11,470 - DEBUG - Requesting: http://10.0.0.254:8082/domain/024378da-7408-4369-ac73-51fa8d4d8d77
2025-08-06 15:47:11,536 - DEBUG - Requesting: http://10.0.0.254:8082/project/5410abda-656d-4bdb-bbd1-2a4e5562512a
2025-08-06 15:47:11,632 - INFO - Validated that VN ctest-right_vn-65011406 is not found in API Server
2025-08-06 15:47:11,644 - DEBUG - VN ctest-right_vn-65011406 is not present in Agent 10.0.0.49
2025-08-06 15:47:11,659 - DEBUG - VN ctest-right_vn-65011406 is not present in Agent 10.0.0.57
2025-08-06 15:47:11,659 - INFO - Validated that VN ctest-right_vn-65011406 is not in any agent
2025-08-06 15:47:11,700 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-65011406 info
2025-08-06 15:47:11,700 - INFO - Deleting VN ctest-left_vn-77068927
2025-08-06 15:47:11,848 - DEBUG - Response for deleting network ()
2025-08-06 15:47:11,848 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/4276d094-a63a-4578-8e24-7e4051d8f278
2025-08-06 15:47:11,857 - DEBUG - Response Code: 404
2025-08-06 15:47:11,857 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-06 15:47:11,869 - DEBUG - Requesting: http://10.0.0.254:8082/domain/024378da-7408-4369-ac73-51fa8d4d8d77
2025-08-06 15:47:11,883 - DEBUG - Requesting: http://10.0.0.254:8082/project/5410abda-656d-4bdb-bbd1-2a4e5562512a
2025-08-06 15:47:11,976 - INFO - Validated that VN ctest-left_vn-77068927 is not found in API Server
2025-08-06 15:47:11,988 - DEBUG - VN ctest-left_vn-77068927 is not present in Agent 10.0.0.49
2025-08-06 15:47:12,003 - DEBUG - VN ctest-left_vn-77068927 is not present in Agent 10.0.0.57
2025-08-06 15:47:12,003 - INFO - Validated that VN ctest-left_vn-77068927 is not in any agent
2025-08-06 15:47:12,042 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-77068927 info
2025-08-06 15:47:12,043 - INFO - Deleting VN ctest-mgmt_vn-47198297
2025-08-06 15:47:12,186 - DEBUG - Response for deleting network ()
2025-08-06 15:47:12,186 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/14e71bf9-5b58-4dae-aa1e-ce4dbe7f6556
2025-08-06 15:47:12,192 - DEBUG - Response Code: 404
2025-08-06 15:47:12,193 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-06 15:47:12,198 - DEBUG - Requesting: http://10.0.0.254:8082/domain/024378da-7408-4369-ac73-51fa8d4d8d77
2025-08-06 15:47:12,210 - DEBUG - Requesting: http://10.0.0.254:8082/project/5410abda-656d-4bdb-bbd1-2a4e5562512a
2025-08-06 15:47:12,300 - INFO - Validated that VN ctest-mgmt_vn-47198297 is not found in API Server
2025-08-06 15:47:12,314 - DEBUG - VN ctest-mgmt_vn-47198297 is not present in Agent 10.0.0.49
2025-08-06 15:47:12,329 - DEBUG - VN ctest-mgmt_vn-47198297 is not present in Agent 10.0.0.57
2025-08-06 15:47:12,329 - INFO - Validated that VN ctest-mgmt_vn-47198297 is not in any agent
2025-08-06 15:47:12,366 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-47198297 info
2025-08-06 15:47:13,627 - DEBUG - No XMPP flaps were noticed during the test
2025-08-06 15:47:13,627 - INFO -
2025-08-06 15:47:13,627 - INFO - END TEST : test_svc_trans_segment_right_hc_fail_svm : SKIPPED[0:00:15]
2025-08-06 15:47:13,627 - INFO - --------------------------------------------------------------------------------
2025-08-06 15:47:14,322 - INFO - Deleted project: ctest-TestSvcHC-94221478, ID : 5410abda-656d-4bdb-bbd1-2a4e5562512a