2025-02-04 07:05:24,350 - INFO - Domain Default found not creating
2025-02-04 07:05:24,502 - INFO - Project ctest-TestSvcRegr-71315967 not found, creating it
2025-02-04 07:05:24,974 - INFO - Created Project:ctest-TestSvcRegr-71315967, ID : d561d5a9-0e36-44b1-a09c-5e0b22f87fce
2025-02-04 07:05:26,516 - INFO - ================================================================================
2025-02-04 07:05:26,517 - INFO - STARTING TEST : test_svc_in_network_datapath
2025-02-04 07:05:26,773 - DEBUG - Nothing to compare xmpp stats {'10.0.0.12': {'10.20.0.6': '0'}} with
2025-02-04 07:05:26,773 - INFO - Initial checks done. Running the testcase now
2025-02-04 07:05:26,773 - INFO -
2025-02-04 07:05:27,325 - DEBUG - Response for create_network : {'network': {'id': '31fe88da-b408-4067-8696-8c81b0b24ad7', 'name': 'ctest-mgmt_vn-53004815', 'tenant_id': 'd561d5a90e3644b1a09c5e0b22f87fce', 'project_id': 'd561d5a90e3644b1a09c5e0b22f87fce', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-71315967', 'ctest-mgmt_vn-53004815'], 'port_security_enabled': True, 'description': ''}}
2025-02-04 07:05:27,501 - DEBUG - Response for create_subnet : {'subnet': {'id': 'cc25be7c-4b88-4720-b27e-876757f1ff80', 'name': '', 'tenant_id': 'd561d5a90e3644b1a09c5e0b22f87fce', 'network_id': '31fe88da-b408-4067-8696-8c81b0b24ad7', 'ip_version': 4, 'cidr': '123.151.63.128/26', 'allocation_pools': [{'start': '123.151.63.130', 'end': '123.151.63.190'}], 'gateway_ip': '123.151.63.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '123.151.63.130', 'tags': [], 'project_id': 'd561d5a90e3644b1a09c5e0b22f87fce'}}
2025-02-04 07:05:27,517 - INFO - Created VN ctest-mgmt_vn-53004815
2025-02-04 07:05:27,571 - DEBUG - VN ctest-mgmt_vn-53004815 UUID is 31fe88da-b408-4067-8696-8c81b0b24ad7
2025-02-04 07:05:27,682 - DEBUG - Requesting: http://10.0.0.12:8082/domains
2025-02-04 07:05:27,976 - DEBUG - Requesting: http://10.0.0.12:8082/domains
2025-02-04 07:05:28,005 - DEBUG - Requesting: http://10.0.0.12:8082/domain/ba13f9de-04b3-4f3c-949e-421777e97252
2025-02-04 07:05:28,019 - DEBUG - Requesting: http://10.0.0.12:8082/project/d561d5a9-0e36-44b1-a09c-5e0b22f87fce
2025-02-04 07:05:28,107 - DEBUG - Requesting: http://10.0.0.12:8082/virtual-network/31fe88da-b408-4067-8696-8c81b0b24ad7
2025-02-04 07:05:28,118 - DEBUG - Requesting: http://10.0.0.12:8082/virtual-network/31fe88da-b408-4067-8696-8c81b0b24ad7
2025-02-04 07:05:28,128 - DEBUG - Requesting: http://10.0.0.12:8082/routing-instance/52e604fc-6187-4b21-8cbb-b994d44bfe8b
2025-02-04 07:05:28,138 - DEBUG - Requesting: http://10.0.0.12:8082/routing-instance/52e604fc-6187-4b21-8cbb-b994d44bfe8b
2025-02-04 07:05:28,148 - DEBUG - Requesting: http://10.0.0.12:8082/route-target/c0b880d1-86dd-4313-9204-e2ea46066f9a
2025-02-04 07:05:28,156 - DEBUG - Route Targets: ['target:64512:8000004']
2025-02-04 07:05:28,156 - DEBUG - Requesting: http://10.0.0.12:8082/virtual-network/31fe88da-b408-4067-8696-8c81b0b24ad7
2025-02-04 07:05:28,166 - DEBUG - Requesting: http://10.0.0.12:8082/routing-instance/52e604fc-6187-4b21-8cbb-b994d44bfe8b
2025-02-04 07:05:28,181 - INFO - Verified VN network id 8 for VN 31fe88da-b408-4067-8696-8c81b0b24ad7
2025-02-04 07:05:28,181 - INFO - Verifications in API Server for VN ctest-mgmt_vn-53004815 passed
2025-02-04 07:05:28,181 - DEBUG - Requesting: http://10.0.0.12:8082/virtual-network/31fe88da-b408-4067-8696-8c81b0b24ad7
2025-02-04 07:05:28,194 - DEBUG - Requesting: http://10.0.0.12:8082/routing-instance/52e604fc-6187-4b21-8cbb-b994d44bfe8b
2025-02-04 07:05:28,203 - DEBUG - Requesting: http://10.0.0.12:8082/routing-instance/52e604fc-6187-4b21-8cbb-b994d44bfe8b
2025-02-04 07:05:28,212 - DEBUG - Requesting: http://10.0.0.12:8082/route-target/c0b880d1-86dd-4313-9204-e2ea46066f9a
2025-02-04 07:05:28,227 - DEBUG - Control-node 10.0.0.12 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-71315967:ctest-mgmt_vn-53004815', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-71315967', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3602467224847663207', 'uuid-lslong': '9698093336232086231'}, 'enable': 'true', 'created': '2025-02-04T07:05:27', 'last-modified': '2025-02-04T07:05:27', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.756882'}
2025-02-04 07:05:28,234 - DEBUG - Route Targets: ['target:64512:8000004']
2025-02-04 07:05:28,235 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-53004815 passed
2025-02-04 07:05:28,235 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-53004815 in API_Server ======
2025-02-04 07:05:28,235 - DEBUG - Requesting: http://10.0.0.12:8082/domains
2025-02-04 07:05:28,241 - DEBUG - Requesting: http://10.0.0.12:8082/domain/ba13f9de-04b3-4f3c-949e-421777e97252
2025-02-04 07:05:28,254 - DEBUG - Requesting: http://10.0.0.12:8082/project/d561d5a9-0e36-44b1-a09c-5e0b22f87fce
2025-02-04 07:05:28,329 - DEBUG - Requesting: http://10.0.0.12:8082/virtual-network/31fe88da-b408-4067-8696-8c81b0b24ad7
2025-02-04 07:05:28,341 - DEBUG - =>VN ctest-mgmt_vn-53004815 has no policy to be verified
2025-02-04 07:05:28,341 - DEBUG - Verifying the vn in opserver
2025-02-04 07:05:28,341 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-71315967:ctest-mgmt_vn-53004815 virtual network link through opserver 10.0.0.12
2025-02-04 07:05:28,341 - DEBUG - Requesting: http://10.0.0.12:8081/analytics/uves/virtual-networks
2025-02-04 07:05:28,635 - DEBUG - Requesting: http://10.0.0.12:8081/analytics/uves/virtual-networks
2025-02-04 07:05:28,697 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-71315967:ctest-mgmt_vn-53004815', 'href': 'http://10.0.0.12:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-71315967:ctest-mgmt_vn-53004815?flat'}
2025-02-04 07:05:28,697 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-71315967:ctest-mgmt_vn-53004815 is found in opserver
2025-02-04 07:05:28,708 - DEBUG - Do not have enough data to verify VN in agent
2025-02-04 07:05:28,713 - DEBUG - VRF ids for VN ctest-mgmt_vn-53004815: {}
2025-02-04 07:05:28,883 - DEBUG - Response for create_network : {'network': {'id': 'a4613c7e-112e-4a10-a160-122c389ae4bc', 'name': 'ctest-left_vn-30527299', 'tenant_id': 'd561d5a90e3644b1a09c5e0b22f87fce', 'project_id': 'd561d5a90e3644b1a09c5e0b22f87fce', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-71315967', 'ctest-left_vn-30527299'], 'port_security_enabled': True, 'description': ''}}
2025-02-04 07:05:29,075 - DEBUG - Response for create_subnet : {'subnet': {'id': 'ff24a0b4-532e-4449-b952-1e7ddc50c57d', 'name': '', 'tenant_id': 'd561d5a90e3644b1a09c5e0b22f87fce', 'network_id': 'a4613c7e-112e-4a10-a160-122c389ae4bc', 'ip_version': 4, 'cidr': '102.241.133.0/26', 'allocation_pools': [{'start': '102.241.133.2', 'end': '102.241.133.62'}], 'gateway_ip': '102.241.133.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '102.241.133.2', 'tags': [], 'project_id': 'd561d5a90e3644b1a09c5e0b22f87fce'}}
2025-02-04 07:05:29,094 - INFO - Created VN ctest-left_vn-30527299
2025-02-04 07:05:29,147 - DEBUG - VN ctest-left_vn-30527299 UUID is a4613c7e-112e-4a10-a160-122c389ae4bc
2025-02-04 07:05:29,254 - DEBUG - Requesting: http://10.0.0.12:8082/domains
2025-02-04 07:05:29,259 - DEBUG - Requesting: http://10.0.0.12:8082/domain/ba13f9de-04b3-4f3c-949e-421777e97252
2025-02-04 07:05:29,271 - DEBUG - Requesting: http://10.0.0.12:8082/project/d561d5a9-0e36-44b1-a09c-5e0b22f87fce
2025-02-04 07:05:29,339 - DEBUG - Requesting: http://10.0.0.12:8082/virtual-network/a4613c7e-112e-4a10-a160-122c389ae4bc
2025-02-04 07:05:29,353 - DEBUG - Requesting: http://10.0.0.12:8082/virtual-network/a4613c7e-112e-4a10-a160-122c389ae4bc
2025-02-04 07:05:29,365 - DEBUG - Requesting: http://10.0.0.12:8082/routing-instance/3c35b22b-5ed0-475a-aaaa-38877480142e
2025-02-04 07:05:29,427 - DEBUG - Requesting: http://10.0.0.12:8082/routing-instance/3c35b22b-5ed0-475a-aaaa-38877480142e
2025-02-04 07:05:29,435 - DEBUG - Requesting: http://10.0.0.12:8082/route-target/84a27753-da5f-49ca-ba46-1f5130f64dc8
2025-02-04 07:05:29,443 - DEBUG - Route Targets: ['target:64512:8000005']
2025-02-04 07:05:29,443 - DEBUG - Requesting: http://10.0.0.12:8082/virtual-network/a4613c7e-112e-4a10-a160-122c389ae4bc
2025-02-04 07:05:29,456 - DEBUG - Requesting: http://10.0.0.12:8082/routing-instance/3c35b22b-5ed0-475a-aaaa-38877480142e
2025-02-04 07:05:29,476 - INFO - Verified VN network id 9 for VN a4613c7e-112e-4a10-a160-122c389ae4bc
2025-02-04 07:05:29,476 - INFO - Verifications in API Server for VN ctest-left_vn-30527299 passed
2025-02-04 07:05:29,476 - DEBUG - Requesting: http://10.0.0.12:8082/virtual-network/a4613c7e-112e-4a10-a160-122c389ae4bc
2025-02-04 07:05:29,490 - DEBUG - Requesting: http://10.0.0.12:8082/routing-instance/3c35b22b-5ed0-475a-aaaa-38877480142e
2025-02-04 07:05:29,498 - DEBUG - Requesting: http://10.0.0.12:8082/routing-instance/3c35b22b-5ed0-475a-aaaa-38877480142e
2025-02-04 07:05:29,508 - DEBUG - Requesting: http://10.0.0.12:8082/route-target/84a27753-da5f-49ca-ba46-1f5130f64dc8
2025-02-04 07:05:29,524 - DEBUG - Control-node 10.0.0.12 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-71315967:ctest-left_vn-30527299', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-71315967', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11844815007112907280', 'uuid-lslong': '11628314219008156860'}, 'enable': 'true', 'created': '2025-02-04T07:05:28', 'last-modified': '2025-02-04T07:05:29', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.481192'}
2025-02-04 07:05:29,532 - DEBUG - Route Targets: ['target:64512:8000005']
2025-02-04 07:05:29,533 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-30527299 passed
2025-02-04 07:05:29,533 - DEBUG - ====Verifying policy data for ctest-left_vn-30527299 in API_Server ======
2025-02-04 07:05:29,533 - DEBUG - Requesting: http://10.0.0.12:8082/domains
2025-02-04 07:05:29,540 - DEBUG - Requesting: http://10.0.0.12:8082/domain/ba13f9de-04b3-4f3c-949e-421777e97252
2025-02-04 07:05:29,554 - DEBUG - Requesting: http://10.0.0.12:8082/project/d561d5a9-0e36-44b1-a09c-5e0b22f87fce
2025-02-04 07:05:29,628 - DEBUG - Requesting: http://10.0.0.12:8082/virtual-network/a4613c7e-112e-4a10-a160-122c389ae4bc
2025-02-04 07:05:29,639 - DEBUG - =>VN ctest-left_vn-30527299 has no policy to be verified
2025-02-04 07:05:29,639 - DEBUG - Verifying the vn in opserver
2025-02-04 07:05:29,639 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-71315967:ctest-left_vn-30527299 virtual network link through opserver 10.0.0.12
2025-02-04 07:05:29,639 - DEBUG - Requesting: http://10.0.0.12:8081/analytics/uves/virtual-networks
2025-02-04 07:05:29,650 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-71315967:ctest-left_vn-30527299', 'href': 'http://10.0.0.12:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-71315967:ctest-left_vn-30527299?flat'}
2025-02-04 07:05:29,650 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-71315967:ctest-left_vn-30527299 is found in opserver
2025-02-04 07:05:29,661 - DEBUG - Do not have enough data to verify VN in agent
2025-02-04 07:05:29,665 - DEBUG - VRF ids for VN ctest-left_vn-30527299: {}
2025-02-04 07:05:29,847 - DEBUG - Response for create_network : {'network': {'id': '47814495-89aa-48c0-8bec-46bbdd927ed2', 'name': 'ctest-right_vn-89438892', 'tenant_id': 'd561d5a90e3644b1a09c5e0b22f87fce', 'project_id': 'd561d5a90e3644b1a09c5e0b22f87fce', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-71315967', 'ctest-right_vn-89438892'], 'port_security_enabled': True, 'description': ''}}
2025-02-04 07:05:30,082 - DEBUG - Response for create_subnet : {'subnet': {'id': 'ada3a3c4-1e99-4f47-b12c-a2e2305aad3a', 'name': '', 'tenant_id': 'd561d5a90e3644b1a09c5e0b22f87fce', 'network_id': '47814495-89aa-48c0-8bec-46bbdd927ed2', 'ip_version': 4, 'cidr': '142.147.16.64/26', 'allocation_pools': [{'start': '142.147.16.66', 'end': '142.147.16.126'}], 'gateway_ip': '142.147.16.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '142.147.16.66', 'tags': [], 'project_id': 'd561d5a90e3644b1a09c5e0b22f87fce'}}
2025-02-04 07:05:30,099 - INFO - Created VN ctest-right_vn-89438892
2025-02-04 07:05:30,151 - DEBUG - VN ctest-right_vn-89438892 UUID is 47814495-89aa-48c0-8bec-46bbdd927ed2
2025-02-04 07:05:30,258 - DEBUG - Requesting: http://10.0.0.12:8082/domains
2025-02-04 07:05:30,264 - DEBUG - Requesting: http://10.0.0.12:8082/domain/ba13f9de-04b3-4f3c-949e-421777e97252
2025-02-04 07:05:30,276 - DEBUG - Requesting: http://10.0.0.12:8082/project/d561d5a9-0e36-44b1-a09c-5e0b22f87fce
2025-02-04 07:05:30,345 - DEBUG - Requesting: http://10.0.0.12:8082/virtual-network/47814495-89aa-48c0-8bec-46bbdd927ed2
2025-02-04 07:05:30,356 - DEBUG - Requesting: http://10.0.0.12:8082/virtual-network/47814495-89aa-48c0-8bec-46bbdd927ed2
2025-02-04 07:05:30,367 - DEBUG - Requesting: http://10.0.0.12:8082/routing-instance/854bc710-7760-4358-86b6-0bca76037628
2025-02-04 07:05:30,375 - DEBUG - Requesting: http://10.0.0.12:8082/routing-instance/854bc710-7760-4358-86b6-0bca76037628
2025-02-04 07:05:30,383 - DEBUG - Requesting: http://10.0.0.12:8082/route-target/edf059e0-7939-4320-984c-25c9c0754ffd
2025-02-04 07:05:30,391 - DEBUG - Route Targets: ['target:64512:8000006']
2025-02-04 07:05:30,391 - DEBUG - Requesting: http://10.0.0.12:8082/virtual-network/47814495-89aa-48c0-8bec-46bbdd927ed2
2025-02-04 07:05:30,403 - DEBUG - Requesting: http://10.0.0.12:8082/routing-instance/854bc710-7760-4358-86b6-0bca76037628
2025-02-04 07:05:30,420 - INFO - Verified VN network id 10 for VN 47814495-89aa-48c0-8bec-46bbdd927ed2
2025-02-04 07:05:30,420 - INFO - Verifications in API Server for VN ctest-right_vn-89438892 passed
2025-02-04 07:05:30,420 - DEBUG - Requesting: http://10.0.0.12:8082/virtual-network/47814495-89aa-48c0-8bec-46bbdd927ed2
2025-02-04 07:05:30,431 - DEBUG - Requesting: http://10.0.0.12:8082/routing-instance/854bc710-7760-4358-86b6-0bca76037628
2025-02-04 07:05:30,440 - DEBUG - Requesting: http://10.0.0.12:8082/routing-instance/854bc710-7760-4358-86b6-0bca76037628
2025-02-04 07:05:30,449 - DEBUG - Requesting: http://10.0.0.12:8082/route-target/edf059e0-7939-4320-984c-25c9c0754ffd
2025-02-04 07:05:30,464 - DEBUG - Control-node 10.0.0.12 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-71315967:ctest-right_vn-89438892', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-71315967', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5152474857739012288', 'uuid-lslong': '10082511438465892050'}, 'enable': 'true', 'created': '2025-02-04T07:05:29', 'last-modified': '2025-02-04T07:05:30', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.418740'}
2025-02-04 07:05:30,472 - DEBUG - Route Targets: ['target:64512:8000006']
2025-02-04 07:05:30,472 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-89438892 passed
2025-02-04 07:05:30,472 - DEBUG - ====Verifying policy data for ctest-right_vn-89438892 in API_Server ======
2025-02-04 07:05:30,472 - DEBUG - Requesting: http://10.0.0.12:8082/domains
2025-02-04 07:05:30,478 - DEBUG - Requesting: http://10.0.0.12:8082/domain/ba13f9de-04b3-4f3c-949e-421777e97252
2025-02-04 07:05:30,490 - DEBUG - Requesting: http://10.0.0.12:8082/project/d561d5a9-0e36-44b1-a09c-5e0b22f87fce
2025-02-04 07:05:30,565 - DEBUG - Requesting: http://10.0.0.12:8082/virtual-network/47814495-89aa-48c0-8bec-46bbdd927ed2
2025-02-04 07:05:30,574 - DEBUG - =>VN ctest-right_vn-89438892 has no policy to be verified
2025-02-04 07:05:30,575 - DEBUG - Verifying the vn in opserver
2025-02-04 07:05:30,575 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-71315967:ctest-right_vn-89438892 virtual network link through opserver 10.0.0.12
2025-02-04 07:05:30,575 - DEBUG - Requesting: http://10.0.0.12:8081/analytics/uves/virtual-networks
2025-02-04 07:05:30,585 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-71315967:ctest-right_vn-89438892', 'href': 'http://10.0.0.12:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-71315967:ctest-right_vn-89438892?flat'}
2025-02-04 07:05:30,585 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-71315967:ctest-right_vn-89438892 is found in opserver
2025-02-04 07:05:30,596 - DEBUG - Do not have enough data to verify VN in agent
2025-02-04 07:05:30,602 - DEBUG - VRF ids for VN ctest-right_vn-89438892: {}
2025-02-04 07:05:30,657 - DEBUG - Services list from nova: [, , ]
2025-02-04 07:05:31,663 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-2684-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-2684-1)
2025-02-04 07:05:32,827 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-2684-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-2684-1)
2025-02-04 07:05:32,827 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-45046648']
2025-02-04 07:05:33,191 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-45046648'] created succesfully
2025-02-04 07:05:33,523 - DEBUG - Image by name cirros_in_net not found
2025-02-04 07:05:33,523 - DEBUG - Installing image cirros_in_net
2025-02-04 07:05:33,523 - DEBUG - Image info {'name': 'cirros-in-net.vmdk.gz', 'webserver': '', 'location': '/images/converts/', 'username': 'cirros', 'password': 'gocubsgo', 'type': 'os', 'flavor': 'contrail_flavor_tiny', 'params': '--container-format bare --disk-format vmdk --property vmware_disktype="sparse" --property vmware_adaptertype="ide" --property hw_scsi_model=virtio-scsi --property hw_disk_bus_model=virtio-scsi --property hw_disk_bus=virtio', 'name_docker': 'phusion-baseimage-enablesshd'}
2025-02-04 07:05:33,523 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/converts//cirros-in-net.vmdk.gz
2025-02-04 07:05:33,523 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/converts//cirros-in-net.vmdk.gz
2025-02-04 07:05:33,534 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: wget -nv http://nexus.opensdn.io/repository///images/converts//cirros-in-net.vmdk.gz -O /contrail-test/images/cirros-in-net.vmdk.gz
2025-02-04 07:05:33,725 - DEBUG - Image has been downloaded to /contrail-test/images/cirros-in-net.vmdk.gz
2025-02-04 07:05:33,726 - DEBUG - Unzip image
2025-02-04 07:05:33,726 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/cirros-in-net.vmdk.gz
2025-02-04 07:05:33,876 - DEBUG - Try to create image in user context
2025-02-04 07:05:34,097 - DEBUG - Image has been created in user context
2025-02-04 07:05:34,097 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: rm -f /contrail-test/images/cirros-in-net.vmdk
2025-02-04 07:05:34,115 - DEBUG - Downloaded image has been removed
2025-02-04 07:05:34,129 - DEBUG - Uploaded image status = active
2025-02-04 07:05:35,227 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-2684-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-2684-1)
2025-02-04 07:05:35,228 - INFO - Waiting for VM ctest-pt_svm0-98700010 to be up..
2025-02-04 07:05:35,268 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2025-02-04 07:05:40,357 - DEBUG - VM is in ACTIVE state now
2025-02-04 07:05:40,357 - INFO - VM name : ctest-pt_svm0-98700010
2025-02-04 07:05:40,441 - DEBUG - VM ctest-pt_svm0-98700010 ID is 5ce9b69e-604d-4934-a7d4-8aac41f1d564
2025-02-04 07:05:40,461 - DEBUG - VM ctest-pt_svm0-98700010 launched on Node cn-jenkins-deploy-platform-ansible-os-2684-1
2025-02-04 07:05:40,712 - DEBUG - Requesting: http://10.0.0.12:8082/virtual-machine/5ce9b69e-604d-4934-a7d4-8aac41f1d564
2025-02-04 07:05:40,722 - DEBUG - Requesting: http://10.0.0.12:8082/virtual-machine-interface/02c68379-dda8-443a-a925-1ce4e612c268
2025-02-04 07:05:40,742 - DEBUG - Requesting: http://10.0.0.12:8082/virtual-machine-interface/34479a7a-37c6-4d24-b319-6a569632a650
2025-02-04 07:05:40,759 - DEBUG - Requesting: http://10.0.0.12:8082/virtual-machine-interface/7620845f-f048-4d84-a813-8802df055b81
2025-02-04 07:05:44,066 - DEBUG - (True, 'PING 169.254.0.5 (169.254.0.5) 56(84) bytes of data.\r\n\r\n--- 169.254.0.5 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1015ms')
2025-02-04 07:05:44,067 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-pt_svm0-98700010 failed!
2025-02-04 07:05:44,123 - DEBUG - Gateway for vn default-domain:ctest-TestSvcRegr-71315967:ctest-mgmt_vn-53004815 is 123.151.63.129 and allocation pool is NOT set
2025-02-04 07:05:48,192 - DEBUG - (True, 'PING 169.254.0.5 (169.254.0.5) 56(84) bytes of data.\r\n\r\n--- 169.254.0.5 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1011ms')
2025-02-04 07:05:48,192 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-pt_svm0-98700010 failed!
2025-02-04 07:05:48,251 - DEBUG - Gateway for vn default-domain:ctest-TestSvcRegr-71315967:ctest-mgmt_vn-53004815 is 123.151.63.129 and allocation pool is NOT set
2025-02-04 07:05:52,319 - DEBUG - (True, 'PING 169.254.0.5 (169.254.0.5) 56(84) bytes of data.\r\n\r\n--- 169.254.0.5 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1011ms')
2025-02-04 07:05:52,319 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-pt_svm0-98700010 failed!
2025-02-04 07:05:52,379 - DEBUG - Gateway for vn default-domain:ctest-TestSvcRegr-71315967:ctest-mgmt_vn-53004815 is 123.151.63.129 and allocation pool is NOT set
2025-02-04 07:05:56,446 - DEBUG - (True, 'PING 169.254.0.5 (169.254.0.5) 56(84) bytes of data.\r\n64 bytes from 169.254.0.5: icmp_seq=2 ttl=63 time=12.2 ms\r\n\r\n--- 169.254.0.5 ping statistics ---\r\n2 packets transmitted, 1 received, 50% packet loss, time 1010ms\r\nrtt min/avg/max/mdev = 12.182/12.182/12.182/0.000 ms')
2025-02-04 07:05:56,446 - INFO - Ping to Metadata IP 169.254.0.5 of VM ctest-pt_svm0-98700010 passed
2025-02-04 07:05:56,599 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-02-04 07:05:56,599 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-98700010, IP 123.151.63.131, Port 22
2025-02-04 07:05:56,665 - DEBUG - Error on ssh to cirros@169.254.0.5:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.5/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.5/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.5/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2025-02-04 07:05:56,746 - DEBUG - VM ctest-pt_svm0-98700010 is NOT ready for SSH connections, VM status: ACTIVE
2025-02-04 07:06:01,747 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-02-04 07:06:01,747 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-98700010, IP 123.151.63.131, Port 22
2025-02-04 07:06:01,809 - DEBUG - Error on ssh to cirros@169.254.0.5:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.5/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.5/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.5/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2025-02-04 07:06:01,900 - DEBUG - VM ctest-pt_svm0-98700010 is NOT ready for SSH connections, VM status: ACTIVE
2025-02-04 07:06:06,901 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-02-04 07:06:06,902 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-98700010, IP 123.151.63.131, Port 22
2025-02-04 07:06:06,970 - DEBUG - Error on ssh to cirros@169.254.0.5:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.5/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.5/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.5/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2025-02-04 07:06:07,060 - DEBUG - VM ctest-pt_svm0-98700010 is NOT ready for SSH connections, VM status: ACTIVE
2025-02-04 07:06:12,061 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-02-04 07:06:12,061 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-98700010, IP 123.151.63.131, Port 22
2025-02-04 07:06:12,128 - DEBUG - Error on ssh to cirros@169.254.0.5:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.5/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.5/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.5/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2025-02-04 07:06:12,213 - DEBUG - VM ctest-pt_svm0-98700010 is NOT ready for SSH connections, VM status: ACTIVE
2025-02-04 07:06:17,214 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-02-04 07:06:17,214 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-98700010, IP 123.151.63.131, Port 22
2025-02-04 07:06:17,381 - DEBUG - VM ctest-pt_svm0-98700010 is ready for SSH connections
2025-02-04 07:06:17,531 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-02-04 07:06:17,531 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-98700010, IP 123.151.63.131, Port 22
2025-02-04 07:06:17,683 - DEBUG - VM ctest-pt_svm0-98700010 is ready for SSH connections
2025-02-04 07:06:17,831 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-02-04 07:06:17,831 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-98700010, IP 123.151.63.131, Port 22
2025-02-04 07:06:17,982 - DEBUG - VM ctest-pt_svm0-98700010 is ready for SSH connections
2025-02-04 07:06:17,988 - DEBUG - Creating service instance: ['default-domain', 'ctest-TestSvcRegr-71315967', 'ctest-si-11363483']
2025-02-04 07:06:18,887 - INFO - Waiting for VM ctest-left_vm-92928521 to be up..
2025-02-04 07:06:18,972 - DEBUG - VM is in ACTIVE state now
2025-02-04 07:06:18,972 - INFO - VM name : ctest-left_vm-92928521
2025-02-04 07:06:19,055 - DEBUG - VM ctest-left_vm-92928521 ID is f8bc9ed2-5dda-4bd1-a639-5941cf6c53ac
2025-02-04 07:06:19,055 - DEBUG - VM ctest-left_vm-92928521 launched on Node cn-jenkins-deploy-platform-ansible-os-2684-1
2025-02-04 07:06:19,157 - DEBUG - Requesting: http://10.0.0.12:8082/virtual-machine/f8bc9ed2-5dda-4bd1-a639-5941cf6c53ac
2025-02-04 07:06:19,170 - DEBUG - Requesting: http://10.0.0.12:8082/virtual-machine-interface/e72adff1-7338-4d89-8586-94eeac6e7e1e
2025-02-04 07:06:20,421 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n64 bytes from 169.254.0.3: icmp_seq=1 ttl=63 time=2.68 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.411 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 0.411/1.544/2.677/1.133 ms')
2025-02-04 07:06:20,421 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-left_vm-92928521 passed
2025-02-04 07:06:20,575 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-02-04 07:06:20,575 - DEBUG - Waiting to SSH to VM ctest-left_vm-92928521, IP 102.241.133.3, Port 22
2025-02-04 07:06:20,735 - DEBUG - VM ctest-left_vm-92928521 is ready for SSH connections
2025-02-04 07:06:20,735 - INFO - Waiting for VM ctest-right_vm-11742909 to be up..
2025-02-04 07:06:20,813 - DEBUG - VM is in ACTIVE state now
2025-02-04 07:06:20,813 - INFO - VM name : ctest-right_vm-11742909
2025-02-04 07:06:20,892 - DEBUG - VM ctest-right_vm-11742909 ID is e536bd7b-0cb5-4393-b896-99ffe5f65d77
2025-02-04 07:06:20,892 - DEBUG - VM ctest-right_vm-11742909 launched on Node cn-jenkins-deploy-platform-ansible-os-2684-1
2025-02-04 07:06:20,966 - DEBUG - Requesting: http://10.0.0.12:8082/virtual-machine/e536bd7b-0cb5-4393-b896-99ffe5f65d77
2025-02-04 07:06:20,976 - DEBUG - Requesting: http://10.0.0.12:8082/virtual-machine-interface/36621540-b6fa-48d2-adbe-12f8fda7270e
2025-02-04 07:06:22,221 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n64 bytes from 169.254.0.4: icmp_seq=1 ttl=63 time=2.75 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.391 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 0.391/1.572/2.753/1.181 ms')
2025-02-04 07:06:22,221 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-right_vm-11742909 passed
2025-02-04 07:06:22,371 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-02-04 07:06:22,371 - DEBUG - Waiting to SSH to VM ctest-right_vm-11742909, IP 142.147.16.67, Port 22
2025-02-04 07:06:22,527 - DEBUG - VM ctest-right_vm-11742909 is ready for SSH connections
2025-02-04 07:06:22,534 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestSvcRegr-71315967:ctest-left_vn-30527299, security_group = None, network_policy = None, subnet_list = []], src_ports = [start_port = 0, end_port = 65535], application = [], dst_addresses = [subnet = None, virtual_network = default-domain:ctest-TestSvcRegr-71315967:ctest-right_vn-89438892, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = 0, end_port = 65535], action_list = simple_action = pass, gateway_name = None, apply_service = ['default-domain:ctest-TestSvcRegr-71315967:ctest-si-11363483'], service_properties = None, mirror_to = None, assign_routing_instance = None, log = False, alert = False, qos_action = None, host_based_service = False, ethertype = None, created = None, last_modified = None, description = None]
2025-02-04 07:06:22,963 - INFO - Attaching policy ctest-policy-43735315 to vn ctest-left_vn-30527299
2025-02-04 07:06:23,507 - INFO - Attaching policy ctest-policy-43735315 to vn ctest-right_vn-89438892
2025-02-04 07:06:23,967 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-45046648'] created succesfully
2025-02-04 07:06:24,199 - DEBUG - Service instance: ['default-domain', 'ctest-TestSvcRegr-71315967', 'ctest-si-11363483'] created succesfully
2025-02-04 07:06:24,199 - DEBUG - Requesting: http://10.0.0.12:8082/domains
2025-02-04 07:06:24,208 - DEBUG - Requesting: http://10.0.0.12:8082/domain/ba13f9de-04b3-4f3c-949e-421777e97252
2025-02-04 07:06:24,221 - DEBUG - Requesting: http://10.0.0.12:8082/project/d561d5a9-0e36-44b1-a09c-5e0b22f87fce
2025-02-04 07:06:24,297 - DEBUG - Requesting: http://10.0.0.12:8082/service-instance/edf1c41f-1b5d-4461-870a-f69bd506172f
2025-02-04 07:06:24,306 - DEBUG - SI 'ctest-si-11363483' has service template ref to ctest-service_template_1-45046648
2025-02-04 07:06:24,307 - DEBUG - Requesting: http://10.0.0.12:8082/domains
2025-02-04 07:06:24,312 - DEBUG - Requesting: http://10.0.0.12:8082/domain/ba13f9de-04b3-4f3c-949e-421777e97252
2025-02-04 07:06:24,322 - DEBUG - Requesting: http://10.0.0.12:8082/project/d561d5a9-0e36-44b1-a09c-5e0b22f87fce
2025-02-04 07:06:24,393 - DEBUG - Requesting: http://10.0.0.12:8082/service-instance/edf1c41f-1b5d-4461-870a-f69bd506172f
2025-02-04 07:06:24,403 - DEBUG - Requesting: http://10.0.0.12:8082/port-tuple/aa9b990d-1782-4996-9bcb-d01e20510cd0
2025-02-04 07:06:24,414 - DEBUG - Requesting: http://10.0.0.12:8082/virtual-machine-interface/02c68379-dda8-443a-a925-1ce4e612c268
2025-02-04 07:06:24,425 - DEBUG - Requesting: http://10.0.0.12:8082/virtual-machine-interface/34479a7a-37c6-4d24-b319-6a569632a650
2025-02-04 07:06:24,436 - DEBUG - Requesting: http://10.0.0.12:8082/virtual-machine-interface/7620845f-f048-4d84-a813-8802df055b81
2025-02-04 07:06:24,667 - DEBUG - VM is in ACTIVE state now
2025-02-04 07:06:25,087 - DEBUG - Requesting: http://10.0.0.12:8082/virtual-machine/5ce9b69e-604d-4934-a7d4-8aac41f1d564
2025-02-04 07:06:25,096 - DEBUG - Requesting: http://10.0.0.12:8082/virtual-machine-interface/02c68379-dda8-443a-a925-1ce4e612c268
2025-02-04 07:06:25,107 - DEBUG - Requesting: http://10.0.0.12:8082/virtual-machine-interface/34479a7a-37c6-4d24-b319-6a569632a650
2025-02-04 07:06:25,120 - DEBUG - Requesting: http://10.0.0.12:8082/virtual-machine-interface/7620845f-f048-4d84-a813-8802df055b81
2025-02-04 07:06:25,134 - DEBUG - Requesting: http://10.0.0.12:8082/instance-ip/41f34b90-647b-4a11-9686-2577824dccbf
2025-02-04 07:06:25,145 - DEBUG - Requesting: http://10.0.0.12:8082/instance-ip/783c30c7-c14f-48b1-a0ae-fd6004b91817
2025-02-04 07:06:25,153 - DEBUG - Requesting: http://10.0.0.12:8082/instance-ip/c3ba2dc0-d615-44b3-968c-93efaf28451e
2025-02-04 07:06:25,162 - DEBUG - Requesting: http://10.0.0.12:8082/instance-ip/6a60ad30-7402-4b45-8ce0-415950a46cc6
2025-02-04 07:06:25,171 - DEBUG - Requesting: http://10.0.0.12:8082/instance-ip/954c75ce-8f44-4bbd-9dc4-56bda4628f32
2025-02-04 07:06:25,179 - DEBUG - Requesting: http://10.0.0.12:8082/instance-ip/d4f0e87e-0bca-4bd9-af70-a1364ba76b63
2025-02-04 07:06:25,190 - DEBUG - Requesting: http://10.0.0.12:8082/instance-ip/65e91d90-2540-4114-82f9-a37ad7953c6e
2025-02-04 07:06:25,201 - DEBUG - Requesting: http://10.0.0.12:8082/instance-ip/cb1a53b8-6b61-4680-bccf-5f533d82c145
2025-02-04 07:06:25,210 - DEBUG - Requesting: http://10.0.0.12:8082/instance-ip/fc6d8297-d556-4623-b02d-b42c15cc1631
2025-02-04 07:06:25,615 - DEBUG - VM is in ACTIVE state now
2025-02-04 07:06:25,615 - DEBUG - Requesting: http://10.0.0.12:8082/virtual-machine/5ce9b69e-604d-4934-a7d4-8aac41f1d564
2025-02-04 07:06:25,625 - DEBUG - Requesting: http://10.0.0.12:8082/virtual-machine-interface/02c68379-dda8-443a-a925-1ce4e612c268
2025-02-04 07:06:25,636 - DEBUG - Requesting: http://10.0.0.12:8082/virtual-machine-interface/34479a7a-37c6-4d24-b319-6a569632a650
2025-02-04 07:06:25,648 - DEBUG - Requesting: http://10.0.0.12:8082/virtual-machine-interface/7620845f-f048-4d84-a813-8802df055b81
2025-02-04 07:06:26,888 - DEBUG - (True, 'PING 169.254.0.6 (169.254.0.6) 56(84) bytes of data.\r\n64 bytes from 169.254.0.6: icmp_seq=1 ttl=63 time=1.04 ms\r\n64 bytes from 169.254.0.6: icmp_seq=2 ttl=63 time=0.670 ms\r\n\r\n--- 169.254.0.6 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 0.670/0.852/1.035/0.182 ms')
2025-02-04 07:06:26,888 - INFO - Ping to Metadata IP 169.254.0.6 of VM ctest-pt_svm0-98700010 passed
2025-02-04 07:06:26,929 - DEBUG - VM ctest-pt_svm0-98700010 Tap interface: {'index': '6', 'name': 'tap02c68379-dd', 'uuid': '02c68379-dda8-443a-a925-1ce4e612c268', 'vrf_name': 'default-domain:ctest-TestSvcRegr-71315967:ctest-left_vn-30527299:ctest-left_vn-30527299', 'active': 'Active', 'ipv4_active': 'Active', 'l2_active': 'L2 Active', 'ip6_active': 'Ipv6 Inactive < no-ipv6-addr >', 'health_check_active': 'Active', 'dhcp_service': 'Enable', 'dhcp_service_v6': 'Disable', 'dns_service': 'Enable', 'type': 'vport', 'label': '44', 'l2_label': '48', 'vxlan_id': '9', 'vn_name': 'default-domain:ctest-TestSvcRegr-71315967:ctest-left_vn-30527299', 'vm_uuid': '5ce9b69e-604d-4934-a7d4-8aac41f1d564', 'vm_name': 'ctest-pt_svm0-98700010', 'ip_addr': '102.241.133.4', 'mac_addr': '02:02:c6:83:79:dd', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.6', 'service_vlan_list': None, 'os_ifindex': '29', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestSvcRegr-71315967:02c68379-dda8-443a-a925-1ce4e612c268', 'sg_uuid_list': ['b7370563-5f99-438b-9cad-506223f1f800'], 'static_route_list': None, 'vm_project_uuid': 'd561d5a9-0e36-44b1-a09c-5e0b22f87fce', 'admin_state': 'Enabled', 'flow_key_idx': '50', 'allowed_address_pair_list': None, 'ip6_addr': '::', 'local_preference': '0', 'tx_vlan_id': '-1', 'rx_vlan_id': '-1', 'parent_interface': None, 'subnet': '--NA--', 'sub_type': 'Tap', 'vrf_assign_acl_uuid': '--NA--', 'vmi_type': 'Virtual Machine', 'transport': 'Ethernet', 'logical_interface_uuid': '00000000-0000-0000-0000-000000000000', 'flood_unknown_unicast': 'false', 'physical_device': None, 'physical_interface': None, 'fixed_ip4_list': None, 'fixed_ip6_list': None, 'fat_flow_list': None, 'metadata_ip_active': 'Active', 'service_health_check_ip': '0.0.0.0', 'alias_ip_list': None, 'drop_new_flows': 'false', 'bridge_domain_list': [], 'vmi_tag_list': None, 'policy_set_acl_list': None, 'slo_list': None, 'vhostuser_mode': '0', 'si_other_end_vmi': '7620845f-f048-4d84-a813-8802df055b81', 'cfg_igmp_enable': 'false', 'igmp_enabled': 'false', 'max_flows': '0', 'policy_set_fwaas_list': None, 'bond_interface_list': None, 'mac_ip_learning_enable': 'false', 'mac_ip_list': []}
2025-02-04 07:06:26,930 - DEBUG - Agent 10.0.0.12 vrf name: default-domain:ctest-TestSvcRegr-71315967:ctest-left_vn-30527299:ctest-left_vn-30527299
2025-02-04 07:06:26,937 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestSvcRegr-71315967:ctest-left_vn-30527299:ctest-left_vn-30527299', 'ucindex': '2', 'mcindex': '2', 'l2index': '2', 'source': 'Config; ', 'uc6index': '2', 'vn': 'default-domain:ctest-TestSvcRegr-71315967:ctest-left_vn-30527299', 'table_label': '-1', 'vxlan_id': '9', 'evpnindex': '2', 'brindex': '2', 'mplsindex': '0', 'RD': '10.20.0.6:2', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]}
2025-02-04 07:06:27,109 - DEBUG - VM ctest-pt_svm0-98700010 : Tap interface tap02c68379-dd is set to Active
2025-02-04 07:06:27,109 - DEBUG - Tap interface tap02c68379-dd detail : {'index': '6', 'name': 'tap02c68379-dd', 'uuid': '02c68379-dda8-443a-a925-1ce4e612c268', 'vrf_name': 'default-domain:ctest-TestSvcRegr-71315967:ctest-left_vn-30527299:ctest-left_vn-30527299', 'active': 'Active', 'ipv4_active': 'Active', 'l2_active': 'L2 Active', 'ip6_active': 'Ipv6 Inactive < no-ipv6-addr >', 'health_check_active': 'Active', 'dhcp_service': 'Enable', 'dhcp_service_v6': 'Disable', 'dns_service': 'Enable', 'type': 'vport', 'label': '44', 'l2_label': '48', 'vxlan_id': '9', 'vn_name': 'default-domain:ctest-TestSvcRegr-71315967:ctest-left_vn-30527299', 'vm_uuid': '5ce9b69e-604d-4934-a7d4-8aac41f1d564', 'vm_name': 'ctest-pt_svm0-98700010', 'ip_addr': '102.241.133.4', 'mac_addr': '02:02:c6:83:79:dd', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.6', 'service_vlan_list': None, 'os_ifindex': '29', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestSvcRegr-71315967:02c68379-dda8-443a-a925-1ce4e612c268', 'sg_uuid_list': ['b7370563-5f99-438b-9cad-506223f1f800'], 'static_route_list': None, 'vm_project_uuid': 'd561d5a9-0e36-44b1-a09c-5e0b22f87fce', 'admin_state': 'Enabled', 'flow_key_idx': '50', 'allowed_address_pair_list': None, 'ip6_addr': '::', 'local_preference': '0', 'tx_vlan_id': '-1', 'rx_vlan_id': '-1', 'parent_interface': None, 'subnet': '--NA--', 'sub_type': 'Tap', 'vrf_assign_acl_uuid': '--NA--', 'vmi_type': 'Virtual Machine', 'transport': 'Ethernet', 'logical_interface_uuid': '00000000-0000-0000-0000-000000000000', 'flood_unknown_unicast': 'false', 'physical_device': None, 'physical_interface': None, 'fixed_ip4_list': None, 'fixed_ip6_list': None, 'fat_flow_list': None, 'metadata_ip_active': 'Active', 'service_health_check_ip': '0.0.0.0', 'alias_ip_list': None, 'drop_new_flows': 'false', 'bridge_domain_list': [], 'vmi_tag_list': None, 'policy_set_acl_list': None, 'slo_list': None, 'vhostuser_mode': '0', 'si_other_end_vmi': '7620845f-f048-4d84-a813-8802df055b81', 'cfg_igmp_enable': 'false', 'igmp_enabled': 'false', 'max_flows': '0', 'policy_set_fwaas_list': None, 'bond_interface_list': None, 'mac_ip_learning_enable': 'false', 'mac_ip_list': []}
2025-02-04 07:06:27,307 - DEBUG - Starting Layer 2 verification in Agent
2025-02-04 07:06:27,319 - DEBUG - Layer 2 path is seen for VM MAC 02:02:c6:83:79:dd in agent 10.0.0.12
2025-02-04 07:06:27,320 - DEBUG - Active layer 2 route in agent is present for VMI tap02c68379-dd
2025-02-04 07:06:27,320 - DEBUG - L2 label(48) matches bw route table and interface table
2025-02-04 07:06:27,325 - DEBUG - VN default-domain:ctest-TestSvcRegr-71315967:ctest-left_vn-30527299 is found in Agent of node 10.0.0.12
2025-02-04 07:06:27,535 - DEBUG - VRF IDs of VN default-domain:ctest-TestSvcRegr-71315967:ctest-left_vn-30527299 is consistent in agent 10.0.0.12
2025-02-04 07:06:27,535 - DEBUG - Route for VM IP ['102.241.133.4'] is consistent in agent 10.0.0.12
2025-02-04 07:06:27,536 - DEBUG - VN default-domain:ctest-TestSvcRegr-71315967:ctest-left_vn-30527299 verification for VM ctest-pt_svm0-98700010 in Agent 10.0.0.12 passed
2025-02-04 07:06:27,731 - DEBUG - Starting all layer 2 verification in agent 10.0.0.12
2025-02-04 07:06:27,751 - DEBUG - Route for VM MAC 02:02:c6:83:79:dd is consistent in agent 10.0.0.12
2025-02-04 07:06:27,778 - DEBUG - VM ctest-pt_svm0-98700010 Tap interface: {'index': '5', 'name': 'tap34479a7a-37', 'uuid': '34479a7a-37c6-4d24-b319-6a569632a650', 'vrf_name': 'default-domain:ctest-TestSvcRegr-71315967:ctest-mgmt_vn-53004815:ctest-mgmt_vn-53004815', 'active': 'Active', 'ipv4_active': 'Active', 'l2_active': 'L2 Active', 'ip6_active': 'Ipv6 Inactive < no-ipv6-addr >', 'health_check_active': 'Active', 'dhcp_service': 'Enable', 'dhcp_service_v6': 'Disable', 'dns_service': 'Enable', 'type': 'vport', 'label': '39', 'l2_label': '43', 'vxlan_id': '8', 'vn_name': 'default-domain:ctest-TestSvcRegr-71315967:ctest-mgmt_vn-53004815', 'vm_uuid': '5ce9b69e-604d-4934-a7d4-8aac41f1d564', 'vm_name': 'ctest-pt_svm0-98700010', 'ip_addr': '123.151.63.131', 'mac_addr': '02:34:47:9a:7a:37', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.5', 'service_vlan_list': None, 'os_ifindex': '28', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestSvcRegr-71315967:34479a7a-37c6-4d24-b319-6a569632a650', 'sg_uuid_list': ['b7370563-5f99-438b-9cad-506223f1f800'], 'static_route_list': None, 'vm_project_uuid': 'd561d5a9-0e36-44b1-a09c-5e0b22f87fce', 'admin_state': 'Enabled', 'flow_key_idx': '43', 'allowed_address_pair_list': None, 'ip6_addr': '::', 'local_preference': '0', 'tx_vlan_id': '-1', 'rx_vlan_id': '-1', 'parent_interface': None, 'subnet': '--NA--', 'sub_type': 'Tap', 'vrf_assign_acl_uuid': '--NA--', 'vmi_type': 'Virtual Machine', 'transport': 'Ethernet', 'logical_interface_uuid': '00000000-0000-0000-0000-000000000000', 'flood_unknown_unicast': 'false', 'physical_device': None, 'physical_interface': None, 'fixed_ip4_list': None, 'fixed_ip6_list': None, 'fat_flow_list': None, 'metadata_ip_active': 'Active', 'service_health_check_ip': '0.0.0.0', 'alias_ip_list': None, 'drop_new_flows': 'false', 'bridge_domain_list': [], 'vmi_tag_list': None, 'policy_set_acl_list': None, 'slo_list': None, 'vhostuser_mode': '0', 'si_other_end_vmi': '00000000-0000-0000-0000-000000000000', 'cfg_igmp_enable': 'false', 'igmp_enabled': 'false', 'max_flows': '0', 'policy_set_fwaas_list': None, 'bond_interface_list': None, 'mac_ip_learning_enable': 'false', 'mac_ip_list': []}
2025-02-04 07:06:27,778 - DEBUG - Agent 10.0.0.12 vrf name: default-domain:ctest-TestSvcRegr-71315967:ctest-mgmt_vn-53004815:ctest-mgmt_vn-53004815
2025-02-04 07:06:27,783 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestSvcRegr-71315967:ctest-mgmt_vn-53004815:ctest-mgmt_vn-53004815', 'ucindex': '4', 'mcindex': '4', 'l2index': '4', 'source': 'Config; ', 'uc6index': '4', 'vn': 'default-domain:ctest-TestSvcRegr-71315967:ctest-mgmt_vn-53004815', 'table_label': '-1', 'vxlan_id': '8', 'evpnindex': '4', 'brindex': '4', 'mplsindex': '0', 'RD': '10.20.0.6:4', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]}
2025-02-04 07:06:27,949 - DEBUG - VM ctest-pt_svm0-98700010 : Tap interface tap34479a7a-37 is set to Active
2025-02-04 07:06:27,949 - DEBUG - Tap interface tap34479a7a-37 detail : {'index': '5', 'name': 'tap34479a7a-37', 'uuid': '34479a7a-37c6-4d24-b319-6a569632a650', 'vrf_name': 'default-domain:ctest-TestSvcRegr-71315967:ctest-mgmt_vn-53004815:ctest-mgmt_vn-53004815', 'active': 'Active', 'ipv4_active': 'Active', 'l2_active': 'L2 Active', 'ip6_active': 'Ipv6 Inactive < no-ipv6-addr >', 'health_check_active': 'Active', 'dhcp_service': 'Enable', 'dhcp_service_v6': 'Disable', 'dns_service': 'Enable', 'type': 'vport', 'label': '39', 'l2_label': '43', 'vxlan_id': '8', 'vn_name': 'default-domain:ctest-TestSvcRegr-71315967:ctest-mgmt_vn-53004815', 'vm_uuid': '5ce9b69e-604d-4934-a7d4-8aac41f1d564', 'vm_name': 'ctest-pt_svm0-98700010', 'ip_addr': '123.151.63.131', 'mac_addr': '02:34:47:9a:7a:37', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.5', 'service_vlan_list': None, 'os_ifindex': '28', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestSvcRegr-71315967:34479a7a-37c6-4d24-b319-6a569632a650', 'sg_uuid_list': ['b7370563-5f99-438b-9cad-506223f1f800'], 'static_route_list': None, 'vm_project_uuid': 'd561d5a9-0e36-44b1-a09c-5e0b22f87fce', 'admin_state': 'Enabled', 'flow_key_idx': '43', 'allowed_address_pair_list': None, 'ip6_addr': '::', 'local_preference': '0', 'tx_vlan_id': '-1', 'rx_vlan_id': '-1', 'parent_interface': None, 'subnet': '--NA--', 'sub_type': 'Tap', 'vrf_assign_acl_uuid': '--NA--', 'vmi_type': 'Virtual Machine', 'transport': 'Ethernet', 'logical_interface_uuid': '00000000-0000-0000-0000-000000000000', 'flood_unknown_unicast': 'false', 'physical_device': None, 'physical_interface': None, 'fixed_ip4_list': None, 'fixed_ip6_list': None, 'fat_flow_list': None, 'metadata_ip_active': 'Active', 'service_health_check_ip': '0.0.0.0', 'alias_ip_list': None, 'drop_new_flows': 'false', 'bridge_domain_list': [], 'vmi_tag_list': None, 'policy_set_acl_list': None, 'slo_list': None, 'vhostuser_mode': '0', 'si_other_end_vmi': '00000000-0000-0000-0000-000000000000', 'cfg_igmp_enable': 'false', 'igmp_enabled': 'false', 'max_flows': '0', 'policy_set_fwaas_list': None, 'bond_interface_list': None, 'mac_ip_learning_enable': 'false', 'mac_ip_list': []}
2025-02-04 07:06:28,143 - DEBUG - Starting Layer 2 verification in Agent
2025-02-04 07:06:28,154 - DEBUG - Layer 2 path is seen for VM MAC 02:34:47:9a:7a:37 in agent 10.0.0.12
2025-02-04 07:06:28,154 - DEBUG - Active layer 2 route in agent is present for VMI tap34479a7a-37
2025-02-04 07:06:28,154 - DEBUG - L2 label(43) matches bw route table and interface table
2025-02-04 07:06:28,160 - DEBUG - VN default-domain:ctest-TestSvcRegr-71315967:ctest-mgmt_vn-53004815 is found in Agent of node 10.0.0.12
2025-02-04 07:06:28,387 - DEBUG - VRF IDs of VN default-domain:ctest-TestSvcRegr-71315967:ctest-mgmt_vn-53004815 is consistent in agent 10.0.0.12
2025-02-04 07:06:28,388 - DEBUG - Route for VM IP ['123.151.63.131'] is consistent in agent 10.0.0.12
2025-02-04 07:06:28,388 - DEBUG - VN default-domain:ctest-TestSvcRegr-71315967:ctest-mgmt_vn-53004815 verification for VM ctest-pt_svm0-98700010 in Agent 10.0.0.12 passed
2025-02-04 07:06:28,583 - DEBUG - Starting all layer 2 verification in agent 10.0.0.12
2025-02-04 07:06:28,594 - DEBUG - Route for VM MAC 02:34:47:9a:7a:37 is consistent in agent 10.0.0.12
2025-02-04 07:06:28,614 - DEBUG - VM ctest-pt_svm0-98700010 Tap interface: {'index': '7', 'name': 'tap7620845f-f0', 'uuid': '7620845f-f048-4d84-a813-8802df055b81', 'vrf_name': 'default-domain:ctest-TestSvcRegr-71315967:ctest-right_vn-89438892:ctest-right_vn-89438892', 'active': 'Active', 'ipv4_active': 'Active', 'l2_active': 'L2 Active', 'ip6_active': 'Ipv6 Inactive < no-ipv6-addr >', 'health_check_active': 'Active', 'dhcp_service': 'Enable', 'dhcp_service_v6': 'Disable', 'dns_service': 'Enable', 'type': 'vport', 'label': '49', 'l2_label': '53', 'vxlan_id': '10', 'vn_name': 'default-domain:ctest-TestSvcRegr-71315967:ctest-right_vn-89438892', 'vm_uuid': '5ce9b69e-604d-4934-a7d4-8aac41f1d564', 'vm_name': 'ctest-pt_svm0-98700010', 'ip_addr': '142.147.16.68', 'mac_addr': '02:76:20:84:5f:f0', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.7', 'service_vlan_list': None, 'os_ifindex': '30', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestSvcRegr-71315967:7620845f-f048-4d84-a813-8802df055b81', 'sg_uuid_list': ['b7370563-5f99-438b-9cad-506223f1f800'], 'static_route_list': None, 'vm_project_uuid': 'd561d5a9-0e36-44b1-a09c-5e0b22f87fce', 'admin_state': 'Enabled', 'flow_key_idx': '28', 'allowed_address_pair_list': None, 'ip6_addr': '::', 'local_preference': '0', 'tx_vlan_id': '-1', 'rx_vlan_id': '-1', 'parent_interface': None, 'subnet': '--NA--', 'sub_type': 'Tap', 'vrf_assign_acl_uuid': '--NA--', 'vmi_type': 'Virtual Machine', 'transport': 'Ethernet', 'logical_interface_uuid': '00000000-0000-0000-0000-000000000000', 'flood_unknown_unicast': 'false', 'physical_device': None, 'physical_interface': None, 'fixed_ip4_list': None, 'fixed_ip6_list': None, 'fat_flow_list': None, 'metadata_ip_active': 'Active', 'service_health_check_ip': '0.0.0.0', 'alias_ip_list': None, 'drop_new_flows': 'false', 'bridge_domain_list': [], 'vmi_tag_list': None, 'policy_set_acl_list': None, 'slo_list': None, 'vhostuser_mode': '0', 'si_other_end_vmi': '02c68379-dda8-443a-a925-1ce4e612c268', 'cfg_igmp_enable': 'false', 'igmp_enabled': 'false', 'max_flows': '0', 'policy_set_fwaas_list': None, 'bond_interface_list': None, 'mac_ip_learning_enable': 'false', 'mac_ip_list': []}
2025-02-04 07:06:28,615 - DEBUG - Agent 10.0.0.12 vrf name: default-domain:ctest-TestSvcRegr-71315967:ctest-right_vn-89438892:ctest-right_vn-89438892
2025-02-04 07:06:28,620 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestSvcRegr-71315967:ctest-right_vn-89438892:ctest-right_vn-89438892', 'ucindex': '3', 'mcindex': '3', 'l2index': '3', 'source': 'Config; ', 'uc6index': '3', 'vn': 'default-domain:ctest-TestSvcRegr-71315967:ctest-right_vn-89438892', 'table_label': '-1', 'vxlan_id': '10', 'evpnindex': '3', 'brindex': '3', 'mplsindex': '0', 'RD': '10.20.0.6:3', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]}
2025-02-04 07:06:28,830 - DEBUG - VM ctest-pt_svm0-98700010 : Tap interface tap7620845f-f0 is set to Active
2025-02-04 07:06:28,831 - DEBUG - Tap interface tap7620845f-f0 detail : {'index': '7', 'name': 'tap7620845f-f0', 'uuid': '7620845f-f048-4d84-a813-8802df055b81', 'vrf_name': 'default-domain:ctest-TestSvcRegr-71315967:ctest-right_vn-89438892:ctest-right_vn-89438892', 'active': 'Active', 'ipv4_active': 'Active', 'l2_active': 'L2 Active', 'ip6_active': 'Ipv6 Inactive < no-ipv6-addr >', 'health_check_active': 'Active', 'dhcp_service': 'Enable', 'dhcp_service_v6': 'Disable', 'dns_service': 'Enable', 'type': 'vport', 'label': '49', 'l2_label': '53', 'vxlan_id': '10', 'vn_name': 'default-domain:ctest-TestSvcRegr-71315967:ctest-right_vn-89438892', 'vm_uuid': '5ce9b69e-604d-4934-a7d4-8aac41f1d564', 'vm_name': 'ctest-pt_svm0-98700010', 'ip_addr': '142.147.16.68', 'mac_addr': '02:76:20:84:5f:f0', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.7', 'service_vlan_list': None, 'os_ifindex': '30', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestSvcRegr-71315967:7620845f-f048-4d84-a813-8802df055b81', 'sg_uuid_list': ['b7370563-5f99-438b-9cad-506223f1f800'], 'static_route_list': None, 'vm_project_uuid': 'd561d5a9-0e36-44b1-a09c-5e0b22f87fce', 'admin_state': 'Enabled', 'flow_key_idx': '28', 'allowed_address_pair_list': None, 'ip6_addr': '::', 'local_preference': '0', 'tx_vlan_id': '-1', 'rx_vlan_id': '-1', 'parent_interface': None, 'subnet': '--NA--', 'sub_type': 'Tap', 'vrf_assign_acl_uuid': '--NA--', 'vmi_type': 'Virtual Machine', 'transport': 'Ethernet', 'logical_interface_uuid': '00000000-0000-0000-0000-000000000000', 'flood_unknown_unicast': 'false', 'physical_device': None, 'physical_interface': None, 'fixed_ip4_list': None, 'fixed_ip6_list': None, 'fat_flow_list': None, 'metadata_ip_active': 'Active', 'service_health_check_ip': '0.0.0.0', 'alias_ip_list': None, 'drop_new_flows': 'false', 'bridge_domain_list': [], 'vmi_tag_list': None, 'policy_set_acl_list': None, 'slo_list': None, 'vhostuser_mode': '0', 'si_other_end_vmi': '02c68379-dda8-443a-a925-1ce4e612c268', 'cfg_igmp_enable': 'false', 'igmp_enabled': 'false', 'max_flows': '0', 'policy_set_fwaas_list': None, 'bond_interface_list': None, 'mac_ip_learning_enable': 'false', 'mac_ip_list': []}
2025-02-04 07:06:29,023 - DEBUG - Starting Layer 2 verification in Agent
2025-02-04 07:06:29,037 - DEBUG - Layer 2 path is seen for VM MAC 02:76:20:84:5f:f0 in agent 10.0.0.12
2025-02-04 07:06:29,037 - DEBUG - Active layer 2 route in agent is present for VMI tap7620845f-f0
2025-02-04 07:06:29,037 - DEBUG - L2 label(53) matches bw route table and interface table
2025-02-04 07:06:29,043 - DEBUG - VN default-domain:ctest-TestSvcRegr-71315967:ctest-right_vn-89438892 is found in Agent of node 10.0.0.12
2025-02-04 07:06:29,261 - DEBUG - VRF IDs of VN default-domain:ctest-TestSvcRegr-71315967:ctest-right_vn-89438892 is consistent in agent 10.0.0.12
2025-02-04 07:06:29,261 - DEBUG - Route for VM IP ['142.147.16.68'] is consistent in agent 10.0.0.12
2025-02-04 07:06:29,261 - DEBUG - VN default-domain:ctest-TestSvcRegr-71315967:ctest-right_vn-89438892 verification for VM ctest-pt_svm0-98700010 in Agent 10.0.0.12 passed
2025-02-04 07:06:29,467 - DEBUG - Starting all layer 2 verification in agent 10.0.0.12
2025-02-04 07:06:29,480 - DEBUG - Route for VM MAC 02:76:20:84:5f:f0 is consistent in agent 10.0.0.12
2025-02-04 07:06:29,480 - INFO - VM ctest-pt_svm0-98700010 verifications in Compute nodes passed
2025-02-04 07:06:29,480 - DEBUG - The SVMs in the SI are : []
2025-02-04 07:06:29,480 - DEBUG - Requesting: http://10.0.0.12:8082/virtual-machine/5ce9b69e-604d-4934-a7d4-8aac41f1d564
2025-02-04 07:06:29,489 - DEBUG - Service VM for SI 'ctest-si-11363483' is launched
2025-02-04 07:06:29,489 - DEBUG - Requesting: http://10.0.0.12:8082/domains
2025-02-04 07:06:29,496 - DEBUG - Requesting: http://10.0.0.12:8082/domain/ba13f9de-04b3-4f3c-949e-421777e97252
2025-02-04 07:06:29,508 - DEBUG - Requesting: http://10.0.0.12:8082/project/d561d5a9-0e36-44b1-a09c-5e0b22f87fce
2025-02-04 07:06:29,588 - DEBUG - Requesting: http://10.0.0.12:8082/service-instance/edf1c41f-1b5d-4461-870a-f69bd506172f
2025-02-04 07:06:29,597 - DEBUG - SI ctest-si-11363483 has Port Tuple: ['ctest-port_tuple-19594891']
2025-02-04 07:06:29,707 - INFO - VMI and/or RI refs are present for VN default-domain:ctest-TestSvcRegr-71315967:ctest-left_vn-30527299
2025-02-04 07:06:29,855 - INFO - VMI and/or RI refs are present for VN default-domain:ctest-TestSvcRegr-71315967:ctest-right_vn-89438892
2025-02-04 07:06:30,018 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-02-04 07:06:30,028 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-02-04 07:06:30,028 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-02-04 07:06:30,034 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-02-04 07:06:30,034 - INFO - Route to 142.147.16.67 seen in VRF:5 on cn-jenkins-deploy-platform-ansible-os-2684-1, and SI ctest-si-11363483 is seen as the NH
2025-02-04 07:06:30,194 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-02-04 07:06:30,202 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-02-04 07:06:30,202 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-02-04 07:06:30,208 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-02-04 07:06:30,208 - INFO - Route to 102.241.133.3 seen in VRF:6 on cn-jenkins-deploy-platform-ansible-os-2684-1, and SI ctest-si-11363483 is seen as the NH
2025-02-04 07:06:30,208 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-02-04 07:06:30,208 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-02-04 07:06:30,208 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 142.147.16.67, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.12, gateway password: c0ntrail123
2025-02-04 07:06:30,208 - DEBUG - ping -s 56 -c 3 -W 1 142.147.16.67
2025-02-04 07:06:34,183 - DEBUG - PING 142.147.16.67 (142.147.16.67): 56 data bytes
64 bytes from 142.147.16.67: seq=0 ttl=61 time=5.145 ms
64 bytes from 142.147.16.67: seq=1 ttl=61 time=1.757 ms
64 bytes from 142.147.16.67: seq=2 ttl=61 time=1.500 ms
--- 142.147.16.67 ping statistics ---
3 packets transmitted, 3 packets received, 0% packet loss
round-trip min/avg/max = 1.500/2.800/5.145 ms
2025-02-04 07:06:34,183 - INFO - Ping to IP 142.147.16.67 from VM ctest-left_vm-92928521 passed
2025-02-04 07:06:34,183 - INFO - Dettaching policy ctest-policy-43735315 from vn ctest-right_vn-89438892
2025-02-04 07:06:34,399 - INFO - Dettaching policy ctest-policy-43735315 from vn ctest-left_vn-30527299
2025-02-04 07:06:34,674 - INFO - Deleted policy ctest-policy-43735315
2025-02-04 07:06:34,674 - DEBUG - Deleting service instance: ['default-domain', 'ctest-TestSvcRegr-71315967', 'ctest-si-11363483']
2025-02-04 07:06:34,773 - INFO - Deleted SI ['default-domain', 'ctest-TestSvcRegr-71315967', 'ctest-si-11363483']
2025-02-04 07:06:34,774 - DEBUG - Requesting: http://10.0.0.12:8082/domains
2025-02-04 07:06:34,780 - DEBUG - Requesting: http://10.0.0.12:8082/domain/ba13f9de-04b3-4f3c-949e-421777e97252
2025-02-04 07:06:34,797 - DEBUG - Requesting: http://10.0.0.12:8082/project/d561d5a9-0e36-44b1-a09c-5e0b22f87fce
2025-02-04 07:06:34,873 - DEBUG - Service instance ctest-si-11363483 removed from api server
2025-02-04 07:06:34,873 - DEBUG - Requesting: http://10.0.0.12:8082/virtual-machine/5ce9b69e-604d-4934-a7d4-8aac41f1d564
2025-02-04 07:06:34,884 - DEBUG - All Service VMs unlinked from SI ctest-si-11363483
2025-02-04 07:06:34,890 - DEBUG - 0 svc intances found in all projects. They are []
2025-02-04 07:06:34,890 - DEBUG - 0 svc intances found in current project. They are []
2025-02-04 07:06:34,943 - DEBUG - 0 svc intances found in all projects. They are []
2025-02-04 07:06:34,943 - DEBUG - 0 svc intances found in current project. They are []
2025-02-04 07:06:34,943 - INFO - Deleting VM ctest-pt_svm0-98700010
2025-02-04 07:06:35,025 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-45046648']
2025-02-04 07:06:35,115 - INFO - Service template: ['default-domain', 'ctest-service_template_1-45046648'] deleted successfully.
2025-02-04 07:06:35,115 - INFO - Deleting VM ctest-right_vm-11742909
2025-02-04 07:06:35,203 - INFO - Deleting VM ctest-left_vm-92928521
2025-02-04 07:06:35,289 - INFO - Deleting VN ctest-right_vn-89438892
2025-02-04 07:06:35,334 - DEBUG - VN 47814495-89aa-48c0-8bec-46bbdd927ed2 still in use: Unable to complete operation on network 47814495-89aa-48c0-8bec-46bbdd927ed2. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-58519f1d-17ad-419c-9667-c0ddad89c76a']
2025-02-04 07:06:35,334 - WARNING - Deleting VN ctest-right_vn-89438892 failed..Will retry
2025-02-04 07:06:37,446 - DEBUG - Response for deleting network ()
2025-02-04 07:06:37,447 - DEBUG - Requesting: http://10.0.0.12:8082/routing-instance/854bc710-7760-4358-86b6-0bca76037628
2025-02-04 07:06:37,453 - DEBUG - Response Code: 404
2025-02-04 07:06:37,454 - DEBUG - Requesting: http://10.0.0.12:8082/domains
2025-02-04 07:06:37,461 - DEBUG - Requesting: http://10.0.0.12:8082/domain/ba13f9de-04b3-4f3c-949e-421777e97252
2025-02-04 07:06:37,519 - DEBUG - Requesting: http://10.0.0.12:8082/project/d561d5a9-0e36-44b1-a09c-5e0b22f87fce
2025-02-04 07:06:37,595 - INFO - Validated that VN ctest-right_vn-89438892 is not found in API Server
2025-02-04 07:06:37,610 - DEBUG - VN ctest-right_vn-89438892 is not present in Agent 10.0.0.12
2025-02-04 07:06:37,610 - INFO - Validated that VN ctest-right_vn-89438892 is not in any agent
2025-02-04 07:06:37,631 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-89438892 info
2025-02-04 07:06:37,631 - INFO - Deleting VN ctest-left_vn-30527299
2025-02-04 07:06:37,750 - DEBUG - Response for deleting network ()
2025-02-04 07:06:37,750 - DEBUG - Requesting: http://10.0.0.12:8082/routing-instance/3c35b22b-5ed0-475a-aaaa-38877480142e
2025-02-04 07:06:37,755 - DEBUG - Response Code: 404
2025-02-04 07:06:37,755 - DEBUG - Requesting: http://10.0.0.12:8082/domains
2025-02-04 07:06:37,761 - DEBUG - Requesting: http://10.0.0.12:8082/domain/ba13f9de-04b3-4f3c-949e-421777e97252
2025-02-04 07:06:37,820 - DEBUG - Requesting: http://10.0.0.12:8082/project/d561d5a9-0e36-44b1-a09c-5e0b22f87fce
2025-02-04 07:06:37,896 - INFO - Validated that VN ctest-left_vn-30527299 is not found in API Server
2025-02-04 07:06:37,907 - DEBUG - VN ctest-left_vn-30527299 is not present in Agent 10.0.0.12
2025-02-04 07:06:37,907 - INFO - Validated that VN ctest-left_vn-30527299 is not in any agent
2025-02-04 07:06:37,923 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-30527299 info
2025-02-04 07:06:37,924 - INFO - Deleting VN ctest-mgmt_vn-53004815
2025-02-04 07:06:38,029 - DEBUG - Response for deleting network ()
2025-02-04 07:06:38,029 - DEBUG - Requesting: http://10.0.0.12:8082/routing-instance/52e604fc-6187-4b21-8cbb-b994d44bfe8b
2025-02-04 07:06:38,036 - DEBUG - Response Code: 404
2025-02-04 07:06:38,037 - DEBUG - Requesting: http://10.0.0.12:8082/domains
2025-02-04 07:06:38,043 - DEBUG - Requesting: http://10.0.0.12:8082/domain/ba13f9de-04b3-4f3c-949e-421777e97252
2025-02-04 07:06:38,100 - DEBUG - Requesting: http://10.0.0.12:8082/project/d561d5a9-0e36-44b1-a09c-5e0b22f87fce
2025-02-04 07:06:38,172 - INFO - Validated that VN ctest-mgmt_vn-53004815 is not found in API Server
2025-02-04 07:06:38,183 - DEBUG - VN ctest-mgmt_vn-53004815 is not present in Agent 10.0.0.12
2025-02-04 07:06:38,184 - INFO - Validated that VN ctest-mgmt_vn-53004815 is not in any agent
2025-02-04 07:06:38,203 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-53004815 info
2025-02-04 07:06:38,458 - DEBUG - No XMPP flaps were noticed during the test
2025-02-04 07:06:38,458 - INFO -
2025-02-04 07:06:38,458 - INFO - END TEST : test_svc_in_network_datapath : PASSED[0:01:12]
2025-02-04 07:06:38,458 - INFO - --------------------------------------------------------------------------------
2025-02-04 07:06:39,126 - INFO - Deleted project: ctest-TestSvcRegr-71315967, ID : d561d5a9-0e36-44b1-a09c-5e0b22f87fce