], ['ctest-vnet0-18945557']
2026-11-09 03:12:51,138 - DEBUG - Traslation of quantum rules to ACES format
2026-11-09 03:12:51,138 - DEBUG - vn is ctest-vnet0-18945557, vn_policy is policy0
2026-11-09 03:12:51,139 - DEBUG - VN: ctest-vnet0-18945557, expected ACE's is
2026-11-09 03:12:51,139 - DEBUG - {"ace_id": "1", "action_l": [{"alert": false, "apply_service": [], "assign_routing_instance": null, "gateway_name": null, "host_based_service": false, "log": false, "mirror_to": null, "qos_action": null, "service_properties": null, "simple_action": "pass"}], "dst": "default-domain:ctest-TestDetailedPolicy1-25260043:ctest-vnet0-18945557", "dst_port_l": {"max": "65535", "min": "0"}, "proto_l": {"max": "tcp", "min": "tcp"}, "rule_type": "Terminal", "src": "default-domain:ctest-TestDetailedPolicy1-25260043:ctest-vnet1-49712780", "src_port_l": {"max": "65535", "min": "0"}}
2026-11-09 03:12:51,139 - DEBUG - {"ace_id": "2", "action_l": [{"alert": false, "apply_service": [], "assign_routing_instance": null, "gateway_name": null, "host_based_service": false, "log": false, "mirror_to": null, "qos_action": null, "service_properties": null, "simple_action": "pass"}], "dst": "default-domain:ctest-TestDetailedPolicy1-25260043:ctest-vnet1-49712780", "dst_port_l": {"max": "65535", "min": "0"}, "proto_l": {"max": "tcp", "min": "tcp"}, "rule_type": "Terminal", "src": "default-domain:ctest-TestDetailedPolicy1-25260043:ctest-vnet0-18945557", "src_port_l": {"max": "65535", "min": "0"}}
2026-11-09 03:12:51,139 - DEBUG - {"ace_id": "3", "action_l": [{"alert": false, "apply_service": [], "assign_routing_instance": null, "gateway_name": null, "host_based_service": false, "log": false, "mirror_to": null, "qos_action": null, "service_properties": null, "simple_action": "pass"}], "dst": "default-domain:ctest-TestDetailedPolicy1-25260043:ctest-vnet0-18945557", "dst_port_l": {"max": "65535", "min": "0"}, "proto_l": {"max": "udp", "min": "udp"}, "rule_type": "Terminal", "src": "default-domain:ctest-TestDetailedPolicy1-25260043:ctest-vnet1-49712780", "src_port_l": {"max": "65535", "min": "0"}}
2026-11-09 03:12:51,139 - DEBUG - {"ace_id": "4", "action_l": [{"alert": false, "apply_service": [], "assign_routing_instance": null, "gateway_name": null, "host_based_service": false, "log": false, "mirror_to": null, "qos_action": null, "service_properties": null, "simple_action": "pass"}], "dst": "default-domain:ctest-TestDetailedPolicy1-25260043:ctest-vnet1-49712780", "dst_port_l": {"max": "65535", "min": "0"}, "proto_l": {"max": "udp", "min": "udp"}, "rule_type": "Terminal", "src": "default-domain:ctest-TestDetailedPolicy1-25260043:ctest-vnet0-18945557", "src_port_l": {"max": "65535", "min": "0"}}
2026-11-09 03:12:51,139 - DEBUG - {"ace_id": "5", "action_l": [{"alert": false, "apply_service": [], "assign_routing_instance": null, "gateway_name": null, "host_based_service": false, "log": false, "mirror_to": null, "qos_action": null, "service_properties": null, "simple_action": "deny"}], "dst": "default-domain:ctest-TestDetailedPolicy1-25260043:ctest-vnet0-18945557", "dst_port_l": {"max": "65535", "min": "0"}, "proto_l": {"max": "icmp", "min": "icmp"}, "rule_type": "Terminal", "src": "default-domain:ctest-TestDetailedPolicy1-25260043:ctest-vnet1-49712780", "src_port_l": {"max": "65535", "min": "0"}}
2026-11-09 03:12:51,139 - DEBUG - {"ace_id": "6", "action_l": [{"alert": false, "apply_service": [], "assign_routing_instance": null, "gateway_name": null, "host_based_service": false, "log": false, "mirror_to": null, "qos_action": null, "service_properties": null, "simple_action": "deny"}], "dst": "default-domain:ctest-TestDetailedPolicy1-25260043:ctest-vnet1-49712780", "dst_port_l": {"max": "65535", "min": "0"}, "proto_l": {"max": "icmp", "min": "icmp"}, "rule_type": "Terminal", "src": "default-domain:ctest-TestDetailedPolicy1-25260043:ctest-vnet0-18945557", "src_port_l": {"max": "65535", "min": "0"}}
2026-11-09 03:12:51,139 - DEBUG - {"ace_id": "7", "action_l": ["pass"], "dst": "default-domain:ctest-TestDetailedPolicy1-25260043:ctest-vnet0-18945557", "dst_port_l": {"max": "65535", "min": "0"}, "proto_l": {"max": "255", "min": "0"}, "rule_type": "Terminal", "src": "default-domain:ctest-TestDetailedPolicy1-25260043:ctest-vnet0-18945557", "src_port_l": {"max": "65535", "min": "0"}}
2026-11-09 03:12:51,139 - DEBUG - {"ace_id": "8", "action_l": ["deny"], "dst": "default-domain:ctest-TestDetailedPolicy1-25260043:ctest-vnet0-18945557", "dst_port_l": {"max": "65535", "min": "0"}, "proto_l": {"max": "255", "min": "0"}, "rule_type": "Terminal", "src": "default-domain:ctest-TestDetailedPolicy1-25260043:ctest-vnet1-49712780", "src_port_l": {"max": "65535", "min": "0"}}
2026-11-09 03:12:51,139 - DEBUG - {"ace_id": "9", "action_l": ["deny"], "dst": "default-domain:ctest-TestDetailedPolicy1-25260043:ctest-vnet1-49712780", "dst_port_l": {"max": "65535", "min": "0"}, "proto_l": {"max": "255", "min": "0"}, "rule_type": "Terminal", "src": "default-domain:ctest-TestDetailedPolicy1-25260043:ctest-vnet0-18945557", "src_port_l": {"max": "65535", "min": "0"}}
2026-11-09 03:12:51,139 - DEBUG - {"ace_id": "10", "action_l": ["pass"], "dst": "any", "dst_port_l": {"max": "65535", "min": "0"}, "proto_l": {"max": "255", "min": "0"}, "rule_type": "Terminal", "src": "any", "src_port_l": {"max": "65535", "min": "0"}}
2026-11-09 03:12:51,139 - DEBUG - Verify rules expected in CN if VN-VM in CN
2026-11-09 03:12:51,139 - DEBUG - CN: 10.0.0.250, Check for expected data
2026-11-09 03:12:51,152 - DEBUG - Verify rules expected in CN if VN-VM in CN
2026-11-09 03:12:51,152 - DEBUG - CN: 10.0.0.38, Check for expected data
2026-11-09 03:12:51,188 - DEBUG - ----------------------------------------
2026-11-09 03:12:51,188 - DEBUG - CN: 10.0.0.38, VN: ctest-vnet0-18945557, result of expected rules check passed
2026-11-09 03:12:51,188 - INFO - Validated the rules for VM:ctest-vmc0-07333144 with attached policy:['policy0'] and vn:ctest-vnet0-18945557
2026-11-09 03:12:51,188 - DEBUG - Verify policy rules for other vn if it is present
2026-11-09 03:12:51,188 - INFO - Policy rules comparison with system for all Virtual networks are done
2026-11-09 03:12:51,188 - INFO - Setup completed with result True
2026-11-09 03:12:51,350 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': '198d60ee-ae04-4623-b87e-b786cf569899', 'name': 'ctest-vnet0-18945557', 'tenant_id': 'ceb5b5114d914e8f98d9c174291a861c', 'project_id': 'ceb5b5114d914e8f98d9c174291a861c', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'tags': [], 'subnets': ['124ba59a-5c83-4839-9792-33dc9371ef30', 'cf4d2917-bd39-45ac-ad96-5d7f60c4eb5e'], 'fq_name': ['default-domain', 'ctest-TestDetailedPolicy1-25260043', 'ctest-vnet0-18945557'], 'policys': [['default-domain', 'ctest-TestDetailedPolicy1-25260043', 'policy1']], 'subnet_ipam': [{'subnet_cidr': '10.1.1.0/24', 'ipam_fq_name': ['default-domain', 'ctest-TestDetailedPolicy1-25260043', 'ctest-TestDetailedPolicy1-25260043-default-ipam']}, {'subnet_cidr': '11.1.1.0/24', 'ipam_fq_name': ['default-domain', 'ctest-TestDetailedPolicy1-25260043', 'ctest-TestDetailedPolicy1-25260043-default-ipam']}], 'port_security_enabled': True, 'description': ''}}
2026-11-09 03:12:56,457 - INFO - new policy list of vn ctest-vnet0-18945557 is policy1
2026-11-09 03:12:56,458 - INFO - Starting Verifications after policy for ctest-vnet0-18945557 updated to policy1
2026-11-09 03:12:56,458 - INFO - policy in effect is : [{'direction': '<>', 'protocol': 'tcp', 'dest_network': 'ctest-vnet0-18945557', 'source_network': 'ctest-vnet1-49712780', 'dst_ports': 'any', 'simple_action': 'pass', 'src_ports': 'any'}, {'direction': '<>', 'protocol': 'udp', 'dest_network': 'ctest-vnet0-18945557', 'source_network': 'ctest-vnet1-49712780', 'dst_ports': 'any', 'simple_action': 'pass', 'src_ports': 'any'}, {'direction': '<>', 'protocol': 'icmp', 'dest_network': 'ctest-vnet0-18945557', 'source_network': 'ctest-vnet1-49712780', 'dst_ports': 'any', 'simple_action': 'pass', 'src_ports': 'any'}]
2026-11-09 03:12:56,458 - INFO - matching_rule_action: {'tcp': 'pass', 'udp': 'pass', 'icmp': 'pass'}
2026-11-09 03:12:56,458 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-11-09 03:12:56,458 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 12.1.1.3, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.38, gateway password: c0ntrail123
2026-11-09 03:12:56,459 - DEBUG - ping -s 56 -c 3 -W 1 12.1.1.3
2026-11-09 03:13:05,529 - DEBUG - PING 12.1.1.3 (12.1.1.3) 56(84) bytes of data.
64 bytes from 12.1.1.3: icmp_req=1 ttl=63 time=22.2 ms
64 bytes from 12.1.1.3: icmp_req=2 ttl=63 time=4.11 ms
64 bytes from 12.1.1.3: icmp_req=3 ttl=63 time=3.63 ms
--- 12.1.1.3 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2005ms
rtt min/avg/max/mdev = 3.631/9.988/22.220/8.651 ms
2026-11-09 03:13:05,530 - INFO - Ping to IP 12.1.1.3 from VM ctest-vmc0-07333144 passed
2026-11-09 03:13:05,530 - INFO - vm ping test result after policy for ctest-vnet0-18945557 updated to policy1 is: True
2026-11-09 03:13:05,665 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': '198d60ee-ae04-4623-b87e-b786cf569899', 'name': 'ctest-vnet0-18945557', 'tenant_id': 'ceb5b5114d914e8f98d9c174291a861c', 'project_id': 'ceb5b5114d914e8f98d9c174291a861c', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'tags': [], 'subnets': ['124ba59a-5c83-4839-9792-33dc9371ef30', 'cf4d2917-bd39-45ac-ad96-5d7f60c4eb5e'], 'fq_name': ['default-domain', 'ctest-TestDetailedPolicy1-25260043', 'ctest-vnet0-18945557'], 'policys': [['default-domain', 'ctest-TestDetailedPolicy1-25260043', 'policy1000']], 'subnet_ipam': [{'subnet_cidr': '10.1.1.0/24', 'ipam_fq_name': ['default-domain', 'ctest-TestDetailedPolicy1-25260043', 'ctest-TestDetailedPolicy1-25260043-default-ipam']}, {'subnet_cidr': '11.1.1.0/24', 'ipam_fq_name': ['default-domain', 'ctest-TestDetailedPolicy1-25260043', 'ctest-TestDetailedPolicy1-25260043-default-ipam']}], 'port_security_enabled': True, 'description': ''}}
2026-11-09 03:13:10,806 - INFO - new policy list of vn ctest-vnet0-18945557 is policy1000
2026-11-09 03:13:10,807 - INFO - Starting Verifications after policy for ctest-vnet0-18945557 updated to policy1000
2026-11-09 03:13:10,807 - INFO - policy in effect is : []
2026-11-09 03:13:10,807 - INFO - matching_rule_action: {'icmp': 'deny'}
2026-11-09 03:13:10,808 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-11-09 03:13:10,808 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 12.1.1.3, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.38, gateway password: c0ntrail123
2026-11-09 03:13:10,808 - DEBUG - ping -s 56 -c 3 -W 1 12.1.1.3
2026-11-09 03:13:14,157 - DEBUG - PING 12.1.1.3 (12.1.1.3) 56(84) bytes of data.
--- 12.1.1.3 ping statistics ---
3 packets transmitted, 0 received, 100% packet loss, time 2003ms
2026-11-09 03:13:14,157 - WARNING - Ping to IP 12.1.1.3 from VM ctest-vmc0-07333144 failed
2026-11-09 03:13:14,157 - INFO - vm ping test result after policy for ctest-vnet0-18945557 updated to policy1000 is: True
2026-11-09 03:13:14,320 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': '198d60ee-ae04-4623-b87e-b786cf569899', 'name': 'ctest-vnet0-18945557', 'tenant_id': 'ceb5b5114d914e8f98d9c174291a861c', 'project_id': 'ceb5b5114d914e8f98d9c174291a861c', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'tags': [], 'subnets': ['124ba59a-5c83-4839-9792-33dc9371ef30', 'cf4d2917-bd39-45ac-ad96-5d7f60c4eb5e'], 'fq_name': ['default-domain', 'ctest-TestDetailedPolicy1-25260043', 'ctest-vnet0-18945557'], 'policys': [['default-domain', 'ctest-TestDetailedPolicy1-25260043', 'policy2']], 'subnet_ipam': [{'subnet_cidr': '10.1.1.0/24', 'ipam_fq_name': ['default-domain', 'ctest-TestDetailedPolicy1-25260043', 'ctest-TestDetailedPolicy1-25260043-default-ipam']}, {'subnet_cidr': '11.1.1.0/24', 'ipam_fq_name': ['default-domain', 'ctest-TestDetailedPolicy1-25260043', 'ctest-TestDetailedPolicy1-25260043-default-ipam']}], 'port_security_enabled': True, 'description': ''}}
2026-11-09 03:13:19,421 - INFO - new policy list of vn ctest-vnet0-18945557 is policy2
2026-11-09 03:13:19,421 - INFO - Starting Verifications after policy for ctest-vnet0-18945557 updated to policy2
2026-11-09 03:13:19,421 - INFO - policy in effect is : [{'direction': '<>', 'protocol': 'tcp', 'dest_network': 'ctest-vnet0-18945557', 'source_network': 'ctest-vnet1-49712780', 'dst_ports': 'any', 'simple_action': 'pass', 'src_ports': 'any'}, {'direction': '<>', 'protocol': 'udp', 'dest_network': 'ctest-vnet0-18945557', 'source_network': 'ctest-vnet1-49712780', 'dst_ports': 'any', 'simple_action': 'deny', 'src_ports': 'any'}, {'direction': '<>', 'protocol': 'icmp', 'dest_network': 'ctest-vnet0-18945557', 'source_network': 'ctest-vnet1-49712780', 'dst_ports': 'any', 'simple_action': 'deny', 'src_ports': 'any'}]
2026-11-09 03:13:19,421 - INFO - matching_rule_action: {'tcp': 'pass', 'udp': 'deny', 'icmp': 'deny'}
2026-11-09 03:13:19,421 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-11-09 03:13:19,422 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 12.1.1.3, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.38, gateway password: c0ntrail123
2026-11-09 03:13:19,422 - DEBUG - ping -s 56 -c 3 -W 1 12.1.1.3
2026-11-09 03:13:22,782 - DEBUG - PING 12.1.1.3 (12.1.1.3) 56(84) bytes of data.
--- 12.1.1.3 ping statistics ---
3 packets transmitted, 0 received, 100% packet loss, time 2000ms
2026-11-09 03:13:22,782 - WARNING - Ping to IP 12.1.1.3 from VM ctest-vmc0-07333144 failed
2026-11-09 03:13:22,782 - INFO - vm ping test result after policy for ctest-vnet0-18945557 updated to policy2 is: True
2026-11-09 03:13:22,929 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': '198d60ee-ae04-4623-b87e-b786cf569899', 'name': 'ctest-vnet0-18945557', 'tenant_id': 'ceb5b5114d914e8f98d9c174291a861c', 'project_id': 'ceb5b5114d914e8f98d9c174291a861c', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'tags': [], 'subnets': ['124ba59a-5c83-4839-9792-33dc9371ef30', 'cf4d2917-bd39-45ac-ad96-5d7f60c4eb5e'], 'fq_name': ['default-domain', 'ctest-TestDetailedPolicy1-25260043', 'ctest-vnet0-18945557'], 'policys': [['default-domain', 'ctest-TestDetailedPolicy1-25260043', 'policy3']], 'subnet_ipam': [{'subnet_cidr': '10.1.1.0/24', 'ipam_fq_name': ['default-domain', 'ctest-TestDetailedPolicy1-25260043', 'ctest-TestDetailedPolicy1-25260043-default-ipam']}, {'subnet_cidr': '11.1.1.0/24', 'ipam_fq_name': ['default-domain', 'ctest-TestDetailedPolicy1-25260043', 'ctest-TestDetailedPolicy1-25260043-default-ipam']}], 'port_security_enabled': True, 'description': ''}}
2026-11-09 03:13:28,052 - INFO - new policy list of vn ctest-vnet0-18945557 is policy3
2026-11-09 03:13:28,052 - INFO - Starting Verifications after policy for ctest-vnet0-18945557 updated to policy3
2026-11-09 03:13:28,052 - INFO - policy in effect is : [{'direction': '<>', 'protocol': 'tcp', 'dest_network': 'ctest-vnet0-18945557', 'source_network': 'ctest-vnet1-49712780', 'dst_ports': 'any', 'simple_action': 'pass', 'src_ports': 'any'}, {'direction': '<>', 'protocol': 'udp', 'dest_network': 'ctest-vnet0-18945557', 'source_network': 'ctest-vnet1-49712780', 'dst_ports': 'any', 'simple_action': 'deny', 'src_ports': 'any'}, {'direction': '<>', 'protocol': 'icmp', 'dest_network': 'ctest-vnet0-18945557', 'source_network': 'ctest-vnet1-49712780', 'dst_ports': 'any', 'simple_action': 'pass', 'src_ports': 'any'}]
2026-11-09 03:13:28,052 - INFO - matching_rule_action: {'tcp': 'pass', 'udp': 'deny', 'icmp': 'pass'}
2026-11-09 03:13:28,053 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-11-09 03:13:28,053 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 12.1.1.3, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.38, gateway password: c0ntrail123
2026-11-09 03:13:28,053 - DEBUG - ping -s 56 -c 3 -W 1 12.1.1.3
2026-11-09 03:13:30,336 - DEBUG - PING 12.1.1.3 (12.1.1.3) 56(84) bytes of data.
64 bytes from 12.1.1.3: icmp_req=1 ttl=63 time=4.62 ms
64 bytes from 12.1.1.3: icmp_req=2 ttl=63 time=1.39 ms
64 bytes from 12.1.1.3: icmp_req=3 ttl=63 time=1.62 ms
--- 12.1.1.3 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2004ms
rtt min/avg/max/mdev = 1.391/2.545/4.624/1.473 ms
2026-11-09 03:13:30,336 - INFO - Ping to IP 12.1.1.3 from VM ctest-vmc0-07333144 passed
2026-11-09 03:13:30,336 - INFO - vm ping test result after policy for ctest-vnet0-18945557 updated to policy3 is: True
2026-11-09 03:13:30,466 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': '198d60ee-ae04-4623-b87e-b786cf569899', 'name': 'ctest-vnet0-18945557', 'tenant_id': 'ceb5b5114d914e8f98d9c174291a861c', 'project_id': 'ceb5b5114d914e8f98d9c174291a861c', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'tags': [], 'subnets': ['124ba59a-5c83-4839-9792-33dc9371ef30', 'cf4d2917-bd39-45ac-ad96-5d7f60c4eb5e'], 'fq_name': ['default-domain', 'ctest-TestDetailedPolicy1-25260043', 'ctest-vnet0-18945557'], 'policys': [['default-domain', 'ctest-TestDetailedPolicy1-25260043', 'policy10']], 'subnet_ipam': [{'subnet_cidr': '10.1.1.0/24', 'ipam_fq_name': ['default-domain', 'ctest-TestDetailedPolicy1-25260043', 'ctest-TestDetailedPolicy1-25260043-default-ipam']}, {'subnet_cidr': '11.1.1.0/24', 'ipam_fq_name': ['default-domain', 'ctest-TestDetailedPolicy1-25260043', 'ctest-TestDetailedPolicy1-25260043-default-ipam']}], 'port_security_enabled': True, 'description': ''}}
2026-11-09 03:13:35,577 - INFO - new policy list of vn ctest-vnet0-18945557 is policy10
2026-11-09 03:13:35,577 - INFO - Starting Verifications after policy for ctest-vnet0-18945557 updated to policy10
2026-11-09 03:13:35,577 - INFO - policy in effect is : [{'direction': '<>', 'protocol': 'udp', 'dest_network': 'ctest-vnet0-18945557', 'source_network': 'ctest-vnet1-49712780', 'dst_ports': 'any', 'simple_action': 'deny', 'src_ports': 'any'}, {'direction': '<>', 'protocol': 'tcp', 'dest_network': 'ctest-vnet0-18945557', 'source_network': 'ctest-vnet1-49712780', 'dst_ports': 'any', 'simple_action': 'deny', 'src_ports': 'any'}, {'direction': '<>', 'protocol': 'icmp', 'dest_network': 'ctest-vnet0-18945557', 'source_network': 'ctest-vnet1-49712780', 'dst_ports': 'any', 'simple_action': 'deny', 'src_ports': 'any'}]
2026-11-09 03:13:35,577 - INFO - matching_rule_action: {'udp': 'deny', 'tcp': 'deny', 'icmp': 'deny'}
2026-11-09 03:13:35,577 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-11-09 03:13:35,578 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 12.1.1.3, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.38, gateway password: c0ntrail123
2026-11-09 03:13:35,578 - DEBUG - ping -s 56 -c 3 -W 1 12.1.1.3
2026-11-09 03:13:38,866 - DEBUG - PING 12.1.1.3 (12.1.1.3) 56(84) bytes of data.
--- 12.1.1.3 ping statistics ---
3 packets transmitted, 0 received, 100% packet loss, time 2017ms
2026-11-09 03:13:38,866 - WARNING - Ping to IP 12.1.1.3 from VM ctest-vmc0-07333144 failed
2026-11-09 03:13:38,866 - INFO - vm ping test result after policy for ctest-vnet0-18945557 updated to policy10 is: True
2026-11-09 03:13:39,013 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': '198d60ee-ae04-4623-b87e-b786cf569899', 'name': 'ctest-vnet0-18945557', 'tenant_id': 'ceb5b5114d914e8f98d9c174291a861c', 'project_id': 'ceb5b5114d914e8f98d9c174291a861c', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'tags': [], 'subnets': ['124ba59a-5c83-4839-9792-33dc9371ef30', 'cf4d2917-bd39-45ac-ad96-5d7f60c4eb5e'], 'fq_name': ['default-domain', 'ctest-TestDetailedPolicy1-25260043', 'ctest-vnet0-18945557'], 'policys': [['default-domain', 'ctest-TestDetailedPolicy1-25260043', 'policy11']], 'subnet_ipam': [{'subnet_cidr': '10.1.1.0/24', 'ipam_fq_name': ['default-domain', 'ctest-TestDetailedPolicy1-25260043', 'ctest-TestDetailedPolicy1-25260043-default-ipam']}, {'subnet_cidr': '11.1.1.0/24', 'ipam_fq_name': ['default-domain', 'ctest-TestDetailedPolicy1-25260043', 'ctest-TestDetailedPolicy1-25260043-default-ipam']}], 'port_security_enabled': True, 'description': ''}}
2026-11-09 03:13:44,133 - INFO - new policy list of vn ctest-vnet0-18945557 is policy11
2026-11-09 03:13:44,134 - INFO - Starting Verifications after policy for ctest-vnet0-18945557 updated to policy11
2026-11-09 03:13:44,134 - INFO - policy in effect is : [{'direction': '<>', 'protocol': 'udp', 'dest_network': 'ctest-vnet0-18945557', 'source_network': 'ctest-vnet1-49712780', 'dst_ports': 'any', 'simple_action': 'deny', 'src_ports': 'any'}, {'direction': '<>', 'protocol': 'tcp', 'dest_network': 'ctest-vnet0-18945557', 'source_network': 'ctest-vnet1-49712780', 'dst_ports': 'any', 'simple_action': 'deny', 'src_ports': 'any'}, {'direction': '<>', 'protocol': 'icmp', 'dest_network': 'ctest-vnet0-18945557', 'source_network': 'ctest-vnet1-49712780', 'dst_ports': 'any', 'simple_action': 'pass', 'src_ports': 'any'}]
2026-11-09 03:13:44,134 - INFO - matching_rule_action: {'udp': 'deny', 'tcp': 'deny', 'icmp': 'pass'}
2026-11-09 03:13:44,134 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-11-09 03:13:44,134 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 12.1.1.3, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.38, gateway password: c0ntrail123
2026-11-09 03:13:44,134 - DEBUG - ping -s 56 -c 3 -W 1 12.1.1.3
2026-11-09 03:13:46,417 - DEBUG - PING 12.1.1.3 (12.1.1.3) 56(84) bytes of data.
64 bytes from 12.1.1.3: icmp_req=1 ttl=63 time=6.17 ms
64 bytes from 12.1.1.3: icmp_req=2 ttl=63 time=1.41 ms
64 bytes from 12.1.1.3: icmp_req=3 ttl=63 time=1.49 ms
--- 12.1.1.3 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2004ms
rtt min/avg/max/mdev = 1.418/3.029/6.178/2.227 ms
2026-11-09 03:13:46,418 - INFO - Ping to IP 12.1.1.3 from VM ctest-vmc0-07333144 passed
2026-11-09 03:13:46,418 - INFO - vm ping test result after policy for ctest-vnet0-18945557 updated to policy11 is: True
2026-11-09 03:13:46,578 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': '198d60ee-ae04-4623-b87e-b786cf569899', 'name': 'ctest-vnet0-18945557', 'tenant_id': 'ceb5b5114d914e8f98d9c174291a861c', 'project_id': 'ceb5b5114d914e8f98d9c174291a861c', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'tags': [], 'subnets': ['124ba59a-5c83-4839-9792-33dc9371ef30', 'cf4d2917-bd39-45ac-ad96-5d7f60c4eb5e'], 'fq_name': ['default-domain', 'ctest-TestDetailedPolicy1-25260043', 'ctest-vnet0-18945557'], 'policys': [['default-domain', 'ctest-TestDetailedPolicy1-25260043', 'policy12']], 'subnet_ipam': [{'subnet_cidr': '10.1.1.0/24', 'ipam_fq_name': ['default-domain', 'ctest-TestDetailedPolicy1-25260043', 'ctest-TestDetailedPolicy1-25260043-default-ipam']}, {'subnet_cidr': '11.1.1.0/24', 'ipam_fq_name': ['default-domain', 'ctest-TestDetailedPolicy1-25260043', 'ctest-TestDetailedPolicy1-25260043-default-ipam']}], 'port_security_enabled': True, 'description': ''}}
2026-11-09 03:13:51,673 - INFO - new policy list of vn ctest-vnet0-18945557 is policy12
2026-11-09 03:13:51,674 - INFO - Starting Verifications after policy for ctest-vnet0-18945557 updated to policy12
2026-11-09 03:13:51,674 - INFO - policy in effect is : [{'direction': '<>', 'protocol': 'tcp', 'dest_network': 'ctest-vnet0-18945557', 'source_network': 'ctest-vnet1-49712780', 'dst_ports': 'any', 'simple_action': 'deny', 'src_ports': 'any'}, {'direction': '<>', 'protocol': 'udp', 'dest_network': 'ctest-vnet0-18945557', 'source_network': 'ctest-vnet1-49712780', 'dst_ports': 'any', 'simple_action': 'pass', 'src_ports': 'any'}, {'direction': '<>', 'protocol': 'icmp', 'dest_network': 'ctest-vnet0-18945557', 'source_network': 'ctest-vnet1-49712780', 'dst_ports': 'any', 'simple_action': 'deny', 'src_ports': 'any'}]
2026-11-09 03:13:51,675 - INFO - matching_rule_action: {'tcp': 'deny', 'udp': 'pass', 'icmp': 'deny'}
2026-11-09 03:13:51,675 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-11-09 03:13:51,675 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 12.1.1.3, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.38, gateway password: c0ntrail123
2026-11-09 03:13:51,675 - DEBUG - ping -s 56 -c 3 -W 1 12.1.1.3
2026-11-09 03:13:55,062 - DEBUG - PING 12.1.1.3 (12.1.1.3) 56(84) bytes of data.
--- 12.1.1.3 ping statistics ---
3 packets transmitted, 0 received, 100% packet loss, time 2016ms
2026-11-09 03:13:55,062 - WARNING - Ping to IP 12.1.1.3 from VM ctest-vmc0-07333144 failed
2026-11-09 03:13:55,062 - INFO - vm ping test result after policy for ctest-vnet0-18945557 updated to policy12 is: True
2026-11-09 03:13:55,194 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': '198d60ee-ae04-4623-b87e-b786cf569899', 'name': 'ctest-vnet0-18945557', 'tenant_id': 'ceb5b5114d914e8f98d9c174291a861c', 'project_id': 'ceb5b5114d914e8f98d9c174291a861c', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'tags': [], 'subnets': ['124ba59a-5c83-4839-9792-33dc9371ef30', 'cf4d2917-bd39-45ac-ad96-5d7f60c4eb5e'], 'fq_name': ['default-domain', 'ctest-TestDetailedPolicy1-25260043', 'ctest-vnet0-18945557'], 'policys': [['default-domain', 'ctest-TestDetailedPolicy1-25260043', 'policy13']], 'subnet_ipam': [{'subnet_cidr': '10.1.1.0/24', 'ipam_fq_name': ['default-domain', 'ctest-TestDetailedPolicy1-25260043', 'ctest-TestDetailedPolicy1-25260043-default-ipam']}, {'subnet_cidr': '11.1.1.0/24', 'ipam_fq_name': ['default-domain', 'ctest-TestDetailedPolicy1-25260043', 'ctest-TestDetailedPolicy1-25260043-default-ipam']}], 'port_security_enabled': True, 'description': ''}}
2026-11-09 03:14:00,309 - INFO - new policy list of vn ctest-vnet0-18945557 is policy13
2026-11-09 03:14:00,310 - INFO - Starting Verifications after policy for ctest-vnet0-18945557 updated to policy13
2026-11-09 03:14:00,310 - INFO - policy in effect is : [{'direction': '<>', 'protocol': 'udp', 'dest_network': 'ctest-vnet0-18945557', 'source_network': 'ctest-vnet1-49712780', 'dst_ports': 'any', 'simple_action': 'pass', 'src_ports': 'any'}, {'direction': '<>', 'protocol': 'icmp', 'dest_network': 'ctest-vnet0-18945557', 'source_network': 'ctest-vnet1-49712780', 'dst_ports': 'any', 'simple_action': 'pass', 'src_ports': 'any'}, {'direction': '<>', 'protocol': 'tcp', 'dest_network': 'ctest-vnet0-18945557', 'source_network': 'ctest-vnet1-49712780', 'dst_ports': 'any', 'simple_action': 'deny', 'src_ports': 'any'}]
2026-11-09 03:14:00,310 - INFO - matching_rule_action: {'udp': 'pass', 'icmp': 'pass', 'tcp': 'deny'}
2026-11-09 03:14:00,310 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-11-09 03:14:00,310 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 12.1.1.3, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.38, gateway password: c0ntrail123
2026-11-09 03:14:00,310 - DEBUG - ping -s 56 -c 3 -W 1 12.1.1.3
2026-11-09 03:14:02,583 - DEBUG - PING 12.1.1.3 (12.1.1.3) 56(84) bytes of data.
64 bytes from 12.1.1.3: icmp_req=1 ttl=63 time=6.70 ms
64 bytes from 12.1.1.3: icmp_req=2 ttl=63 time=1.78 ms
64 bytes from 12.1.1.3: icmp_req=3 ttl=63 time=1.61 ms
--- 12.1.1.3 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2003ms
rtt min/avg/max/mdev = 1.611/3.366/6.704/2.361 ms
2026-11-09 03:14:02,583 - INFO - Ping to IP 12.1.1.3 from VM ctest-vmc0-07333144 passed
2026-11-09 03:14:02,583 - INFO - vm ping test result after policy for ctest-vnet0-18945557 updated to policy13 is: True
2026-11-09 03:14:02,782 - INFO - Deleting VM ctest-vmc1-06898964
2026-11-09 03:14:02,876 - DEBUG - Verifying in api server 10.0.0.33
2026-11-09 03:14:02,876 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine/0a2922fe-ce57-4d25-97f6-3e24be727b31
2026-11-09 03:14:02,886 - DEBUG - VM ID 0a2922fe-ce57-4d25-97f6-3e24be727b31 of VM ctest-vmc1-06898964 is still found in API Server
2026-11-09 03:14:04,887 - DEBUG - Verifying in api server 10.0.0.33
2026-11-09 03:14:04,887 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine/0a2922fe-ce57-4d25-97f6-3e24be727b31
2026-11-09 03:14:04,896 - DEBUG - VM ID 0a2922fe-ce57-4d25-97f6-3e24be727b31 of VM ctest-vmc1-06898964 is still found in API Server
2026-11-09 03:14:06,897 - DEBUG - Verifying in api server 10.0.0.33
2026-11-09 03:14:06,897 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine/0a2922fe-ce57-4d25-97f6-3e24be727b31
2026-11-09 03:14:06,904 - DEBUG - Response Code: 404
2026-11-09 03:14:06,905 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine/0a2922fe-ce57-4d25-97f6-3e24be727b31
2026-11-09 03:14:06,911 - DEBUG - Response Code: 404
2026-11-09 03:14:06,911 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine/0a2922fe-ce57-4d25-97f6-3e24be727b31
2026-11-09 03:14:06,918 - DEBUG - Response Code: 404
2026-11-09 03:14:06,918 - INFO - VM ctest-vmc1-06898964 is fully removed in API-Server
2026-11-09 03:14:07,077 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found
2026-11-09 03:14:07,139 - DEBUG - Validated that vrouter 10.0.0.250 does not have VMs route for VN default-domain:ctest-TestDetailedPolicy1-25260043:ctest-vnet1-49712780
2026-11-09 03:14:07,152 - DEBUG - Validated that vrouter 10.0.0.38 does not have VMs route for VN default-domain:ctest-TestDetailedPolicy1-25260043:ctest-vnet1-49712780
2026-11-09 03:14:07,152 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestDetailedPolicy1-25260043:ctest-vnet1-49712780
2026-11-09 03:14:07,152 - INFO - VM ctest-vmc1-06898964 is removed in Compute, and routes are removed in all compute nodes
2026-11-09 03:14:07,394 - INFO - Routes for VM ctest-vmc1-06898964 is removed in all control-nodes
2026-11-09 03:14:07,395 - DEBUG - Requesting: http://10.0.0.248:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-6500-1:Compute:contrail-vrouter-agent:0?flat
2026-11-09 03:14:07,435 - DEBUG - Requesting: http://10.0.0.248:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-6500-1?flat
2026-11-09 03:14:07,470 - DEBUG - VM 0a2922fe-ce57-4d25-97f6-3e24be727b31 is still present in vrouter an-jenkins-deploy-platform-ansible-os-6500-1 uve
2026-11-09 03:14:07,470 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-11-09 03:14:07,471 - DEBUG - interface for vm 0a2922fe-ce57-4d25-97f6-3e24be727b31 deleted from vrouter uve ...
2026-11-09 03:14:07,471 - DEBUG - Deleted interface not in error interface list ...
2026-11-09 03:14:10,471 - DEBUG - Requesting: http://10.0.0.248:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-6500-1:Compute:contrail-vrouter-agent:0?flat
2026-11-09 03:14:10,546 - DEBUG - Requesting: http://10.0.0.248:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-6500-1?flat
2026-11-09 03:14:10,584 - DEBUG - VM 0a2922fe-ce57-4d25-97f6-3e24be727b31 is still present in vrouter an-jenkins-deploy-platform-ansible-os-6500-1 uve
2026-11-09 03:14:10,584 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-11-09 03:14:10,584 - DEBUG - interface for vm 0a2922fe-ce57-4d25-97f6-3e24be727b31 deleted from vrouter uve ...
2026-11-09 03:14:10,584 - DEBUG - Deleted interface not in error interface list ...
2026-11-09 03:14:13,585 - DEBUG - Requesting: http://10.0.0.248:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-6500-1:Compute:contrail-vrouter-agent:0?flat
2026-11-09 03:14:13,627 - DEBUG - Requesting: http://10.0.0.248:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-6500-1?flat
2026-11-09 03:14:13,662 - DEBUG - VM 0a2922fe-ce57-4d25-97f6-3e24be727b31 is still present in vrouter an-jenkins-deploy-platform-ansible-os-6500-1 uve
2026-11-09 03:14:13,662 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-11-09 03:14:13,662 - DEBUG - interface for vm 0a2922fe-ce57-4d25-97f6-3e24be727b31 deleted from vrouter uve ...
2026-11-09 03:14:13,662 - DEBUG - Deleted interface not in error interface list ...
2026-11-09 03:14:16,662 - DEBUG - Requesting: http://10.0.0.248:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-6500-1:Compute:contrail-vrouter-agent:0?flat
2026-11-09 03:14:16,704 - DEBUG - Requesting: http://10.0.0.248:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-6500-1?flat
2026-11-09 03:14:16,804 - DEBUG - VM 0a2922fe-ce57-4d25-97f6-3e24be727b31 is still present in vrouter an-jenkins-deploy-platform-ansible-os-6500-1 uve
2026-11-09 03:14:16,804 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-11-09 03:14:16,804 - DEBUG - interface for vm 0a2922fe-ce57-4d25-97f6-3e24be727b31 deleted from vrouter uve ...
2026-11-09 03:14:16,804 - DEBUG - Deleted interface not in error interface list ...
2026-11-09 03:14:19,805 - DEBUG - Requesting: http://10.0.0.248:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-6500-1:Compute:contrail-vrouter-agent:0?flat
2026-11-09 03:14:19,850 - DEBUG - Requesting: http://10.0.0.248:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-6500-1?flat
2026-11-09 03:14:19,938 - DEBUG - VM 0a2922fe-ce57-4d25-97f6-3e24be727b31 is still present in vrouter an-jenkins-deploy-platform-ansible-os-6500-1 uve
2026-11-09 03:14:19,938 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-11-09 03:14:19,938 - DEBUG - interface for vm 0a2922fe-ce57-4d25-97f6-3e24be727b31 deleted from vrouter uve ...
2026-11-09 03:14:19,938 - DEBUG - Deleted interface not in error interface list ...
2026-11-09 03:14:22,939 - DEBUG - Requesting: http://10.0.0.248:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-6500-1:Compute:contrail-vrouter-agent:0?flat
2026-11-09 03:14:22,983 - DEBUG - Requesting: http://10.0.0.248:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-6500-1?flat
2026-11-09 03:14:23,020 - DEBUG - VM 0a2922fe-ce57-4d25-97f6-3e24be727b31 is still present in vrouter an-jenkins-deploy-platform-ansible-os-6500-1 uve
2026-11-09 03:14:23,020 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-11-09 03:14:23,020 - DEBUG - interface for vm 0a2922fe-ce57-4d25-97f6-3e24be727b31 deleted from vrouter uve ...
2026-11-09 03:14:23,020 - DEBUG - Deleted interface not in error interface list ...
2026-11-09 03:14:26,020 - DEBUG - Requesting: http://10.0.0.248:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-6500-1:Compute:contrail-vrouter-agent:0?flat
2026-11-09 03:14:26,062 - DEBUG - Requesting: http://10.0.0.248:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-6500-1?flat
2026-11-09 03:14:26,096 - DEBUG - VM 0a2922fe-ce57-4d25-97f6-3e24be727b31 is not present in vrouter an-jenkins-deploy-platform-ansible-os-6500-1 uve
2026-11-09 03:14:26,096 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-11-09 03:14:26,096 - DEBUG - interface for vm 0a2922fe-ce57-4d25-97f6-3e24be727b31 deleted from vrouter uve ...
2026-11-09 03:14:26,096 - DEBUG - Deleted interface not in error interface list ...
2026-11-09 03:14:26,096 - DEBUG - Validated that VM 0a2922fe-ce57-4d25-97f6-3e24be727b31 is removed in Vrouter UVE
2026-11-09 03:14:26,096 - DEBUG - Verifying through opserver in 10.0.0.248
2026-11-09 03:14:26,096 - DEBUG - Requesting: http://10.0.0.248:8081/analytics/uves/virtual-network/default-domain:ctest-TestDetailedPolicy1-25260043:ctest-vnet1-49712780?flat
2026-11-09 03:14:26,117 - DEBUG - 0a2922fe-ce57-4d25-97f6-3e24be727b31 BM not in default-domain:ctest-TestDetailedPolicy1-25260043:ctest-vnet1-49712780 uve
2026-11-09 03:14:26,117 - DEBUG - Validated that VM 0a2922fe-ce57-4d25-97f6-3e24be727b31 is not present in the VN UVE
2026-11-09 03:14:26,117 - DEBUG - Verifying the 0a2922fe-ce57-4d25-97f6-3e24be727b31 virtual network link through opserver 10.0.0.248
2026-11-09 03:14:26,117 - DEBUG - Requesting: http://10.0.0.248:8081/analytics/uves/virtual-machines
2026-11-09 03:14:26,131 - INFO - 0a2922fe-ce57-4d25-97f6-3e24be727b31 vm uve deleted from opserver
2026-11-09 03:14:26,132 - DEBUG - Verifying the 0a2922fe-ce57-4d25-97f6-3e24be727b31 virtual network link through opserver 10.0.0.249
2026-11-09 03:14:26,132 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-machines
2026-11-09 03:14:26,146 - INFO - 0a2922fe-ce57-4d25-97f6-3e24be727b31 vm uve deleted from opserver
2026-11-09 03:14:26,146 - DEBUG - Verifying the 0a2922fe-ce57-4d25-97f6-3e24be727b31 virtual network link through opserver 10.0.0.33
2026-11-09 03:14:26,147 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-machines
2026-11-09 03:14:26,160 - INFO - 0a2922fe-ce57-4d25-97f6-3e24be727b31 vm uve deleted from opserver
2026-11-09 03:14:26,161 - INFO - Validated that VM 0a2922fe-ce57-4d25-97f6-3e24be727b31 is removed from Opserver
2026-11-09 03:14:26,161 - INFO - Deleting VM ctest-vmc0-07333144
2026-11-09 03:14:26,244 - DEBUG - Verifying in api server 10.0.0.33
2026-11-09 03:14:26,244 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine/d1dc5921-2368-46a0-a2f3-fb5e4e0d5d27
2026-11-09 03:14:26,256 - DEBUG - VM ID d1dc5921-2368-46a0-a2f3-fb5e4e0d5d27 of VM ctest-vmc0-07333144 is still found in API Server
2026-11-09 03:14:28,257 - DEBUG - Verifying in api server 10.0.0.33
2026-11-09 03:14:28,257 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine/d1dc5921-2368-46a0-a2f3-fb5e4e0d5d27
2026-11-09 03:14:28,266 - DEBUG - VM ID d1dc5921-2368-46a0-a2f3-fb5e4e0d5d27 of VM ctest-vmc0-07333144 is still found in API Server
2026-11-09 03:14:30,267 - DEBUG - Verifying in api server 10.0.0.33
2026-11-09 03:14:30,267 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine/d1dc5921-2368-46a0-a2f3-fb5e4e0d5d27
2026-11-09 03:14:30,275 - DEBUG - Response Code: 404
2026-11-09 03:14:30,275 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine/d1dc5921-2368-46a0-a2f3-fb5e4e0d5d27
2026-11-09 03:14:30,282 - DEBUG - Response Code: 404
2026-11-09 03:14:30,282 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine/d1dc5921-2368-46a0-a2f3-fb5e4e0d5d27
2026-11-09 03:14:30,289 - DEBUG - Response Code: 404
2026-11-09 03:14:30,290 - INFO - VM ctest-vmc0-07333144 is fully removed in API-Server
2026-11-09 03:14:30,476 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found
2026-11-09 03:14:30,561 - DEBUG - Validated that vrouter 10.0.0.250 does not have VMs route for VN default-domain:ctest-TestDetailedPolicy1-25260043:ctest-vnet0-18945557
2026-11-09 03:14:30,575 - DEBUG - Validated that vrouter 10.0.0.38 does not have VMs route for VN default-domain:ctest-TestDetailedPolicy1-25260043:ctest-vnet0-18945557
2026-11-09 03:14:30,575 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestDetailedPolicy1-25260043:ctest-vnet0-18945557
2026-11-09 03:14:30,575 - INFO - VM ctest-vmc0-07333144 is removed in Compute, and routes are removed in all compute nodes
2026-11-09 03:14:30,856 - INFO - Routes for VM ctest-vmc0-07333144 is removed in all control-nodes
2026-11-09 03:14:30,856 - DEBUG - Requesting: http://10.0.0.248:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-6500-2:Compute:contrail-vrouter-agent:0?flat
2026-11-09 03:14:30,897 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-6500-2?flat
2026-11-09 03:14:30,972 - DEBUG - VM d1dc5921-2368-46a0-a2f3-fb5e4e0d5d27 is not present in vrouter an-jenkins-deploy-platform-ansible-os-6500-2 uve
2026-11-09 03:14:30,972 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-11-09 03:14:30,972 - DEBUG - interface for vm d1dc5921-2368-46a0-a2f3-fb5e4e0d5d27 deleted from vrouter uve ...
2026-11-09 03:14:30,972 - DEBUG - Deleted interface not in error interface list ...
2026-11-09 03:14:30,972 - DEBUG - Validated that VM d1dc5921-2368-46a0-a2f3-fb5e4e0d5d27 is removed in Vrouter UVE
2026-11-09 03:14:30,972 - DEBUG - Verifying through opserver in 10.0.0.248
2026-11-09 03:14:30,972 - DEBUG - Requesting: http://10.0.0.248:8081/analytics/uves/virtual-network/default-domain:ctest-TestDetailedPolicy1-25260043:ctest-vnet0-18945557?flat
2026-11-09 03:14:30,994 - DEBUG - d1dc5921-2368-46a0-a2f3-fb5e4e0d5d27 BM not in default-domain:ctest-TestDetailedPolicy1-25260043:ctest-vnet0-18945557 uve
2026-11-09 03:14:30,994 - DEBUG - Validated that VM d1dc5921-2368-46a0-a2f3-fb5e4e0d5d27 is not present in the VN UVE
2026-11-09 03:14:30,994 - DEBUG - Verifying the d1dc5921-2368-46a0-a2f3-fb5e4e0d5d27 virtual network link through opserver 10.0.0.248
2026-11-09 03:14:30,994 - DEBUG - Requesting: http://10.0.0.248:8081/analytics/uves/virtual-machines
2026-11-09 03:14:31,012 - INFO - d1dc5921-2368-46a0-a2f3-fb5e4e0d5d27 vm uve deleted from opserver
2026-11-09 03:14:31,012 - DEBUG - Verifying the d1dc5921-2368-46a0-a2f3-fb5e4e0d5d27 virtual network link through opserver 10.0.0.249
2026-11-09 03:14:31,013 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-machines
2026-11-09 03:14:31,062 - INFO - d1dc5921-2368-46a0-a2f3-fb5e4e0d5d27 vm uve deleted from opserver
2026-11-09 03:14:31,062 - DEBUG - Verifying the d1dc5921-2368-46a0-a2f3-fb5e4e0d5d27 virtual network link through opserver 10.0.0.33
2026-11-09 03:14:31,062 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-machines
2026-11-09 03:14:31,075 - INFO - d1dc5921-2368-46a0-a2f3-fb5e4e0d5d27 vm uve deleted from opserver
2026-11-09 03:14:31,075 - INFO - Validated that VM d1dc5921-2368-46a0-a2f3-fb5e4e0d5d27 is removed from Opserver
2026-11-09 03:14:31,076 - DEBUG - Detaching the Policy for VN :ctest-vnet1-49712780
2026-11-09 03:14:31,076 - DEBUG - Requesting: http://10.0.0.248:8082/domains
2026-11-09 03:14:31,083 - DEBUG - Requesting: http://10.0.0.248:8082/domain/48397716-fb81-4046-93f4-8bda606db062
2026-11-09 03:14:31,123 - DEBUG - Requesting: http://10.0.0.248:8082/project/ceb5b511-4d91-4e8f-98d9-c174291a861c
2026-11-09 03:14:31,226 - DEBUG - Requesting: http://10.0.0.248:8082/virtual-network/caab9275-8a40-4365-8b1f-d31751ad47b4
2026-11-09 03:14:31,430 - DEBUG - Detached Policy:[['default-domain', 'ctest-TestDetailedPolicy1-25260043', 'policy100']] from ctest-vnet1-49712780
2026-11-09 03:14:31,430 - DEBUG - Detaching the Policy for VN :ctest-vnet0-18945557
2026-11-09 03:14:31,430 - DEBUG - Requesting: http://10.0.0.248:8082/domains
2026-11-09 03:14:31,440 - DEBUG - Requesting: http://10.0.0.248:8082/domain/48397716-fb81-4046-93f4-8bda606db062
2026-11-09 03:14:31,469 - DEBUG - Requesting: http://10.0.0.248:8082/project/ceb5b511-4d91-4e8f-98d9-c174291a861c
2026-11-09 03:14:31,557 - DEBUG - Requesting: http://10.0.0.248:8082/virtual-network/198d60ee-ae04-4623-b87e-b786cf569899
2026-11-09 03:14:31,617 - INFO - Deleted policy policy1000
2026-11-09 03:14:31,794 - DEBUG - policy policy1000 is not found in API Server
2026-11-09 03:14:31,834 - INFO - Deleted policy policy13
2026-11-09 03:14:31,982 - DEBUG - policy policy13 is not found in API Server
2026-11-09 03:14:32,025 - INFO - Deleted policy policy12
2026-11-09 03:14:32,187 - DEBUG - policy policy12 is not found in API Server
2026-11-09 03:14:32,226 - INFO - Deleted policy policy11
2026-11-09 03:14:32,421 - DEBUG - policy policy11 is not found in API Server
2026-11-09 03:14:32,465 - INFO - Deleted policy policy10
2026-11-09 03:14:32,661 - DEBUG - policy policy10 is not found in API Server
2026-11-09 03:14:32,703 - INFO - Deleted policy policy100
2026-11-09 03:14:32,893 - DEBUG - policy policy100 is not found in API Server
2026-11-09 03:14:32,954 - INFO - Deleted policy policy3
2026-11-09 03:14:33,165 - DEBUG - policy policy3 is not found in API Server
2026-11-09 03:14:33,208 - INFO - Deleted policy policy2
2026-11-09 03:14:33,405 - DEBUG - policy policy2 is not found in API Server
2026-11-09 03:14:33,454 - INFO - Deleted policy policy1
2026-11-09 03:14:33,653 - DEBUG - policy policy1 is not found in API Server
2026-11-09 03:14:33,697 - INFO - Deleted policy policy0
2026-11-09 03:14:33,889 - DEBUG - policy policy0 is not found in API Server
2026-11-09 03:14:33,889 - INFO - Deleting VN ctest-vnet1-49712780
2026-11-09 03:14:34,070 - DEBUG - Response for deleting network ()
2026-11-09 03:14:34,070 - DEBUG - Requesting: http://10.0.0.248:8082/routing-instance/858beae7-f440-421c-b331-0b3fb8336fdd
2026-11-09 03:14:34,086 - DEBUG - Response Code: 404
2026-11-09 03:14:34,087 - DEBUG - Requesting: http://10.0.0.248:8082/domains
2026-11-09 03:14:34,093 - DEBUG - Requesting: http://10.0.0.248:8082/domain/48397716-fb81-4046-93f4-8bda606db062
2026-11-09 03:14:34,107 - DEBUG - Requesting: http://10.0.0.248:8082/project/ceb5b511-4d91-4e8f-98d9-c174291a861c
2026-11-09 03:14:34,207 - INFO - Validated that VN ctest-vnet1-49712780 is not found in API Server
2026-11-09 03:14:34,220 - DEBUG - VN ctest-vnet1-49712780 is not present in Agent 10.0.0.250
2026-11-09 03:14:34,232 - DEBUG - VN ctest-vnet1-49712780 is not present in Agent 10.0.0.38
2026-11-09 03:14:34,232 - INFO - Validated that VN ctest-vnet1-49712780 is not in any agent
2026-11-09 03:14:34,271 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vnet1-49712780 info
2026-11-09 03:14:34,271 - INFO - Deleting VN ctest-vnet0-18945557
2026-11-09 03:14:34,482 - DEBUG - Response for deleting network ()
2026-11-09 03:14:34,482 - DEBUG - Requesting: http://10.0.0.248:8082/routing-instance/71b5d0e6-97e4-4497-98c6-d3081de28269
2026-11-09 03:14:34,490 - DEBUG - Response Code: 404
2026-11-09 03:14:34,490 - DEBUG - Requesting: http://10.0.0.248:8082/domains
2026-11-09 03:14:34,497 - DEBUG - Requesting: http://10.0.0.248:8082/domain/48397716-fb81-4046-93f4-8bda606db062
2026-11-09 03:14:34,510 - DEBUG - Requesting: http://10.0.0.248:8082/project/ceb5b511-4d91-4e8f-98d9-c174291a861c
2026-11-09 03:14:34,619 - INFO - Validated that VN ctest-vnet0-18945557 is not found in API Server
2026-11-09 03:14:34,632 - DEBUG - VN ctest-vnet0-18945557 is not present in Agent 10.0.0.250
2026-11-09 03:14:34,644 - DEBUG - VN ctest-vnet0-18945557 is not present in Agent 10.0.0.38
2026-11-09 03:14:34,645 - INFO - Validated that VN ctest-vnet0-18945557 is not in any agent
2026-11-09 03:14:34,679 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vnet0-18945557 info
2026-11-09 03:14:34,777 - INFO - IPAM: ctest-TestDetailedPolicy1-25260043-default-ipam is not found in API Server
2026-11-09 03:14:34,802 - INFO - IPAM:ctest-TestDetailedPolicy1-25260043-default-ipam is not found in control node
2026-11-09 03:14:35,369 - DEBUG - Skipping the deletion of Project ['default-domain', 'ctest-TestDetailedPolicy1-25260043']
2026-11-09 03:14:35,369 - DEBUG - Skipping the deletion of User admin
2026-11-09 03:14:36,663 - DEBUG - No XMPP flaps were noticed during the test
2026-11-09 03:14:36,663 - INFO - END TEST : test_multi_vn_repeated_policy_update_with_ping : PASSED[0:03:24]
2026-11-09 03:14:36,663 - INFO - --------------------------------------------------------------------------------
2026-11-09 03:14:38,045 - INFO - Deleted project: ctest-TestDetailedPolicy1-25260043, ID : ceb5b511-4d91-4e8f-98d9-c174291a861c