2025-10-20 03:07:59,631 - INFO - Domain Default found not creating
2025-10-20 03:07:59,765 - INFO - Project ctest-TestSvcHC-27824646 not found, creating it
2025-10-20 03:08:00,249 - INFO - Created Project:ctest-TestSvcHC-27824646, ID : cec0a583-93ae-4444-8076-32363ef1da5d
2025-10-20 03:08:01,668 - INFO - ================================================================================
2025-10-20 03:08:01,668 - INFO - STARTING TEST : test_svc_hc_basic
2025-10-20 03:08:02,940 - DEBUG - Nothing to compare xmpp stats {'10.0.0.131': {'10.20.0.25': '0', '10.20.0.193': '0'}, '10.0.0.19': {'10.20.0.193': '0', '10.20.0.14': '0'}} with
2025-10-20 03:08:02,940 - INFO - Initial checks done. Running the testcase now
2025-10-20 03:08:02,940 - INFO -
2025-10-20 03:08:03,614 - DEBUG - Response for create_network : {'network': {'id': '12df8371-f7f1-4600-8e1b-f9f640495215', 'name': 'ctest-mgmt_vn-77532855', 'tenant_id': 'cec0a58393ae4444807632363ef1da5d', 'project_id': 'cec0a58393ae4444807632363ef1da5d', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcHC-27824646', 'ctest-mgmt_vn-77532855'], 'port_security_enabled': True, 'description': ''}}
2025-10-20 03:08:03,891 - DEBUG - Response for create_subnet : {'subnet': {'id': '90212de2-caa2-4ad7-b9bb-6f6cfd72c686', 'name': '', 'tenant_id': 'cec0a58393ae4444807632363ef1da5d', 'network_id': '12df8371-f7f1-4600-8e1b-f9f640495215', 'ip_version': 4, 'cidr': '110.246.232.128/26', 'allocation_pools': [{'start': '110.246.232.130', 'end': '110.246.232.190'}], 'gateway_ip': '110.246.232.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '110.246.232.130', 'tags': [], 'project_id': 'cec0a58393ae4444807632363ef1da5d'}}
2025-10-20 03:08:03,916 - INFO - Created VN ctest-mgmt_vn-77532855
2025-10-20 03:08:03,932 - DEBUG - VN ctest-mgmt_vn-77532855 UUID is 12df8371-f7f1-4600-8e1b-f9f640495215
2025-10-20 03:08:03,998 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-20 03:08:04,299 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-20 03:08:04,325 - DEBUG - Requesting: http://10.0.0.22:8082/domain/9567af48-d216-4b74-bb40-c5fce52cf633
2025-10-20 03:08:04,341 - DEBUG - Requesting: http://10.0.0.22:8082/project/cec0a583-93ae-4444-8076-32363ef1da5d
2025-10-20 03:08:04,439 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/12df8371-f7f1-4600-8e1b-f9f640495215
2025-10-20 03:08:04,453 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/12df8371-f7f1-4600-8e1b-f9f640495215
2025-10-20 03:08:04,466 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/d6dacfd8-a3d8-4735-8437-e6d61af4888e
2025-10-20 03:08:04,477 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/d6dacfd8-a3d8-4735-8437-e6d61af4888e
2025-10-20 03:08:04,485 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/809cab3e-d1cc-4d32-9fad-857d09852770
2025-10-20 03:08:04,492 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-20 03:08:04,492 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/12df8371-f7f1-4600-8e1b-f9f640495215
2025-10-20 03:08:04,501 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/d6dacfd8-a3d8-4735-8437-e6d61af4888e
2025-10-20 03:08:04,517 - INFO - Verified VN network id 11 for VN 12df8371-f7f1-4600-8e1b-f9f640495215
2025-10-20 03:08:04,517 - INFO - Verifications in API Server for VN ctest-mgmt_vn-77532855 passed
2025-10-20 03:08:04,517 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/12df8371-f7f1-4600-8e1b-f9f640495215
2025-10-20 03:08:04,526 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/d6dacfd8-a3d8-4735-8437-e6d61af4888e
2025-10-20 03:08:04,536 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/d6dacfd8-a3d8-4735-8437-e6d61af4888e
2025-10-20 03:08:04,545 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/809cab3e-d1cc-4d32-9fad-857d09852770
2025-10-20 03:08:04,560 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcHC-27824646:ctest-mgmt_vn-77532855', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcHC-27824646', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1359950138003506688', 'uuid-lslong': '10240053013792772629'}, 'enable': 'true', 'created': '2025-10-20T03:08:03', 'last-modified': '2025-10-20T03:08:03', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.732938'}
2025-10-20 03:08:04,566 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-20 03:08:04,572 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcHC-27824646:ctest-mgmt_vn-77532855', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcHC-27824646', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1359950138003506688', 'uuid-lslong': '10240053013792772629'}, 'enable': 'true', 'created': '2025-10-20T03:08:03', 'last-modified': '2025-10-20T03:08:03', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.748057'}
2025-10-20 03:08:04,580 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-20 03:08:04,590 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcHC-27824646:ctest-mgmt_vn-77532855', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcHC-27824646', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1359950138003506688', 'uuid-lslong': '10240053013792772629'}, 'enable': 'true', 'created': '2025-10-20T03:08:03', 'last-modified': '2025-10-20T03:08:03', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.760423'}
2025-10-20 03:08:04,603 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-20 03:08:04,603 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-77532855 passed
2025-10-20 03:08:04,605 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-77532855 in API_Server ======
2025-10-20 03:08:04,605 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-20 03:08:04,613 - DEBUG - Requesting: http://10.0.0.22:8082/domain/9567af48-d216-4b74-bb40-c5fce52cf633
2025-10-20 03:08:04,624 - DEBUG - Requesting: http://10.0.0.22:8082/project/cec0a583-93ae-4444-8076-32363ef1da5d
2025-10-20 03:08:04,712 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/12df8371-f7f1-4600-8e1b-f9f640495215
2025-10-20 03:08:04,724 - DEBUG - =>VN ctest-mgmt_vn-77532855 has no policy to be verified
2025-10-20 03:08:04,724 - DEBUG - Verifying the vn in opserver
2025-10-20 03:08:04,724 - DEBUG - Verifying the default-domain:ctest-TestSvcHC-27824646:ctest-mgmt_vn-77532855 virtual network link through opserver 10.0.0.22
2025-10-20 03:08:04,724 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2025-10-20 03:08:05,020 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2025-10-20 03:08:05,076 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcHC-27824646:ctest-mgmt_vn-77532855', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcHC-27824646:ctest-mgmt_vn-77532855?flat'}
2025-10-20 03:08:05,076 - DEBUG - Verifying the default-domain:ctest-TestSvcHC-27824646:ctest-mgmt_vn-77532855 virtual network link through opserver 10.0.0.254
2025-10-20 03:08:05,076 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-10-20 03:08:05,368 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-10-20 03:08:05,424 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcHC-27824646:ctest-mgmt_vn-77532855', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcHC-27824646:ctest-mgmt_vn-77532855?flat'}
2025-10-20 03:08:05,424 - DEBUG - Verifying the default-domain:ctest-TestSvcHC-27824646:ctest-mgmt_vn-77532855 virtual network link through opserver 10.0.0.27
2025-10-20 03:08:05,424 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-10-20 03:08:05,713 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-10-20 03:08:05,766 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcHC-27824646:ctest-mgmt_vn-77532855', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcHC-27824646:ctest-mgmt_vn-77532855?flat'}
2025-10-20 03:08:05,766 - INFO - Validated that VN default-domain:ctest-TestSvcHC-27824646:ctest-mgmt_vn-77532855 is found in opserver
2025-10-20 03:08:05,787 - DEBUG - Do not have enough data to verify VN in agent
2025-10-20 03:08:05,797 - DEBUG - VRF ids for VN ctest-mgmt_vn-77532855: {}
2025-10-20 03:08:06,081 - DEBUG - Response for create_network : {'network': {'id': 'c84428b4-310d-4cdb-9c97-fc8272e9e681', 'name': 'ctest-left_vn-09734864', 'tenant_id': 'cec0a58393ae4444807632363ef1da5d', 'project_id': 'cec0a58393ae4444807632363ef1da5d', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcHC-27824646', 'ctest-left_vn-09734864'], 'port_security_enabled': True, 'description': ''}}
2025-10-20 03:08:06,384 - DEBUG - Response for create_subnet : {'subnet': {'id': '13ae2f33-3cef-4f40-979f-57859826202d', 'name': '', 'tenant_id': 'cec0a58393ae4444807632363ef1da5d', 'network_id': 'c84428b4-310d-4cdb-9c97-fc8272e9e681', 'ip_version': 4, 'cidr': '106.41.118.192/26', 'allocation_pools': [{'start': '106.41.118.194', 'end': '106.41.118.254'}], 'gateway_ip': '106.41.118.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '106.41.118.194', 'tags': [], 'project_id': 'cec0a58393ae4444807632363ef1da5d'}}
2025-10-20 03:08:06,404 - INFO - Created VN ctest-left_vn-09734864
2025-10-20 03:08:06,415 - DEBUG - VN ctest-left_vn-09734864 UUID is c84428b4-310d-4cdb-9c97-fc8272e9e681
2025-10-20 03:08:06,492 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-20 03:08:06,498 - DEBUG - Requesting: http://10.0.0.22:8082/domain/9567af48-d216-4b74-bb40-c5fce52cf633
2025-10-20 03:08:06,511 - DEBUG - Requesting: http://10.0.0.22:8082/project/cec0a583-93ae-4444-8076-32363ef1da5d
2025-10-20 03:08:06,610 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/c84428b4-310d-4cdb-9c97-fc8272e9e681
2025-10-20 03:08:06,626 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/c84428b4-310d-4cdb-9c97-fc8272e9e681
2025-10-20 03:08:06,641 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/f6133ab8-1643-4a2c-992c-08cf078e0171
2025-10-20 03:08:06,654 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/f6133ab8-1643-4a2c-992c-08cf078e0171
2025-10-20 03:08:06,662 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/67771d49-1d08-4333-8fa9-57e1deb5f56e
2025-10-20 03:08:06,671 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-20 03:08:06,671 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/c84428b4-310d-4cdb-9c97-fc8272e9e681
2025-10-20 03:08:06,682 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/f6133ab8-1643-4a2c-992c-08cf078e0171
2025-10-20 03:08:06,704 - INFO - Verified VN network id 12 for VN c84428b4-310d-4cdb-9c97-fc8272e9e681
2025-10-20 03:08:06,704 - INFO - Verifications in API Server for VN ctest-left_vn-09734864 passed
2025-10-20 03:08:06,704 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/c84428b4-310d-4cdb-9c97-fc8272e9e681
2025-10-20 03:08:06,715 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/f6133ab8-1643-4a2c-992c-08cf078e0171
2025-10-20 03:08:06,725 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/f6133ab8-1643-4a2c-992c-08cf078e0171
2025-10-20 03:08:06,733 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/67771d49-1d08-4333-8fa9-57e1deb5f56e
2025-10-20 03:08:06,744 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcHC-27824646:ctest-left_vn-09734864', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcHC-27824646', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '14430703860384091355', 'uuid-lslong': '11283765028603946625'}, 'enable': 'true', 'created': '2025-10-20T03:08:06', 'last-modified': '2025-10-20T03:08:06', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.432367'}
2025-10-20 03:08:06,750 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-20 03:08:06,756 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcHC-27824646:ctest-left_vn-09734864', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcHC-27824646', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '14430703860384091355', 'uuid-lslong': '11283765028603946625'}, 'enable': 'true', 'created': '2025-10-20T03:08:06', 'last-modified': '2025-10-20T03:08:06', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.436439'}
2025-10-20 03:08:06,763 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-20 03:08:06,769 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcHC-27824646:ctest-left_vn-09734864', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcHC-27824646', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '14430703860384091355', 'uuid-lslong': '11283765028603946625'}, 'enable': 'true', 'created': '2025-10-20T03:08:06', 'last-modified': '2025-10-20T03:08:06', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.449083'}
2025-10-20 03:08:06,776 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-20 03:08:06,776 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-09734864 passed
2025-10-20 03:08:06,776 - DEBUG - ====Verifying policy data for ctest-left_vn-09734864 in API_Server ======
2025-10-20 03:08:06,777 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-20 03:08:06,785 - DEBUG - Requesting: http://10.0.0.22:8082/domain/9567af48-d216-4b74-bb40-c5fce52cf633
2025-10-20 03:08:06,799 - DEBUG - Requesting: http://10.0.0.22:8082/project/cec0a583-93ae-4444-8076-32363ef1da5d
2025-10-20 03:08:06,897 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/c84428b4-310d-4cdb-9c97-fc8272e9e681
2025-10-20 03:08:06,907 - DEBUG - =>VN ctest-left_vn-09734864 has no policy to be verified
2025-10-20 03:08:06,907 - DEBUG - Verifying the vn in opserver
2025-10-20 03:08:06,907 - DEBUG - Verifying the default-domain:ctest-TestSvcHC-27824646:ctest-left_vn-09734864 virtual network link through opserver 10.0.0.22
2025-10-20 03:08:06,907 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2025-10-20 03:08:06,964 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcHC-27824646:ctest-left_vn-09734864', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcHC-27824646:ctest-left_vn-09734864?flat'}
2025-10-20 03:08:06,964 - DEBUG - Verifying the default-domain:ctest-TestSvcHC-27824646:ctest-left_vn-09734864 virtual network link through opserver 10.0.0.254
2025-10-20 03:08:06,964 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-10-20 03:08:07,023 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcHC-27824646:ctest-left_vn-09734864', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcHC-27824646:ctest-left_vn-09734864?flat'}
2025-10-20 03:08:07,023 - DEBUG - Verifying the default-domain:ctest-TestSvcHC-27824646:ctest-left_vn-09734864 virtual network link through opserver 10.0.0.27
2025-10-20 03:08:07,023 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-10-20 03:08:07,086 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcHC-27824646:ctest-left_vn-09734864', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcHC-27824646:ctest-left_vn-09734864?flat'}
2025-10-20 03:08:07,086 - INFO - Validated that VN default-domain:ctest-TestSvcHC-27824646:ctest-left_vn-09734864 is found in opserver
2025-10-20 03:08:07,107 - DEBUG - Do not have enough data to verify VN in agent
2025-10-20 03:08:07,116 - DEBUG - VRF ids for VN ctest-left_vn-09734864: {}
2025-10-20 03:08:07,382 - DEBUG - Response for create_network : {'network': {'id': '49f79283-27ab-4cee-87c2-7d0c68f639e8', 'name': 'ctest-right_vn-08290234', 'tenant_id': 'cec0a58393ae4444807632363ef1da5d', 'project_id': 'cec0a58393ae4444807632363ef1da5d', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcHC-27824646', 'ctest-right_vn-08290234'], 'port_security_enabled': True, 'description': ''}}
2025-10-20 03:08:07,604 - DEBUG - Response for create_subnet : {'subnet': {'id': '5b8d5cb0-165c-4e21-95b8-b4fe6ae506c3', 'name': '', 'tenant_id': 'cec0a58393ae4444807632363ef1da5d', 'network_id': '49f79283-27ab-4cee-87c2-7d0c68f639e8', 'ip_version': 4, 'cidr': '115.23.60.128/26', 'allocation_pools': [{'start': '115.23.60.130', 'end': '115.23.60.190'}], 'gateway_ip': '115.23.60.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '115.23.60.130', 'tags': [], 'project_id': 'cec0a58393ae4444807632363ef1da5d'}}
2025-10-20 03:08:07,630 - INFO - Created VN ctest-right_vn-08290234
2025-10-20 03:08:07,640 - DEBUG - VN ctest-right_vn-08290234 UUID is 49f79283-27ab-4cee-87c2-7d0c68f639e8
2025-10-20 03:08:07,709 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-20 03:08:07,715 - DEBUG - Requesting: http://10.0.0.22:8082/domain/9567af48-d216-4b74-bb40-c5fce52cf633
2025-10-20 03:08:07,727 - DEBUG - Requesting: http://10.0.0.22:8082/project/cec0a583-93ae-4444-8076-32363ef1da5d
2025-10-20 03:08:07,819 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/49f79283-27ab-4cee-87c2-7d0c68f639e8
2025-10-20 03:08:07,830 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/49f79283-27ab-4cee-87c2-7d0c68f639e8
2025-10-20 03:08:07,838 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/6e105f6a-2641-41bd-99a7-be8ddb93013c
2025-10-20 03:08:07,848 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/6e105f6a-2641-41bd-99a7-be8ddb93013c
2025-10-20 03:08:07,854 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/d2cc12cb-7753-4e48-b826-745fcef5f8be
2025-10-20 03:08:07,861 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-20 03:08:07,861 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/49f79283-27ab-4cee-87c2-7d0c68f639e8
2025-10-20 03:08:07,870 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/6e105f6a-2641-41bd-99a7-be8ddb93013c
2025-10-20 03:08:07,886 - INFO - Verified VN network id 13 for VN 49f79283-27ab-4cee-87c2-7d0c68f639e8
2025-10-20 03:08:07,886 - INFO - Verifications in API Server for VN ctest-right_vn-08290234 passed
2025-10-20 03:08:07,886 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/49f79283-27ab-4cee-87c2-7d0c68f639e8
2025-10-20 03:08:07,895 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/6e105f6a-2641-41bd-99a7-be8ddb93013c
2025-10-20 03:08:07,902 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/6e105f6a-2641-41bd-99a7-be8ddb93013c
2025-10-20 03:08:07,907 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/d2cc12cb-7753-4e48-b826-745fcef5f8be
2025-10-20 03:08:07,921 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcHC-27824646:ctest-right_vn-08290234', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcHC-27824646', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5329889776020180206', 'uuid-lslong': '9782518832856185320'}, 'enable': 'true', 'created': '2025-10-20T03:08:07', 'last-modified': '2025-10-20T03:08:07', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.357834'}
2025-10-20 03:08:07,930 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-20 03:08:07,935 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcHC-27824646:ctest-right_vn-08290234', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcHC-27824646', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5329889776020180206', 'uuid-lslong': '9782518832856185320'}, 'enable': 'true', 'created': '2025-10-20T03:08:07', 'last-modified': '2025-10-20T03:08:07', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.375850'}
2025-10-20 03:08:07,942 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-20 03:08:07,948 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcHC-27824646:ctest-right_vn-08290234', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcHC-27824646', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5329889776020180206', 'uuid-lslong': '9782518832856185320'}, 'enable': 'true', 'created': '2025-10-20T03:08:07', 'last-modified': '2025-10-20T03:08:07', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.395010'}
2025-10-20 03:08:07,954 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-20 03:08:07,955 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-08290234 passed
2025-10-20 03:08:07,955 - DEBUG - ====Verifying policy data for ctest-right_vn-08290234 in API_Server ======
2025-10-20 03:08:07,955 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-20 03:08:07,961 - DEBUG - Requesting: http://10.0.0.22:8082/domain/9567af48-d216-4b74-bb40-c5fce52cf633
2025-10-20 03:08:07,972 - DEBUG - Requesting: http://10.0.0.22:8082/project/cec0a583-93ae-4444-8076-32363ef1da5d
2025-10-20 03:08:08,063 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/49f79283-27ab-4cee-87c2-7d0c68f639e8
2025-10-20 03:08:08,074 - DEBUG - =>VN ctest-right_vn-08290234 has no policy to be verified
2025-10-20 03:08:08,074 - DEBUG - Verifying the vn in opserver
2025-10-20 03:08:08,074 - DEBUG - Verifying the default-domain:ctest-TestSvcHC-27824646:ctest-right_vn-08290234 virtual network link through opserver 10.0.0.22
2025-10-20 03:08:08,074 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2025-10-20 03:08:08,126 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcHC-27824646:ctest-right_vn-08290234', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcHC-27824646:ctest-right_vn-08290234?flat'}
2025-10-20 03:08:08,126 - DEBUG - Verifying the default-domain:ctest-TestSvcHC-27824646:ctest-right_vn-08290234 virtual network link through opserver 10.0.0.254
2025-10-20 03:08:08,126 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-10-20 03:08:08,179 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcHC-27824646:ctest-right_vn-08290234', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcHC-27824646:ctest-right_vn-08290234?flat'}
2025-10-20 03:08:08,179 - DEBUG - Verifying the default-domain:ctest-TestSvcHC-27824646:ctest-right_vn-08290234 virtual network link through opserver 10.0.0.27
2025-10-20 03:08:08,179 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-10-20 03:08:08,232 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcHC-27824646:ctest-right_vn-08290234', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcHC-27824646:ctest-right_vn-08290234?flat'}
2025-10-20 03:08:08,233 - INFO - Validated that VN default-domain:ctest-TestSvcHC-27824646:ctest-right_vn-08290234 is found in opserver
2025-10-20 03:08:08,254 - DEBUG - Do not have enough data to verify VN in agent
2025-10-20 03:08:08,265 - DEBUG - VRF ids for VN ctest-right_vn-08290234: {}
2025-10-20 03:08:08,318 - DEBUG - Services list from nova: [, , , ]
2025-10-20 03:08:09,302 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4370-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4370-2)
2025-10-20 03:08:10,452 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4370-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4370-1)
2025-10-20 03:08:10,452 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-96371982']
2025-10-20 03:08:10,738 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-96371982'] created succesfully
2025-10-20 03:08:11,108 - DEBUG - Image by name tiny_nat_fw not found
2025-10-20 03:08:11,109 - DEBUG - Installing image tiny_nat_fw
2025-10-20 03:08:11,109 - 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-10-20 03:08:11,109 - DEBUG - Image build path file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-20 03:08:11,109 - DEBUG - Download image from local file file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-20 03:08:11,109 - DEBUG - Unzip image
2025-10-20 03:08:11,109 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-20 03:08:11,126 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-10-20 03:08:11,127 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-96371982']
2025-10-20 03:08:11,229 - INFO - Service template: ['default-domain', 'ctest-service_template_1-96371982'] deleted successfully.
2025-10-20 03:08:11,229 - INFO - Skip interface_detach for VM ctest-right_vm-56776159 in state BUILD
2025-10-20 03:08:11,229 - INFO - Deleting VM ctest-right_vm-56776159
2025-10-20 03:08:11,259 - ERROR - VM ctest-right_vm-56776159 has failed to come up
2025-10-20 03:08:11,259 - ERROR - Nova failed to get host of the VM
2025-10-20 03:08:11,259 - ERROR - VM ctest-right_vm-56776159 has failed to come up
2025-10-20 03:08:11,259 - ERROR - Nova failed to get host of the VM
2025-10-20 03:08:11,403 - INFO - Skip interface_detach for VM ctest-left_vm-61123130 in state BUILD
2025-10-20 03:08:11,403 - INFO - Deleting VM ctest-left_vm-61123130
2025-10-20 03:08:11,403 - ERROR - VM ctest-left_vm-61123130 has failed to come up
2025-10-20 03:08:11,403 - ERROR - Nova failed to get host of the VM
2025-10-20 03:08:11,403 - ERROR - VM ctest-left_vm-61123130 has failed to come up
2025-10-20 03:08:11,403 - ERROR - Nova failed to get host of the VM
2025-10-20 03:08:11,483 - INFO - Deleting VN ctest-right_vn-08290234
2025-10-20 03:08:11,543 - DEBUG - VN 49f79283-27ab-4cee-87c2-7d0c68f639e8 still in use: Unable to complete operation on network 49f79283-27ab-4cee-87c2-7d0c68f639e8. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-f126e714-476d-4178-bb06-437d3cc0a4f3']
2025-10-20 03:08:11,544 - WARNING - Deleting VN ctest-right_vn-08290234 failed..Will retry
2025-10-20 03:08:13,730 - DEBUG - Response for deleting network ()
2025-10-20 03:08:13,730 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/6e105f6a-2641-41bd-99a7-be8ddb93013c
2025-10-20 03:08:13,736 - DEBUG - Response Code: 404
2025-10-20 03:08:13,736 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-20 03:08:13,741 - DEBUG - Requesting: http://10.0.0.22:8082/domain/9567af48-d216-4b74-bb40-c5fce52cf633
2025-10-20 03:08:13,755 - DEBUG - Requesting: http://10.0.0.22:8082/project/cec0a583-93ae-4444-8076-32363ef1da5d
2025-10-20 03:08:13,835 - INFO - Validated that VN ctest-right_vn-08290234 is not found in API Server
2025-10-20 03:08:13,847 - DEBUG - VN ctest-right_vn-08290234 is not present in Agent 10.0.0.131
2025-10-20 03:08:13,858 - DEBUG - VN ctest-right_vn-08290234 is not present in Agent 10.0.0.19
2025-10-20 03:08:13,858 - INFO - Validated that VN ctest-right_vn-08290234 is not in any agent
2025-10-20 03:08:13,884 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-08290234 info
2025-10-20 03:08:13,885 - INFO - Deleting VN ctest-left_vn-09734864
2025-10-20 03:08:14,032 - DEBUG - Response for deleting network ()
2025-10-20 03:08:14,032 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/f6133ab8-1643-4a2c-992c-08cf078e0171
2025-10-20 03:08:14,038 - DEBUG - Response Code: 404
2025-10-20 03:08:14,038 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-20 03:08:14,055 - DEBUG - Requesting: http://10.0.0.22:8082/domain/9567af48-d216-4b74-bb40-c5fce52cf633
2025-10-20 03:08:14,114 - DEBUG - Requesting: http://10.0.0.22:8082/project/cec0a583-93ae-4444-8076-32363ef1da5d
2025-10-20 03:08:14,199 - INFO - Validated that VN ctest-left_vn-09734864 is not found in API Server
2025-10-20 03:08:14,211 - DEBUG - VN ctest-left_vn-09734864 is not present in Agent 10.0.0.131
2025-10-20 03:08:14,221 - DEBUG - VN ctest-left_vn-09734864 is not present in Agent 10.0.0.19
2025-10-20 03:08:14,221 - INFO - Validated that VN ctest-left_vn-09734864 is not in any agent
2025-10-20 03:08:14,250 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-09734864 info
2025-10-20 03:08:14,251 - INFO - Deleting VN ctest-mgmt_vn-77532855
2025-10-20 03:08:14,405 - DEBUG - Response for deleting network ()
2025-10-20 03:08:14,405 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/d6dacfd8-a3d8-4735-8437-e6d61af4888e
2025-10-20 03:08:14,409 - DEBUG - Response Code: 404
2025-10-20 03:08:14,410 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-20 03:08:14,415 - DEBUG - Requesting: http://10.0.0.22:8082/domain/9567af48-d216-4b74-bb40-c5fce52cf633
2025-10-20 03:08:14,431 - DEBUG - Requesting: http://10.0.0.22:8082/project/cec0a583-93ae-4444-8076-32363ef1da5d
2025-10-20 03:08:14,516 - INFO - Validated that VN ctest-mgmt_vn-77532855 is not found in API Server
2025-10-20 03:08:14,528 - DEBUG - VN ctest-mgmt_vn-77532855 is not present in Agent 10.0.0.131
2025-10-20 03:08:14,537 - DEBUG - VN ctest-mgmt_vn-77532855 is not present in Agent 10.0.0.19
2025-10-20 03:08:14,537 - INFO - Validated that VN ctest-mgmt_vn-77532855 is not in any agent
2025-10-20 03:08:14,568 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-77532855 info
2025-10-20 03:08:15,829 - DEBUG - No XMPP flaps were noticed during the test
2025-10-20 03:08:15,829 - INFO -
2025-10-20 03:08:15,830 - INFO - END TEST : test_svc_hc_basic : SKIPPED[0:00:14]
2025-10-20 03:08:15,830 - INFO - --------------------------------------------------------------------------------
2025-10-20 03:08:15,833 - INFO - ================================================================================
2025-10-20 03:08:15,833 - INFO - STARTING TEST : test_svc_trans_segment_right_hc_fail_svm
2025-10-20 03:08:17,106 - DEBUG - Nothing to compare xmpp stats {'10.0.0.131': {'10.20.0.25': '0', '10.20.0.193': '0'}, '10.0.0.19': {'10.20.0.193': '0', '10.20.0.14': '0'}} with
2025-10-20 03:08:17,106 - INFO - Initial checks done. Running the testcase now
2025-10-20 03:08:17,106 - INFO -
2025-10-20 03:08:17,362 - DEBUG - Response for create_network : {'network': {'id': '9d8eeeb5-b7cc-4da5-8af8-e198d4350395', 'name': 'ctest-mgmt_vn-85169874', 'tenant_id': 'cec0a58393ae4444807632363ef1da5d', 'project_id': 'cec0a58393ae4444807632363ef1da5d', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcHC-27824646', 'ctest-mgmt_vn-85169874'], 'port_security_enabled': True, 'description': ''}}
2025-10-20 03:08:17,571 - DEBUG - Response for create_subnet : {'subnet': {'id': '1e84ce0f-9614-4f3d-81be-488d1b30eb9d', 'name': '', 'tenant_id': 'cec0a58393ae4444807632363ef1da5d', 'network_id': '9d8eeeb5-b7cc-4da5-8af8-e198d4350395', 'ip_version': 4, 'cidr': '61.84.206.0/26', 'allocation_pools': [{'start': '61.84.206.2', 'end': '61.84.206.62'}], 'gateway_ip': '61.84.206.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '61.84.206.2', 'tags': [], 'project_id': 'cec0a58393ae4444807632363ef1da5d'}}
2025-10-20 03:08:17,593 - INFO - Created VN ctest-mgmt_vn-85169874
2025-10-20 03:08:17,603 - DEBUG - VN ctest-mgmt_vn-85169874 UUID is 9d8eeeb5-b7cc-4da5-8af8-e198d4350395
2025-10-20 03:08:17,670 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-20 03:08:17,675 - DEBUG - Requesting: http://10.0.0.22:8082/domain/9567af48-d216-4b74-bb40-c5fce52cf633
2025-10-20 03:08:17,685 - DEBUG - Requesting: http://10.0.0.22:8082/project/cec0a583-93ae-4444-8076-32363ef1da5d
2025-10-20 03:08:17,775 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/9d8eeeb5-b7cc-4da5-8af8-e198d4350395
2025-10-20 03:08:17,786 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/9d8eeeb5-b7cc-4da5-8af8-e198d4350395
2025-10-20 03:08:17,794 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/f3286d56-a063-41cc-8b1d-0f8d3be63fbf
2025-10-20 03:08:17,802 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/f3286d56-a063-41cc-8b1d-0f8d3be63fbf
2025-10-20 03:08:17,809 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/c03d5cca-cfa1-4172-8eba-5185886c2568
2025-10-20 03:08:17,815 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-20 03:08:17,815 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/9d8eeeb5-b7cc-4da5-8af8-e198d4350395
2025-10-20 03:08:17,823 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/f3286d56-a063-41cc-8b1d-0f8d3be63fbf
2025-10-20 03:08:17,837 - INFO - Verified VN network id 11 for VN 9d8eeeb5-b7cc-4da5-8af8-e198d4350395
2025-10-20 03:08:17,837 - INFO - Verifications in API Server for VN ctest-mgmt_vn-85169874 passed
2025-10-20 03:08:17,837 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/9d8eeeb5-b7cc-4da5-8af8-e198d4350395
2025-10-20 03:08:17,847 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/f3286d56-a063-41cc-8b1d-0f8d3be63fbf
2025-10-20 03:08:17,854 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/f3286d56-a063-41cc-8b1d-0f8d3be63fbf
2025-10-20 03:08:17,862 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/c03d5cca-cfa1-4172-8eba-5185886c2568
2025-10-20 03:08:17,873 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcHC-27824646:ctest-mgmt_vn-85169874', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcHC-27824646', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11353274174887710117', 'uuid-lslong': '10014001817969820565'}, 'enable': 'true', 'created': '2025-10-20T03:08:17', 'last-modified': '2025-10-20T03:08:17', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.353921'}
2025-10-20 03:08:17,878 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-20 03:08:17,884 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcHC-27824646:ctest-mgmt_vn-85169874', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcHC-27824646', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11353274174887710117', 'uuid-lslong': '10014001817969820565'}, 'enable': 'true', 'created': '2025-10-20T03:08:17', 'last-modified': '2025-10-20T03:08:17', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.369928'}
2025-10-20 03:08:17,890 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-20 03:08:17,896 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcHC-27824646:ctest-mgmt_vn-85169874', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcHC-27824646', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11353274174887710117', 'uuid-lslong': '10014001817969820565'}, 'enable': 'true', 'created': '2025-10-20T03:08:17', 'last-modified': '2025-10-20T03:08:17', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.382353'}
2025-10-20 03:08:17,902 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-20 03:08:17,902 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-85169874 passed
2025-10-20 03:08:17,902 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-85169874 in API_Server ======
2025-10-20 03:08:17,902 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-20 03:08:17,908 - DEBUG - Requesting: http://10.0.0.22:8082/domain/9567af48-d216-4b74-bb40-c5fce52cf633
2025-10-20 03:08:17,918 - DEBUG - Requesting: http://10.0.0.22:8082/project/cec0a583-93ae-4444-8076-32363ef1da5d
2025-10-20 03:08:18,011 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/9d8eeeb5-b7cc-4da5-8af8-e198d4350395
2025-10-20 03:08:18,021 - DEBUG - =>VN ctest-mgmt_vn-85169874 has no policy to be verified
2025-10-20 03:08:18,021 - DEBUG - Verifying the vn in opserver
2025-10-20 03:08:18,021 - DEBUG - Verifying the default-domain:ctest-TestSvcHC-27824646:ctest-mgmt_vn-85169874 virtual network link through opserver 10.0.0.22
2025-10-20 03:08:18,021 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2025-10-20 03:08:18,032 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcHC-27824646:ctest-mgmt_vn-85169874', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcHC-27824646:ctest-mgmt_vn-85169874?flat'}
2025-10-20 03:08:18,032 - DEBUG - Verifying the default-domain:ctest-TestSvcHC-27824646:ctest-mgmt_vn-85169874 virtual network link through opserver 10.0.0.254
2025-10-20 03:08:18,032 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-10-20 03:08:18,045 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcHC-27824646:ctest-mgmt_vn-85169874', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcHC-27824646:ctest-mgmt_vn-85169874?flat'}
2025-10-20 03:08:18,045 - DEBUG - Verifying the default-domain:ctest-TestSvcHC-27824646:ctest-mgmt_vn-85169874 virtual network link through opserver 10.0.0.27
2025-10-20 03:08:18,045 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-10-20 03:08:18,059 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcHC-27824646:ctest-mgmt_vn-85169874', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcHC-27824646:ctest-mgmt_vn-85169874?flat'}
2025-10-20 03:08:18,059 - INFO - Validated that VN default-domain:ctest-TestSvcHC-27824646:ctest-mgmt_vn-85169874 is found in opserver
2025-10-20 03:08:18,081 - DEBUG - Do not have enough data to verify VN in agent
2025-10-20 03:08:18,092 - DEBUG - VRF ids for VN ctest-mgmt_vn-85169874: {}
2025-10-20 03:08:18,418 - DEBUG - Response for create_network : {'network': {'id': 'ebd46891-bdaa-40dc-80fb-2d2d7ed3b1ae', 'name': 'ctest-left_vn-42102085', 'tenant_id': 'cec0a58393ae4444807632363ef1da5d', 'project_id': 'cec0a58393ae4444807632363ef1da5d', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcHC-27824646', 'ctest-left_vn-42102085'], 'port_security_enabled': True, 'description': ''}}
2025-10-20 03:08:18,636 - DEBUG - Response for create_subnet : {'subnet': {'id': '3692deed-67d8-41e0-bbc4-e93bb7db46a1', 'name': '', 'tenant_id': 'cec0a58393ae4444807632363ef1da5d', 'network_id': 'ebd46891-bdaa-40dc-80fb-2d2d7ed3b1ae', 'ip_version': 4, 'cidr': '31.160.25.192/26', 'allocation_pools': [{'start': '31.160.25.194', 'end': '31.160.25.254'}], 'gateway_ip': '31.160.25.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '31.160.25.194', 'tags': [], 'project_id': 'cec0a58393ae4444807632363ef1da5d'}}
2025-10-20 03:08:18,657 - INFO - Created VN ctest-left_vn-42102085
2025-10-20 03:08:18,669 - DEBUG - VN ctest-left_vn-42102085 UUID is ebd46891-bdaa-40dc-80fb-2d2d7ed3b1ae
2025-10-20 03:08:18,732 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-20 03:08:18,739 - DEBUG - Requesting: http://10.0.0.22:8082/domain/9567af48-d216-4b74-bb40-c5fce52cf633
2025-10-20 03:08:18,750 - DEBUG - Requesting: http://10.0.0.22:8082/project/cec0a583-93ae-4444-8076-32363ef1da5d
2025-10-20 03:08:18,841 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/ebd46891-bdaa-40dc-80fb-2d2d7ed3b1ae
2025-10-20 03:08:18,852 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/ebd46891-bdaa-40dc-80fb-2d2d7ed3b1ae
2025-10-20 03:08:18,860 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/44a20836-5b5e-48f3-a5e4-1c9ef6f05f77
2025-10-20 03:08:18,867 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/44a20836-5b5e-48f3-a5e4-1c9ef6f05f77
2025-10-20 03:08:18,875 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/6bb810ed-5352-4cff-a58a-43640d8bf0fa
2025-10-20 03:08:18,881 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-20 03:08:18,881 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/ebd46891-bdaa-40dc-80fb-2d2d7ed3b1ae
2025-10-20 03:08:18,889 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/44a20836-5b5e-48f3-a5e4-1c9ef6f05f77
2025-10-20 03:08:18,905 - INFO - Verified VN network id 12 for VN ebd46891-bdaa-40dc-80fb-2d2d7ed3b1ae
2025-10-20 03:08:18,905 - INFO - Verifications in API Server for VN ctest-left_vn-42102085 passed
2025-10-20 03:08:18,905 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/ebd46891-bdaa-40dc-80fb-2d2d7ed3b1ae
2025-10-20 03:08:18,914 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/44a20836-5b5e-48f3-a5e4-1c9ef6f05f77
2025-10-20 03:08:18,920 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/44a20836-5b5e-48f3-a5e4-1c9ef6f05f77
2025-10-20 03:08:18,927 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/6bb810ed-5352-4cff-a58a-43640d8bf0fa
2025-10-20 03:08:18,937 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcHC-27824646:ctest-left_vn-42102085', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcHC-27824646', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '16993322269137322204', 'uuid-lslong': '9294071929433731502'}, 'enable': 'true', 'created': '2025-10-20T03:08:18', 'last-modified': '2025-10-20T03:08:18', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.346900'}
2025-10-20 03:08:18,942 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-20 03:08:18,947 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcHC-27824646:ctest-left_vn-42102085', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcHC-27824646', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '16993322269137322204', 'uuid-lslong': '9294071929433731502'}, 'enable': 'true', 'created': '2025-10-20T03:08:18', 'last-modified': '2025-10-20T03:08:18', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.364739'}
2025-10-20 03:08:18,954 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-20 03:08:18,959 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcHC-27824646:ctest-left_vn-42102085', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcHC-27824646', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '16993322269137322204', 'uuid-lslong': '9294071929433731502'}, 'enable': 'true', 'created': '2025-10-20T03:08:18', 'last-modified': '2025-10-20T03:08:18', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.378774'}
2025-10-20 03:08:18,965 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-20 03:08:18,965 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-42102085 passed
2025-10-20 03:08:18,965 - DEBUG - ====Verifying policy data for ctest-left_vn-42102085 in API_Server ======
2025-10-20 03:08:18,965 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-20 03:08:18,971 - DEBUG - Requesting: http://10.0.0.22:8082/domain/9567af48-d216-4b74-bb40-c5fce52cf633
2025-10-20 03:08:18,983 - DEBUG - Requesting: http://10.0.0.22:8082/project/cec0a583-93ae-4444-8076-32363ef1da5d
2025-10-20 03:08:19,076 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/ebd46891-bdaa-40dc-80fb-2d2d7ed3b1ae
2025-10-20 03:08:19,085 - DEBUG - =>VN ctest-left_vn-42102085 has no policy to be verified
2025-10-20 03:08:19,085 - DEBUG - Verifying the vn in opserver
2025-10-20 03:08:19,085 - DEBUG - Verifying the default-domain:ctest-TestSvcHC-27824646:ctest-left_vn-42102085 virtual network link through opserver 10.0.0.22
2025-10-20 03:08:19,085 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2025-10-20 03:08:19,097 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcHC-27824646:ctest-left_vn-42102085', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcHC-27824646:ctest-left_vn-42102085?flat'}
2025-10-20 03:08:19,097 - DEBUG - Verifying the default-domain:ctest-TestSvcHC-27824646:ctest-left_vn-42102085 virtual network link through opserver 10.0.0.254
2025-10-20 03:08:19,097 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-10-20 03:08:19,109 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcHC-27824646:ctest-left_vn-42102085', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcHC-27824646:ctest-left_vn-42102085?flat'}
2025-10-20 03:08:19,109 - DEBUG - Verifying the default-domain:ctest-TestSvcHC-27824646:ctest-left_vn-42102085 virtual network link through opserver 10.0.0.27
2025-10-20 03:08:19,109 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-10-20 03:08:19,121 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcHC-27824646:ctest-left_vn-42102085', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcHC-27824646:ctest-left_vn-42102085?flat'}
2025-10-20 03:08:19,121 - INFO - Validated that VN default-domain:ctest-TestSvcHC-27824646:ctest-left_vn-42102085 is found in opserver
2025-10-20 03:08:19,140 - DEBUG - Do not have enough data to verify VN in agent
2025-10-20 03:08:19,149 - DEBUG - VRF ids for VN ctest-left_vn-42102085: {}
2025-10-20 03:08:19,473 - DEBUG - Response for create_network : {'network': {'id': '416751b7-bd3f-4eea-b942-e11961efc667', 'name': 'ctest-right_vn-59936896', 'tenant_id': 'cec0a58393ae4444807632363ef1da5d', 'project_id': 'cec0a58393ae4444807632363ef1da5d', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcHC-27824646', 'ctest-right_vn-59936896'], 'port_security_enabled': True, 'description': ''}}
2025-10-20 03:08:19,741 - DEBUG - Response for create_subnet : {'subnet': {'id': 'd117a391-65b5-4c18-9901-a12f11ae81bc', 'name': '', 'tenant_id': 'cec0a58393ae4444807632363ef1da5d', 'network_id': '416751b7-bd3f-4eea-b942-e11961efc667', 'ip_version': 4, 'cidr': '147.111.69.192/26', 'allocation_pools': [{'start': '147.111.69.194', 'end': '147.111.69.254'}], 'gateway_ip': '147.111.69.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '147.111.69.194', 'tags': [], 'project_id': 'cec0a58393ae4444807632363ef1da5d'}}
2025-10-20 03:08:19,769 - INFO - Created VN ctest-right_vn-59936896
2025-10-20 03:08:19,780 - DEBUG - VN ctest-right_vn-59936896 UUID is 416751b7-bd3f-4eea-b942-e11961efc667
2025-10-20 03:08:19,871 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-20 03:08:19,877 - DEBUG - Requesting: http://10.0.0.22:8082/domain/9567af48-d216-4b74-bb40-c5fce52cf633
2025-10-20 03:08:19,887 - DEBUG - Requesting: http://10.0.0.22:8082/project/cec0a583-93ae-4444-8076-32363ef1da5d
2025-10-20 03:08:19,991 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/416751b7-bd3f-4eea-b942-e11961efc667
2025-10-20 03:08:20,002 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/416751b7-bd3f-4eea-b942-e11961efc667
2025-10-20 03:08:20,014 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/66d76321-fbf8-46c5-80bb-aa1b8987a71d
2025-10-20 03:08:20,026 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/66d76321-fbf8-46c5-80bb-aa1b8987a71d
2025-10-20 03:08:20,036 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/487a09c2-0ee5-478c-a87e-80ffec500b50
2025-10-20 03:08:20,044 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-20 03:08:20,044 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/416751b7-bd3f-4eea-b942-e11961efc667
2025-10-20 03:08:20,053 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/66d76321-fbf8-46c5-80bb-aa1b8987a71d
2025-10-20 03:08:20,074 - INFO - Verified VN network id 13 for VN 416751b7-bd3f-4eea-b942-e11961efc667
2025-10-20 03:08:20,074 - INFO - Verifications in API Server for VN ctest-right_vn-59936896 passed
2025-10-20 03:08:20,074 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/416751b7-bd3f-4eea-b942-e11961efc667
2025-10-20 03:08:20,085 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/66d76321-fbf8-46c5-80bb-aa1b8987a71d
2025-10-20 03:08:20,094 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/66d76321-fbf8-46c5-80bb-aa1b8987a71d
2025-10-20 03:08:20,105 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/487a09c2-0ee5-478c-a87e-80ffec500b50
2025-10-20 03:08:20,115 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcHC-27824646:ctest-right_vn-59936896', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcHC-27824646', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4712825384662421226', 'uuid-lslong': '13349479744613107303'}, 'enable': 'true', 'created': '2025-10-20T03:08:19', 'last-modified': '2025-10-20T03:08:19', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.453141'}
2025-10-20 03:08:20,122 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-20 03:08:20,129 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcHC-27824646:ctest-right_vn-59936896', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcHC-27824646', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4712825384662421226', 'uuid-lslong': '13349479744613107303'}, 'enable': 'true', 'created': '2025-10-20T03:08:19', 'last-modified': '2025-10-20T03:08:19', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.463507'}
2025-10-20 03:08:20,138 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-20 03:08:20,143 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcHC-27824646:ctest-right_vn-59936896', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcHC-27824646', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4712825384662421226', 'uuid-lslong': '13349479744613107303'}, 'enable': 'true', 'created': '2025-10-20T03:08:19', 'last-modified': '2025-10-20T03:08:19', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.479661'}
2025-10-20 03:08:20,148 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-20 03:08:20,149 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-59936896 passed
2025-10-20 03:08:20,149 - DEBUG - ====Verifying policy data for ctest-right_vn-59936896 in API_Server ======
2025-10-20 03:08:20,149 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-20 03:08:20,154 - DEBUG - Requesting: http://10.0.0.22:8082/domain/9567af48-d216-4b74-bb40-c5fce52cf633
2025-10-20 03:08:20,165 - DEBUG - Requesting: http://10.0.0.22:8082/project/cec0a583-93ae-4444-8076-32363ef1da5d
2025-10-20 03:08:20,250 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/416751b7-bd3f-4eea-b942-e11961efc667
2025-10-20 03:08:20,261 - DEBUG - =>VN ctest-right_vn-59936896 has no policy to be verified
2025-10-20 03:08:20,261 - DEBUG - Verifying the vn in opserver
2025-10-20 03:08:20,261 - DEBUG - Verifying the default-domain:ctest-TestSvcHC-27824646:ctest-right_vn-59936896 virtual network link through opserver 10.0.0.22
2025-10-20 03:08:20,261 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2025-10-20 03:08:20,271 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcHC-27824646:ctest-right_vn-59936896', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcHC-27824646:ctest-right_vn-59936896?flat'}
2025-10-20 03:08:20,271 - DEBUG - Verifying the default-domain:ctest-TestSvcHC-27824646:ctest-right_vn-59936896 virtual network link through opserver 10.0.0.254
2025-10-20 03:08:20,271 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-10-20 03:08:20,284 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcHC-27824646:ctest-right_vn-59936896', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcHC-27824646:ctest-right_vn-59936896?flat'}
2025-10-20 03:08:20,284 - DEBUG - Verifying the default-domain:ctest-TestSvcHC-27824646:ctest-right_vn-59936896 virtual network link through opserver 10.0.0.27
2025-10-20 03:08:20,284 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-10-20 03:08:20,295 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcHC-27824646:ctest-right_vn-59936896', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcHC-27824646:ctest-right_vn-59936896?flat'}
2025-10-20 03:08:20,295 - INFO - Validated that VN default-domain:ctest-TestSvcHC-27824646:ctest-right_vn-59936896 is found in opserver
2025-10-20 03:08:20,314 - DEBUG - Do not have enough data to verify VN in agent
2025-10-20 03:08:20,322 - DEBUG - VRF ids for VN ctest-right_vn-59936896: {}
2025-10-20 03:08:20,615 - DEBUG - Response for create_network : {'network': {'id': '9ac8b5b4-d035-483f-a711-ac4bc59488c1', 'name': 'ctest-trans_left_vn-77370516', 'tenant_id': 'cec0a58393ae4444807632363ef1da5d', 'project_id': 'cec0a58393ae4444807632363ef1da5d', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcHC-27824646', 'ctest-trans_left_vn-77370516'], 'port_security_enabled': True, 'description': ''}}
2025-10-20 03:08:20,842 - DEBUG - Response for create_subnet : {'subnet': {'id': 'a809abd1-48ac-45fc-9073-d7211a67ad69', 'name': '', 'tenant_id': 'cec0a58393ae4444807632363ef1da5d', 'network_id': '9ac8b5b4-d035-483f-a711-ac4bc59488c1', 'ip_version': 4, 'cidr': '142.126.44.128/26', 'allocation_pools': [{'start': '142.126.44.130', 'end': '142.126.44.190'}], 'gateway_ip': '142.126.44.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '142.126.44.130', 'tags': [], 'project_id': 'cec0a58393ae4444807632363ef1da5d'}}
2025-10-20 03:08:20,866 - INFO - Created VN ctest-trans_left_vn-77370516
2025-10-20 03:08:20,876 - DEBUG - VN ctest-trans_left_vn-77370516 UUID is 9ac8b5b4-d035-483f-a711-ac4bc59488c1
2025-10-20 03:08:20,941 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-20 03:08:20,947 - DEBUG - Requesting: http://10.0.0.22:8082/domain/9567af48-d216-4b74-bb40-c5fce52cf633
2025-10-20 03:08:20,957 - DEBUG - Requesting: http://10.0.0.22:8082/project/cec0a583-93ae-4444-8076-32363ef1da5d
2025-10-20 03:08:21,048 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/9ac8b5b4-d035-483f-a711-ac4bc59488c1
2025-10-20 03:08:21,060 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/9ac8b5b4-d035-483f-a711-ac4bc59488c1
2025-10-20 03:08:21,068 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/184f41ec-2b77-4c4b-8882-7650bec83656
2025-10-20 03:08:21,076 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/184f41ec-2b77-4c4b-8882-7650bec83656
2025-10-20 03:08:21,085 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/1c250b1a-e67b-4a16-b1da-01134ec297cb
2025-10-20 03:08:21,091 - DEBUG - Route Targets: ['target:64512:8000007']
2025-10-20 03:08:21,091 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/9ac8b5b4-d035-483f-a711-ac4bc59488c1
2025-10-20 03:08:21,101 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/184f41ec-2b77-4c4b-8882-7650bec83656
2025-10-20 03:08:21,115 - INFO - Verified VN network id 14 for VN 9ac8b5b4-d035-483f-a711-ac4bc59488c1
2025-10-20 03:08:21,115 - INFO - Verifications in API Server for VN ctest-trans_left_vn-77370516 passed
2025-10-20 03:08:21,115 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/9ac8b5b4-d035-483f-a711-ac4bc59488c1
2025-10-20 03:08:21,125 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/184f41ec-2b77-4c4b-8882-7650bec83656
2025-10-20 03:08:21,132 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/184f41ec-2b77-4c4b-8882-7650bec83656
2025-10-20 03:08:21,138 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/1c250b1a-e67b-4a16-b1da-01134ec297cb
2025-10-20 03:08:21,149 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcHC-27824646:ctest-trans_left_vn-77370516', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcHC-27824646', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11153364265374926911', 'uuid-lslong': '12038592720375417025'}, 'enable': 'true', 'created': '2025-10-20T03:08:20', 'last-modified': '2025-10-20T03:08:20', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.351215'}
2025-10-20 03:08:21,154 - DEBUG - Route Targets: ['target:64512:8000007']
2025-10-20 03:08:21,160 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcHC-27824646:ctest-trans_left_vn-77370516', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcHC-27824646', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11153364265374926911', 'uuid-lslong': '12038592720375417025'}, 'enable': 'true', 'created': '2025-10-20T03:08:20', 'last-modified': '2025-10-20T03:08:20', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.362181'}
2025-10-20 03:08:21,165 - DEBUG - Route Targets: ['target:64512:8000007']
2025-10-20 03:08:21,170 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcHC-27824646:ctest-trans_left_vn-77370516', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcHC-27824646', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11153364265374926911', 'uuid-lslong': '12038592720375417025'}, 'enable': 'true', 'created': '2025-10-20T03:08:20', 'last-modified': '2025-10-20T03:08:20', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.372287'}
2025-10-20 03:08:21,175 - DEBUG - Route Targets: ['target:64512:8000007']
2025-10-20 03:08:21,175 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-trans_left_vn-77370516 passed
2025-10-20 03:08:21,175 - DEBUG - ====Verifying policy data for ctest-trans_left_vn-77370516 in API_Server ======
2025-10-20 03:08:21,175 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-20 03:08:21,182 - DEBUG - Requesting: http://10.0.0.22:8082/domain/9567af48-d216-4b74-bb40-c5fce52cf633
2025-10-20 03:08:21,195 - DEBUG - Requesting: http://10.0.0.22:8082/project/cec0a583-93ae-4444-8076-32363ef1da5d
2025-10-20 03:08:21,280 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/9ac8b5b4-d035-483f-a711-ac4bc59488c1
2025-10-20 03:08:21,290 - DEBUG - =>VN ctest-trans_left_vn-77370516 has no policy to be verified
2025-10-20 03:08:21,290 - DEBUG - Verifying the vn in opserver
2025-10-20 03:08:21,290 - DEBUG - Verifying the default-domain:ctest-TestSvcHC-27824646:ctest-trans_left_vn-77370516 virtual network link through opserver 10.0.0.22
2025-10-20 03:08:21,290 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2025-10-20 03:08:21,305 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcHC-27824646:ctest-trans_left_vn-77370516', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcHC-27824646:ctest-trans_left_vn-77370516?flat'}
2025-10-20 03:08:21,305 - DEBUG - Verifying the default-domain:ctest-TestSvcHC-27824646:ctest-trans_left_vn-77370516 virtual network link through opserver 10.0.0.254
2025-10-20 03:08:21,305 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-10-20 03:08:21,318 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcHC-27824646:ctest-trans_left_vn-77370516', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcHC-27824646:ctest-trans_left_vn-77370516?flat'}
2025-10-20 03:08:21,318 - DEBUG - Verifying the default-domain:ctest-TestSvcHC-27824646:ctest-trans_left_vn-77370516 virtual network link through opserver 10.0.0.27
2025-10-20 03:08:21,318 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-10-20 03:08:21,333 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcHC-27824646:ctest-trans_left_vn-77370516', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcHC-27824646:ctest-trans_left_vn-77370516?flat'}
2025-10-20 03:08:21,333 - INFO - Validated that VN default-domain:ctest-TestSvcHC-27824646:ctest-trans_left_vn-77370516 is found in opserver
2025-10-20 03:08:21,360 - DEBUG - Do not have enough data to verify VN in agent
2025-10-20 03:08:21,371 - DEBUG - VRF ids for VN ctest-trans_left_vn-77370516: {}
2025-10-20 03:08:21,638 - DEBUG - Response for create_network : {'network': {'id': '57f82c3d-7636-4cae-bb91-aea5d45b7147', 'name': 'ctest-trans_right_vn-71000495', 'tenant_id': 'cec0a58393ae4444807632363ef1da5d', 'project_id': 'cec0a58393ae4444807632363ef1da5d', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcHC-27824646', 'ctest-trans_right_vn-71000495'], 'port_security_enabled': True, 'description': ''}}
2025-10-20 03:08:21,877 - DEBUG - Response for create_subnet : {'subnet': {'id': '09e9f7da-7865-4ebb-85f5-9a8ed0cdcbec', 'name': '', 'tenant_id': 'cec0a58393ae4444807632363ef1da5d', 'network_id': '57f82c3d-7636-4cae-bb91-aea5d45b7147', 'ip_version': 4, 'cidr': '74.173.89.0/26', 'allocation_pools': [{'start': '74.173.89.2', 'end': '74.173.89.62'}], 'gateway_ip': '74.173.89.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '74.173.89.2', 'tags': [], 'project_id': 'cec0a58393ae4444807632363ef1da5d'}}
2025-10-20 03:08:21,909 - INFO - Created VN ctest-trans_right_vn-71000495
2025-10-20 03:08:21,922 - DEBUG - VN ctest-trans_right_vn-71000495 UUID is 57f82c3d-7636-4cae-bb91-aea5d45b7147
2025-10-20 03:08:22,000 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-20 03:08:22,006 - DEBUG - Requesting: http://10.0.0.22:8082/domain/9567af48-d216-4b74-bb40-c5fce52cf633
2025-10-20 03:08:22,022 - DEBUG - Requesting: http://10.0.0.22:8082/project/cec0a583-93ae-4444-8076-32363ef1da5d
2025-10-20 03:08:22,112 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/57f82c3d-7636-4cae-bb91-aea5d45b7147
2025-10-20 03:08:22,126 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/57f82c3d-7636-4cae-bb91-aea5d45b7147
2025-10-20 03:08:22,137 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/3798fde5-9cd0-4d3a-93bc-a7a181cf61b7
2025-10-20 03:08:22,143 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/3798fde5-9cd0-4d3a-93bc-a7a181cf61b7
2025-10-20 03:08:22,156 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/48e4e145-d4cf-4f1c-94b2-368f192027e1
2025-10-20 03:08:22,165 - DEBUG - Route Targets: ['target:64512:8000008']
2025-10-20 03:08:22,165 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/57f82c3d-7636-4cae-bb91-aea5d45b7147
2025-10-20 03:08:22,173 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/3798fde5-9cd0-4d3a-93bc-a7a181cf61b7
2025-10-20 03:08:22,190 - INFO - Verified VN network id 15 for VN 57f82c3d-7636-4cae-bb91-aea5d45b7147
2025-10-20 03:08:22,190 - INFO - Verifications in API Server for VN ctest-trans_right_vn-71000495 passed
2025-10-20 03:08:22,191 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/57f82c3d-7636-4cae-bb91-aea5d45b7147
2025-10-20 03:08:22,201 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/3798fde5-9cd0-4d3a-93bc-a7a181cf61b7
2025-10-20 03:08:22,209 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/3798fde5-9cd0-4d3a-93bc-a7a181cf61b7
2025-10-20 03:08:22,217 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/48e4e145-d4cf-4f1c-94b2-368f192027e1
2025-10-20 03:08:22,236 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcHC-27824646:ctest-trans_right_vn-71000495', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcHC-27824646', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6338865118011870382', 'uuid-lslong': '13515775983971168583'}, 'enable': 'true', 'created': '2025-10-20T03:08:21', 'last-modified': '2025-10-20T03:08:21', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.414098'}
2025-10-20 03:08:22,248 - DEBUG - Route Targets: ['target:64512:8000008']
2025-10-20 03:08:22,254 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcHC-27824646:ctest-trans_right_vn-71000495', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcHC-27824646', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6338865118011870382', 'uuid-lslong': '13515775983971168583'}, 'enable': 'true', 'created': '2025-10-20T03:08:21', 'last-modified': '2025-10-20T03:08:21', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.440645'}
2025-10-20 03:08:22,260 - DEBUG - Route Targets: ['target:64512:8000008']
2025-10-20 03:08:22,264 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcHC-27824646:ctest-trans_right_vn-71000495', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcHC-27824646', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6338865118011870382', 'uuid-lslong': '13515775983971168583'}, 'enable': 'true', 'created': '2025-10-20T03:08:21', 'last-modified': '2025-10-20T03:08:21', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.452700'}
2025-10-20 03:08:22,270 - DEBUG - Route Targets: ['target:64512:8000008']
2025-10-20 03:08:22,270 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-trans_right_vn-71000495 passed
2025-10-20 03:08:22,270 - DEBUG - ====Verifying policy data for ctest-trans_right_vn-71000495 in API_Server ======
2025-10-20 03:08:22,270 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-20 03:08:22,276 - DEBUG - Requesting: http://10.0.0.22:8082/domain/9567af48-d216-4b74-bb40-c5fce52cf633
2025-10-20 03:08:22,287 - DEBUG - Requesting: http://10.0.0.22:8082/project/cec0a583-93ae-4444-8076-32363ef1da5d
2025-10-20 03:08:22,382 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/57f82c3d-7636-4cae-bb91-aea5d45b7147
2025-10-20 03:08:22,391 - DEBUG - =>VN ctest-trans_right_vn-71000495 has no policy to be verified
2025-10-20 03:08:22,391 - DEBUG - Verifying the vn in opserver
2025-10-20 03:08:22,391 - DEBUG - Verifying the default-domain:ctest-TestSvcHC-27824646:ctest-trans_right_vn-71000495 virtual network link through opserver 10.0.0.22
2025-10-20 03:08:22,391 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2025-10-20 03:08:22,403 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcHC-27824646:ctest-trans_right_vn-71000495', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcHC-27824646:ctest-trans_right_vn-71000495?flat'}
2025-10-20 03:08:22,403 - DEBUG - Verifying the default-domain:ctest-TestSvcHC-27824646:ctest-trans_right_vn-71000495 virtual network link through opserver 10.0.0.254
2025-10-20 03:08:22,403 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-10-20 03:08:22,419 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcHC-27824646:ctest-trans_right_vn-71000495', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcHC-27824646:ctest-trans_right_vn-71000495?flat'}
2025-10-20 03:08:22,419 - DEBUG - Verifying the default-domain:ctest-TestSvcHC-27824646:ctest-trans_right_vn-71000495 virtual network link through opserver 10.0.0.27
2025-10-20 03:08:22,419 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-10-20 03:08:22,431 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcHC-27824646:ctest-trans_right_vn-71000495', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcHC-27824646:ctest-trans_right_vn-71000495?flat'}
2025-10-20 03:08:22,432 - INFO - Validated that VN default-domain:ctest-TestSvcHC-27824646:ctest-trans_right_vn-71000495 is found in opserver
2025-10-20 03:08:22,451 - DEBUG - Do not have enough data to verify VN in agent
2025-10-20 03:08:22,462 - DEBUG - VRF ids for VN ctest-trans_right_vn-71000495: {}
2025-10-20 03:08:23,487 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4370-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4370-1)
2025-10-20 03:08:24,790 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4370-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4370-2)
2025-10-20 03:08:24,791 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-90249582']
2025-10-20 03:08:25,098 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-90249582'] created succesfully
2025-10-20 03:08:25,474 - DEBUG - Image by name tiny_trans_fw not found
2025-10-20 03:08:25,474 - DEBUG - Installing image tiny_trans_fw
2025-10-20 03:08:25,474 - 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-10-20 03:08:25,474 - DEBUG - Image build path file:///contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2025-10-20 03:08:25,474 - DEBUG - Download image from local file file:///contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2025-10-20 03:08:25,474 - DEBUG - Unzip image
2025-10-20 03:08:25,474 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2025-10-20 03:08:25,492 - INFO - image /contrail-test/images/tinycore-net-transparent-fw.qcow2 is not found
2025-10-20 03:08:25,492 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-90249582']
2025-10-20 03:08:25,613 - INFO - Service template: ['default-domain', 'ctest-service_template_1-90249582'] deleted successfully.
2025-10-20 03:08:25,613 - INFO - Skip interface_detach for VM ctest-right_vm-58122382 in state BUILD
2025-10-20 03:08:25,613 - INFO - Deleting VM ctest-right_vm-58122382
2025-10-20 03:08:25,613 - ERROR - VM ctest-right_vm-58122382 has failed to come up
2025-10-20 03:08:25,613 - ERROR - Nova failed to get host of the VM
2025-10-20 03:08:25,613 - ERROR - VM ctest-right_vm-58122382 has failed to come up
2025-10-20 03:08:25,613 - ERROR - Nova failed to get host of the VM
2025-10-20 03:08:25,682 - INFO - Skip interface_detach for VM ctest-left_vm-78980800 in state BUILD
2025-10-20 03:08:25,682 - INFO - Deleting VM ctest-left_vm-78980800
2025-10-20 03:08:25,682 - ERROR - VM ctest-left_vm-78980800 has failed to come up
2025-10-20 03:08:25,682 - ERROR - Nova failed to get host of the VM
2025-10-20 03:08:25,682 - ERROR - VM ctest-left_vm-78980800 has failed to come up
2025-10-20 03:08:25,682 - ERROR - Nova failed to get host of the VM
2025-10-20 03:08:25,749 - INFO - Deleting VN ctest-trans_right_vn-71000495
2025-10-20 03:08:25,918 - DEBUG - Response for deleting network ()
2025-10-20 03:08:25,918 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/3798fde5-9cd0-4d3a-93bc-a7a181cf61b7
2025-10-20 03:08:25,924 - DEBUG - Response Code: 404
2025-10-20 03:08:25,924 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-20 03:08:25,930 - DEBUG - Requesting: http://10.0.0.22:8082/domain/9567af48-d216-4b74-bb40-c5fce52cf633
2025-10-20 03:08:25,954 - DEBUG - Requesting: http://10.0.0.22:8082/project/cec0a583-93ae-4444-8076-32363ef1da5d
2025-10-20 03:08:26,038 - INFO - Validated that VN ctest-trans_right_vn-71000495 is not found in API Server
2025-10-20 03:08:26,050 - DEBUG - VN ctest-trans_right_vn-71000495 is not present in Agent 10.0.0.131
2025-10-20 03:08:26,082 - DEBUG - VN ctest-trans_right_vn-71000495 is not present in Agent 10.0.0.19
2025-10-20 03:08:26,082 - INFO - Validated that VN ctest-trans_right_vn-71000495 is not in any agent
2025-10-20 03:08:26,119 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-trans_right_vn-71000495 info
2025-10-20 03:08:26,120 - INFO - Deleting VN ctest-trans_left_vn-77370516
2025-10-20 03:08:26,289 - DEBUG - Response for deleting network ()
2025-10-20 03:08:26,289 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/184f41ec-2b77-4c4b-8882-7650bec83656
2025-10-20 03:08:26,295 - DEBUG - Response Code: 404
2025-10-20 03:08:26,295 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-20 03:08:26,301 - DEBUG - Requesting: http://10.0.0.22:8082/domain/9567af48-d216-4b74-bb40-c5fce52cf633
2025-10-20 03:08:26,327 - DEBUG - Requesting: http://10.0.0.22:8082/project/cec0a583-93ae-4444-8076-32363ef1da5d
2025-10-20 03:08:26,441 - INFO - Validated that VN ctest-trans_left_vn-77370516 is not found in API Server
2025-10-20 03:08:26,453 - DEBUG - VN ctest-trans_left_vn-77370516 is not present in Agent 10.0.0.131
2025-10-20 03:08:26,466 - DEBUG - VN ctest-trans_left_vn-77370516 is not present in Agent 10.0.0.19
2025-10-20 03:08:26,466 - INFO - Validated that VN ctest-trans_left_vn-77370516 is not in any agent
2025-10-20 03:08:26,540 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-trans_left_vn-77370516 info
2025-10-20 03:08:26,540 - INFO - Deleting VN ctest-right_vn-59936896
2025-10-20 03:08:26,583 - DEBUG - VN 416751b7-bd3f-4eea-b942-e11961efc667 still in use: Unable to complete operation on network 416751b7-bd3f-4eea-b942-e11961efc667. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-5202284b-c19b-481b-9379-9b0729a3e416']
2025-10-20 03:08:26,583 - WARNING - Deleting VN ctest-right_vn-59936896 failed..Will retry
2025-10-20 03:08:28,729 - DEBUG - Response for deleting network ()
2025-10-20 03:08:28,729 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/66d76321-fbf8-46c5-80bb-aa1b8987a71d
2025-10-20 03:08:28,735 - DEBUG - Response Code: 404
2025-10-20 03:08:28,735 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-20 03:08:28,742 - DEBUG - Requesting: http://10.0.0.22:8082/domain/9567af48-d216-4b74-bb40-c5fce52cf633
2025-10-20 03:08:28,799 - DEBUG - Requesting: http://10.0.0.22:8082/project/cec0a583-93ae-4444-8076-32363ef1da5d
2025-10-20 03:08:28,890 - INFO - Validated that VN ctest-right_vn-59936896 is not found in API Server
2025-10-20 03:08:28,901 - DEBUG - VN ctest-right_vn-59936896 is not present in Agent 10.0.0.131
2025-10-20 03:08:28,911 - DEBUG - VN ctest-right_vn-59936896 is not present in Agent 10.0.0.19
2025-10-20 03:08:28,911 - INFO - Validated that VN ctest-right_vn-59936896 is not in any agent
2025-10-20 03:08:28,942 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-59936896 info
2025-10-20 03:08:28,942 - INFO - Deleting VN ctest-left_vn-42102085
2025-10-20 03:08:29,088 - DEBUG - Response for deleting network ()
2025-10-20 03:08:29,088 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/44a20836-5b5e-48f3-a5e4-1c9ef6f05f77
2025-10-20 03:08:29,094 - DEBUG - Response Code: 404
2025-10-20 03:08:29,094 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-20 03:08:29,100 - DEBUG - Requesting: http://10.0.0.22:8082/domain/9567af48-d216-4b74-bb40-c5fce52cf633
2025-10-20 03:08:29,109 - DEBUG - Requesting: http://10.0.0.22:8082/project/cec0a583-93ae-4444-8076-32363ef1da5d
2025-10-20 03:08:29,215 - INFO - Validated that VN ctest-left_vn-42102085 is not found in API Server
2025-10-20 03:08:29,227 - DEBUG - VN ctest-left_vn-42102085 is not present in Agent 10.0.0.131
2025-10-20 03:08:29,235 - DEBUG - VN ctest-left_vn-42102085 is not present in Agent 10.0.0.19
2025-10-20 03:08:29,235 - INFO - Validated that VN ctest-left_vn-42102085 is not in any agent
2025-10-20 03:08:29,269 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-42102085 info
2025-10-20 03:08:29,269 - INFO - Deleting VN ctest-mgmt_vn-85169874
2025-10-20 03:08:29,413 - DEBUG - Response for deleting network ()
2025-10-20 03:08:29,413 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/f3286d56-a063-41cc-8b1d-0f8d3be63fbf
2025-10-20 03:08:29,418 - DEBUG - Response Code: 404
2025-10-20 03:08:29,418 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-20 03:08:29,423 - DEBUG - Requesting: http://10.0.0.22:8082/domain/9567af48-d216-4b74-bb40-c5fce52cf633
2025-10-20 03:08:29,441 - DEBUG - Requesting: http://10.0.0.22:8082/project/cec0a583-93ae-4444-8076-32363ef1da5d
2025-10-20 03:08:29,530 - INFO - Validated that VN ctest-mgmt_vn-85169874 is not found in API Server
2025-10-20 03:08:29,540 - DEBUG - VN ctest-mgmt_vn-85169874 is not present in Agent 10.0.0.131
2025-10-20 03:08:29,549 - DEBUG - VN ctest-mgmt_vn-85169874 is not present in Agent 10.0.0.19
2025-10-20 03:08:29,549 - INFO - Validated that VN ctest-mgmt_vn-85169874 is not in any agent
2025-10-20 03:08:29,579 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-85169874 info
2025-10-20 03:08:30,832 - DEBUG - No XMPP flaps were noticed during the test
2025-10-20 03:08:30,832 - INFO -
2025-10-20 03:08:30,832 - INFO - END TEST : test_svc_trans_segment_right_hc_fail_svm : SKIPPED[0:00:15]
2025-10-20 03:08:30,832 - INFO - --------------------------------------------------------------------------------
2025-10-20 03:08:31,515 - INFO - Deleted project: ctest-TestSvcHC-27824646, ID : cec0a583-93ae-4444-8076-32363ef1da5d