2025-07-21 14:32:57,084 - INFO - Domain Default found not creating
2025-07-21 14:32:57,345 - INFO - Project ctest-TestRbacBasic-57777976 not found, creating it
2025-07-21 14:32:57,856 - INFO - Created Project:ctest-TestRbacBasic-57777976, ID : a1eda2ee-e942-4c23-a12e-69cdce9858b2
2025-07-21 14:33:02,926 - INFO - ================================================================================
2025-07-21 14:33:02,926 - INFO - STARTING TEST : test_perms2_owner
2025-07-21 14:33:02,926 - INFO - TEST DESCRIPTION :
Validate perms2 tenant ownership
steps:
1. Create Project1 and Project2
2. Add user1 as role1 under project1 and project2
3. create domain acl rule 'VirtualNetwork.* role1:CRUD'
4. create VN1 under Project1
4. create VN2 under Project2
5. user1 shouldnt be able to read VN1 using project2 creds
6. admin should be able to read VN1 though he isnt member of the project
7. Network list with respective project creds should list corresponding VNs
8. Change ownership of VN1 to Project2
9. user1 should now be able to read VN1 using Project2 creds
10. Network list with Project2 creds should list both VNs,
Project1 creds should list VN1 alone, admin should list both VNs
2025-07-21 14:33:03,096 - DEBUG - Skipping xmpp flap check
2025-07-21 14:33:03,112 - INFO - Initial checks done. Running the testcase now
2025-07-21 14:33:03,113 - INFO -
2025-07-21 14:33:03,576 - INFO - Project ctest-TestRbacBasic-05314431 not found, creating it
2025-07-21 14:33:04,221 - INFO - Created Project:ctest-TestRbacBasic-05314431, ID : 20b9f22a-ec47-4591-a407-779492fda8d6
2025-07-21 14:33:04,606 - INFO - Project ctest-TestRbacBasic-37827884 not found, creating it
2025-07-21 14:33:05,353 - INFO - Created Project:ctest-TestRbacBasic-37827884, ID : 5207dc85-b624-4e3b-9461-456dc901b08f
2025-07-21 14:33:08,121 - DEBUG - Requesting: http://10.0.0.254:8082/api-access-list/86e0a794-77d7-4e2b-926c-4957ad8cdd51
2025-07-21 14:33:08,511 - DEBUG - Requesting: http://10.0.0.254:8082/api-access-list/86e0a794-77d7-4e2b-926c-4957ad8cdd51
2025-07-21 14:33:08,643 - DEBUG - API access-list (86e0a794-77d7-4e2b-926c-4957ad8cdd51) found in api server
2025-07-21 14:33:08,643 - INFO - API access-list 86e0a794-77d7-4e2b-926c-4957ad8cdd51 verify on api server passed
2025-07-21 14:33:09,488 - INFO - Created VN ctest-TestRbacBasic-05314431-48294185, UUID :18c542bd-bb13-4a86-b78f-49b892d3c56d
2025-07-21 14:33:10,769 - INFO - Created VN ctest-TestRbacBasic-37827884-83353047, UUID :1fb0dfad-30ba-4f12-bc6d-737f921fca45
2025-07-21 14:33:11,347 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/18c542bd-bb13-4a86-b78f-49b892d3c56d
2025-07-21 14:33:11,698 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/18c542bd-bb13-4a86-b78f-49b892d3c56d
2025-07-21 14:33:11,805 - INFO - API Server: Read VN 18c542bd-bb13-4a86-b78f-49b892d3c56d
2025-07-21 14:33:11,805 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/18c542bd-bb13-4a86-b78f-49b892d3c56d
2025-07-21 14:33:12,156 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/18c542bd-bb13-4a86-b78f-49b892d3c56d
2025-07-21 14:33:12,244 - DEBUG - Virtual Network ID: 18c542bd-bb13-4a86-b78f-49b892d3c56d not found
2025-07-21 14:33:12,245 - INFO - API Server: Permission Denied to read VN 18c542bd-bb13-4a86-b78f-49b892d3c56d
2025-07-21 14:33:12,245 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/18c542bd-bb13-4a86-b78f-49b892d3c56d
2025-07-21 14:33:12,275 - INFO - API Server: Read VN 18c542bd-bb13-4a86-b78f-49b892d3c56d
2025-07-21 14:33:12,276 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-networks
2025-07-21 14:33:12,376 - INFO - API Server: List VN ['18c542bd-bb13-4a86-b78f-49b892d3c56d']
2025-07-21 14:33:12,376 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-networks
2025-07-21 14:33:12,476 - INFO - API Server: List VN ['1fb0dfad-30ba-4f12-bc6d-737f921fca45']
2025-07-21 14:33:12,531 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-networks
2025-07-21 14:33:12,587 - INFO - API Server: List VN []
2025-07-21 14:33:12,587 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-networks
2025-07-21 14:33:12,625 - INFO - API Server: List VN ['18c542bd-bb13-4a86-b78f-49b892d3c56d', '1fb0dfad-30ba-4f12-bc6d-737f921fca45']
2025-07-21 14:33:12,625 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-networks
2025-07-21 14:33:12,635 - INFO - API Server: List VN ['54db0e2c-a4ec-4b8b-a92f-3d60ecdc7387', 'acdf2c3d-180a-4716-a199-5149eb6e4a1b', '18c542bd-bb13-4a86-b78f-49b892d3c56d', '1fb0dfad-30ba-4f12-bc6d-737f921fca45', 'b43dc213-ac05-49b1-b673-f215d7ba20a4', '96aef5fb-28c2-475f-9f20-917a958948ea', '773ff8cd-7781-4242-b4a9-366102443c5b', 'ed0afd0e-5281-4e31-91fd-4b9e72db8f5c', 'af00466d-2115-4d66-94d2-ece5a6b7cd7a', '8e471ac2-f22e-4c4a-9ad8-e94867a3fe8d', '2820ce33-8099-4532-a6f1-d7b1472ddd32', 'a22d5bac-2e59-4b9b-9818-33b9074e2055', '9dd5a9b2-3cf7-4496-aa2f-359c2ff7bc58', '275fad29-126d-4a3b-86d0-2129aa7129a1', 'fc333b7b-e545-40ed-a666-28047a296c48']
2025-07-21 14:33:12,671 - INFO - Deleting VN ctest-TestRbacBasic-37827884-83353047
2025-07-21 14:33:12,815 - INFO - Deleting VN ctest-TestRbacBasic-05314431-48294185
2025-07-21 14:33:12,970 - DEBUG - Requesting: http://10.0.0.254:8082/api-access-list/86e0a794-77d7-4e2b-926c-4957ad8cdd51
2025-07-21 14:33:12,975 - DEBUG - Response Code: 404
2025-07-21 14:33:12,976 - INFO - API access-list (86e0a794-77d7-4e2b-926c-4957ad8cdd51) got deleted in api server
2025-07-21 14:33:14,257 - INFO - Deleted project: ctest-TestRbacBasic-37827884, ID : 5207dc85-b624-4e3b-9461-456dc901b08f
2025-07-21 14:33:15,154 - INFO - Deleted project: ctest-TestRbacBasic-05314431, ID : 20b9f22a-ec47-4591-a407-779492fda8d6
2025-07-21 14:33:15,332 - DEBUG - Skipping xmpp flap check
2025-07-21 14:33:15,333 - INFO - END TEST : test_perms2_owner : PASSED[0:00:13]
2025-07-21 14:33:15,333 - INFO - --------------------------------------------------------------------------------
2025-07-21 14:33:15,348 - INFO - ================================================================================
2025-07-21 14:33:15,351 - INFO - STARTING TEST : test_rbac_acl_different_roles
2025-07-21 14:33:15,351 - INFO - TEST DESCRIPTION :
Validate via vnc_apis CRUD of rbac acl and objects
steps:
1. Add user1 as role1 and user2 as role2 to the project
2. Both user1 and user2 shouldnt be able to create VNs/STs
3. Create Rbac ACL under project with VN.* role1:CRUD rule
4. user1 should be able to create VN, but not Service-Template
5. Create Rbac ACL under domain with ST.* role1:CRUD rule
6. user1 should be able to create Service-Template
7. user2 shouldnt be able to read the created VN/ST or create new
8. Update the acl rule with Read perms for role2
9. user2 should be able to read created VN but not create new VNs
10. user2 should be able to read created ST but not create new STs
11. Delete the acl rule with Read perms for role2
12. user2 shouldnt be able to read the created ST/VN or create new VN/ST
13. Update global acl with role2:R for both VN and ST
14. user2 should be able to read VN and ST
13. Delete both project and domain acls
14. user1 shouldnt be able to read/delete VN/ST
15. Update global acl with role1:CRUD for both VN/ST
16. user1 should now be able to delete both VN and ST
17. Delete global acl
pass : acl creation and update should complete scucessfully.
2025-07-21 14:33:15,543 - DEBUG - Skipping xmpp flap check
2025-07-21 14:33:15,557 - INFO - Initial checks done. Running the testcase now
2025-07-21 14:33:15,557 - INFO -
2025-07-21 14:33:18,917 - INFO - Permission denied to create/update VirtualNetwork
2025-07-21 14:33:18,917 - DEBUG - Creating service template: ['default-domain', 'ctest-TestRbacBasic-57777976-94188952']
2025-07-21 14:33:19,345 - DEBUG - Requesting: http://10.0.0.254:8082/api-access-list/df29f9d8-b1df-4d6b-907e-f71094487623
2025-07-21 14:33:19,355 - DEBUG - API access-list (df29f9d8-b1df-4d6b-907e-f71094487623) found in api server
2025-07-21 14:33:19,355 - INFO - API access-list df29f9d8-b1df-4d6b-907e-f71094487623 verify on api server passed
2025-07-21 14:33:19,701 - INFO - Created VN ctest-TestRbacBasic-57777976-25569749, UUID :22d1191b-9008-4775-9897-155572843c50
2025-07-21 14:33:20,737 - DEBUG - Fetched VN: default-domain:ctest-TestRbacBasic-57777976:ctest-TestRbacBasic-57777976-25569749(22d1191b-9008-4775-9897-155572843c50) with subnets ['36.223.139.128/26']
2025-07-21 14:33:20,738 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-07-21 14:33:20,750 - DEBUG - Requesting: http://10.0.0.254:8082/domain/6f2dbf83-3a1f-4a4f-8676-305ebe4efd33
2025-07-21 14:33:20,794 - DEBUG - Requesting: http://10.0.0.254:8082/project/a1eda2ee-e942-4c23-a12e-69cdce9858b2
2025-07-21 14:33:20,926 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/22d1191b-9008-4775-9897-155572843c50
2025-07-21 14:33:20,961 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/22d1191b-9008-4775-9897-155572843c50
2025-07-21 14:33:20,998 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/2d543480-9b08-42ff-81d5-93db1443e102
2025-07-21 14:33:21,024 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/2d543480-9b08-42ff-81d5-93db1443e102
2025-07-21 14:33:21,050 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/09c496dc-a00f-42f1-b3f8-c17b5546c69b
2025-07-21 14:33:21,074 - DEBUG - Route Targets: ['target:64512:8000013']
2025-07-21 14:33:21,074 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/22d1191b-9008-4775-9897-155572843c50
2025-07-21 14:33:21,099 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/2d543480-9b08-42ff-81d5-93db1443e102
2025-07-21 14:33:21,135 - INFO - Verified VN network id 18 for VN 22d1191b-9008-4775-9897-155572843c50
2025-07-21 14:33:21,135 - INFO - Verifications in API Server for VN ctest-TestRbacBasic-57777976-25569749 passed
2025-07-21 14:33:21,135 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/22d1191b-9008-4775-9897-155572843c50
2025-07-21 14:33:21,158 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/2d543480-9b08-42ff-81d5-93db1443e102
2025-07-21 14:33:21,171 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/2d543480-9b08-42ff-81d5-93db1443e102
2025-07-21 14:33:21,183 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/09c496dc-a00f-42f1-b3f8-c17b5546c69b
2025-07-21 14:33:21,201 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestRbacBasic-57777976:ctest-TestRbacBasic-57777976-25569749', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestRbacBasic-19303513', 'owner-access': '7', 'group': 'ctest-TestRbacBasic-58542978', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2508814073593350005', 'uuid-lslong': '10995280471986027600'}, 'enable': 'true', 'created': '2025-07-21T14:33:19', 'last-modified': '2025-07-21T14:33:19', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.285580'}
2025-07-21 14:33:21,211 - DEBUG - Route Targets: ['target:64512:8000013']
2025-07-21 14:33:21,211 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-TestRbacBasic-57777976-25569749 passed
2025-07-21 14:33:21,211 - DEBUG - ====Verifying policy data for ctest-TestRbacBasic-57777976-25569749 in API_Server ======
2025-07-21 14:33:21,211 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-07-21 14:33:21,220 - DEBUG - Requesting: http://10.0.0.254:8082/domain/6f2dbf83-3a1f-4a4f-8676-305ebe4efd33
2025-07-21 14:33:21,252 - DEBUG - Requesting: http://10.0.0.254:8082/project/a1eda2ee-e942-4c23-a12e-69cdce9858b2
2025-07-21 14:33:21,360 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/22d1191b-9008-4775-9897-155572843c50
2025-07-21 14:33:21,378 - DEBUG - =>VN ctest-TestRbacBasic-57777976-25569749 has no policy to be verified
2025-07-21 14:33:21,378 - DEBUG - Verifying the vn in opserver
2025-07-21 14:33:21,378 - DEBUG - Verifying the default-domain:ctest-TestRbacBasic-57777976:ctest-TestRbacBasic-57777976-25569749 virtual network link through opserver 10.0.0.254
2025-07-21 14:33:21,378 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-07-21 14:33:21,702 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-07-21 14:33:21,854 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestRbacBasic-57777976:ctest-TestRbacBasic-57777976-25569749', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestRbacBasic-57777976:ctest-TestRbacBasic-57777976-25569749?flat'}
2025-07-21 14:33:21,854 - INFO - Validated that VN default-domain:ctest-TestRbacBasic-57777976:ctest-TestRbacBasic-57777976-25569749 is found in opserver
2025-07-21 14:33:21,879 - DEBUG - Do not have enough data to verify VN in agent
2025-07-21 14:33:21,890 - DEBUG - VRF ids for VN ctest-TestRbacBasic-57777976-25569749: {}
2025-07-21 14:33:21,890 - DEBUG - Creating service template: ['default-domain', 'ctest-TestRbacBasic-57777976-74452388']
2025-07-21 14:33:22,317 - DEBUG - Requesting: http://10.0.0.254:8082/api-access-list/2d93dcdc-55aa-4d3e-86d7-731b5a17b32a
2025-07-21 14:33:22,333 - DEBUG - API access-list (2d93dcdc-55aa-4d3e-86d7-731b5a17b32a) found in api server
2025-07-21 14:33:22,333 - INFO - API access-list 2d93dcdc-55aa-4d3e-86d7-731b5a17b32a verify on api server passed
2025-07-21 14:33:22,333 - DEBUG - Creating service template: ['default-domain', 'ctest-TestRbacBasic-57777976-63046805']
2025-07-21 14:33:23,029 - DEBUG - Service template: ['default-domain', 'ctest-TestRbacBasic-57777976-63046805'] created succesfully
2025-07-21 14:33:23,030 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/22d1191b-9008-4775-9897-155572843c50
2025-07-21 14:33:23,402 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/22d1191b-9008-4775-9897-155572843c50
2025-07-21 14:33:23,463 - DEBUG - Virtual Network ID: 22d1191b-9008-4775-9897-155572843c50 not found
2025-07-21 14:33:23,463 - INFO - API Server: Permission Denied to read VN 22d1191b-9008-4775-9897-155572843c50
2025-07-21 14:33:23,464 - DEBUG - Requesting: http://10.0.0.254:8082/service-template/9c38fe5f-f9c3-4231-98e0-4b64bbc98d2b
2025-07-21 14:33:23,792 - DEBUG - Requesting: http://10.0.0.254:8082/service-template/9c38fe5f-f9c3-4231-98e0-4b64bbc98d2b
2025-07-21 14:33:23,856 - INFO - API Server: Permission Denied to read ST 9c38fe5f-f9c3-4231-98e0-4b64bbc98d2b
2025-07-21 14:33:23,933 - DEBUG - Requesting: http://10.0.0.254:8082/api-access-list/df29f9d8-b1df-4d6b-907e-f71094487623
2025-07-21 14:33:23,955 - DEBUG - API access-list (df29f9d8-b1df-4d6b-907e-f71094487623) found in api server
2025-07-21 14:33:23,955 - INFO - API access-list df29f9d8-b1df-4d6b-907e-f71094487623 verify on api server passed
2025-07-21 14:33:23,956 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/22d1191b-9008-4775-9897-155572843c50
2025-07-21 14:33:24,014 - INFO - API Server: Read VN 22d1191b-9008-4775-9897-155572843c50
2025-07-21 14:33:24,801 - INFO - Permission denied to create/update VirtualNetwork
2025-07-21 14:33:24,801 - DEBUG - Requesting: http://10.0.0.254:8082/service-template/9c38fe5f-f9c3-4231-98e0-4b64bbc98d2b
2025-07-21 14:33:25,186 - DEBUG - Requesting: http://10.0.0.254:8082/service-template/9c38fe5f-f9c3-4231-98e0-4b64bbc98d2b
2025-07-21 14:33:25,290 - INFO - API Server: Permission Denied to read ST 9c38fe5f-f9c3-4231-98e0-4b64bbc98d2b
2025-07-21 14:33:25,377 - DEBUG - Requesting: http://10.0.0.254:8082/api-access-list/2d93dcdc-55aa-4d3e-86d7-731b5a17b32a
2025-07-21 14:33:25,403 - DEBUG - API access-list (2d93dcdc-55aa-4d3e-86d7-731b5a17b32a) found in api server
2025-07-21 14:33:25,403 - INFO - API access-list 2d93dcdc-55aa-4d3e-86d7-731b5a17b32a verify on api server passed
2025-07-21 14:33:25,404 - DEBUG - Requesting: http://10.0.0.254:8082/service-template/9c38fe5f-f9c3-4231-98e0-4b64bbc98d2b
2025-07-21 14:33:25,463 - INFO - API Server: Read Service-Template 9c38fe5f-f9c3-4231-98e0-4b64bbc98d2b
2025-07-21 14:33:25,464 - DEBUG - Creating service template: ['default-domain', 'ctest-TestRbacBasic-57777976-36333241']
2025-07-21 14:33:25,845 - DEBUG - Requesting: http://10.0.0.254:8082/api-access-list/df29f9d8-b1df-4d6b-907e-f71094487623
2025-07-21 14:33:25,869 - DEBUG - API access-list (df29f9d8-b1df-4d6b-907e-f71094487623) found in api server
2025-07-21 14:33:25,869 - INFO - API access-list df29f9d8-b1df-4d6b-907e-f71094487623 verify on api server passed
2025-07-21 14:33:26,001 - DEBUG - Requesting: http://10.0.0.254:8082/api-access-list/2d93dcdc-55aa-4d3e-86d7-731b5a17b32a
2025-07-21 14:33:26,025 - DEBUG - API access-list (2d93dcdc-55aa-4d3e-86d7-731b5a17b32a) found in api server
2025-07-21 14:33:26,025 - INFO - API access-list 2d93dcdc-55aa-4d3e-86d7-731b5a17b32a verify on api server passed
2025-07-21 14:33:26,026 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/22d1191b-9008-4775-9897-155572843c50
2025-07-21 14:33:26,395 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/22d1191b-9008-4775-9897-155572843c50
2025-07-21 14:33:26,456 - DEBUG - Virtual Network ID: 22d1191b-9008-4775-9897-155572843c50 not found
2025-07-21 14:33:26,456 - INFO - API Server: Permission Denied to read VN 22d1191b-9008-4775-9897-155572843c50
2025-07-21 14:33:26,456 - DEBUG - Requesting: http://10.0.0.254:8082/service-template/9c38fe5f-f9c3-4231-98e0-4b64bbc98d2b
2025-07-21 14:33:26,838 - DEBUG - Requesting: http://10.0.0.254:8082/service-template/9c38fe5f-f9c3-4231-98e0-4b64bbc98d2b
2025-07-21 14:33:26,901 - INFO - API Server: Permission Denied to read ST 9c38fe5f-f9c3-4231-98e0-4b64bbc98d2b
2025-07-21 14:33:26,989 - DEBUG - Requesting: http://10.0.0.254:8082/service-template/9c38fe5f-f9c3-4231-98e0-4b64bbc98d2b
2025-07-21 14:33:27,040 - INFO - API Server: Read Service-Template 9c38fe5f-f9c3-4231-98e0-4b64bbc98d2b
2025-07-21 14:33:27,041 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/22d1191b-9008-4775-9897-155572843c50
2025-07-21 14:33:27,105 - INFO - API Server: Read VN 22d1191b-9008-4775-9897-155572843c50
2025-07-21 14:33:27,172 - DEBUG - Requesting: http://10.0.0.254:8082/api-access-list/df29f9d8-b1df-4d6b-907e-f71094487623
2025-07-21 14:33:27,180 - DEBUG - Response Code: 404
2025-07-21 14:33:27,181 - INFO - API access-list (df29f9d8-b1df-4d6b-907e-f71094487623) got deleted in api server
2025-07-21 14:33:27,219 - DEBUG - Requesting: http://10.0.0.254:8082/api-access-list/2d93dcdc-55aa-4d3e-86d7-731b5a17b32a
2025-07-21 14:33:27,231 - DEBUG - Response Code: 404
2025-07-21 14:33:27,231 - INFO - API access-list (2d93dcdc-55aa-4d3e-86d7-731b5a17b32a) got deleted in api server
2025-07-21 14:33:27,231 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/22d1191b-9008-4775-9897-155572843c50
2025-07-21 14:33:27,589 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/22d1191b-9008-4775-9897-155572843c50
2025-07-21 14:33:27,652 - DEBUG - Virtual Network ID: 22d1191b-9008-4775-9897-155572843c50 not found
2025-07-21 14:33:27,652 - INFO - API Server: Permission Denied to read VN 22d1191b-9008-4775-9897-155572843c50
2025-07-21 14:33:27,652 - DEBUG - Requesting: http://10.0.0.254:8082/service-template/9c38fe5f-f9c3-4231-98e0-4b64bbc98d2b
2025-07-21 14:33:28,035 - DEBUG - Requesting: http://10.0.0.254:8082/service-template/9c38fe5f-f9c3-4231-98e0-4b64bbc98d2b
2025-07-21 14:33:28,391 - INFO - API Server: Permission Denied to read ST 9c38fe5f-f9c3-4231-98e0-4b64bbc98d2b
2025-07-21 14:33:28,452 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/22d1191b-9008-4775-9897-155572843c50
2025-07-21 14:33:28,471 - INFO - API Server: Read VN 22d1191b-9008-4775-9897-155572843c50
2025-07-21 14:33:28,471 - DEBUG - Requesting: http://10.0.0.254:8082/service-template/9c38fe5f-f9c3-4231-98e0-4b64bbc98d2b
2025-07-21 14:33:28,487 - INFO - API Server: Read Service-Template 9c38fe5f-f9c3-4231-98e0-4b64bbc98d2b
2025-07-21 14:33:28,487 - DEBUG - Deleting service template: ['default-domain', 'ctest-TestRbacBasic-57777976-63046805']
2025-07-21 14:33:28,604 - INFO - Service template: ['default-domain', 'ctest-TestRbacBasic-57777976-63046805'] deleted successfully.
2025-07-21 14:33:28,605 - INFO - Deleting VN ctest-TestRbacBasic-57777976-25569749
2025-07-21 14:33:29,396 - DEBUG - Skipping xmpp flap check
2025-07-21 14:33:29,396 - INFO - END TEST : test_rbac_acl_different_roles : PASSED[0:00:14]
2025-07-21 14:33:29,396 - INFO - --------------------------------------------------------------------------------
2025-07-21 14:33:29,399 - INFO - ================================================================================
2025-07-21 14:33:29,399 - INFO - STARTING TEST : test_rbac_create_delete_vm
2025-07-21 14:33:29,399 - INFO - TEST DESCRIPTION :
Validate creds passed via orchestrator(nova/neutron)
steps:
1. Add user1 as role1
2. Create Rbac ACL under project with role1:CRUD perms for
a. VN.*
b. VM.*
c. VMI.*
d. IIP.*
e. SG.*
f. LR.*
g. FIP-Pool.*
h. ACL.*
3. user1 should be able to create VN and VM via orchestrator
4. Validate the VN and VM
pass: user should be able to create and delete VN and VM
2025-07-21 14:33:29,560 - DEBUG - Skipping xmpp flap check
2025-07-21 14:33:29,567 - INFO - Initial checks done. Running the testcase now
2025-07-21 14:33:29,567 - INFO -
2025-07-21 14:33:29,836 - DEBUG - Response for create_network : {'network': {'id': '920b6822-92d2-403c-a1e2-48f49b49c108', 'name': 'ctest-TestRbacBasic-57777976-81276570', 'tenant_id': 'a1eda2eee9424c23a12e69cdce9858b2', 'project_id': 'a1eda2eee9424c23a12e69cdce9858b2', 'admin_state_up': True, 'shared': True, 'status': 'ACTIVE', 'router:external': True, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRbacBasic-57777976', 'ctest-TestRbacBasic-57777976-81276570'], 'port_security_enabled': True, 'description': ''}}
2025-07-21 14:33:30,110 - DEBUG - Response for create_subnet : {'subnet': {'id': '68793f12-87d5-4405-b39f-04166e5d60f4', 'name': '', 'tenant_id': 'a1eda2eee9424c23a12e69cdce9858b2', 'network_id': '920b6822-92d2-403c-a1e2-48f49b49c108', 'ip_version': 4, 'cidr': '48.247.70.0/26', 'allocation_pools': [{'start': '48.247.70.2', 'end': '48.247.70.62'}], 'gateway_ip': '48.247.70.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '48.247.70.2', 'tags': [], 'project_id': 'a1eda2eee9424c23a12e69cdce9858b2'}}
2025-07-21 14:33:30,148 - INFO - Created VN ctest-TestRbacBasic-57777976-81276570
2025-07-21 14:33:30,213 - DEBUG - VN ctest-TestRbacBasic-57777976-81276570 UUID is 920b6822-92d2-403c-a1e2-48f49b49c108
2025-07-21 14:33:30,525 - DEBUG - Fetched VN: default-domain:ctest-TestRbacBasic-57777976:ctest-TestRbacBasic-57777976-81276570(920b6822-92d2-403c-a1e2-48f49b49c108) with subnets ['48.247.70.0/26']
2025-07-21 14:33:30,525 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-07-21 14:33:30,542 - DEBUG - Requesting: http://10.0.0.254:8082/domain/6f2dbf83-3a1f-4a4f-8676-305ebe4efd33
2025-07-21 14:33:30,575 - DEBUG - Requesting: http://10.0.0.254:8082/project/a1eda2ee-e942-4c23-a12e-69cdce9858b2
2025-07-21 14:33:30,679 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/920b6822-92d2-403c-a1e2-48f49b49c108
2025-07-21 14:33:30,696 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/920b6822-92d2-403c-a1e2-48f49b49c108
2025-07-21 14:33:30,708 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/460ae6ed-41d5-4398-ac17-6aa5a6029426
2025-07-21 14:33:30,718 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/460ae6ed-41d5-4398-ac17-6aa5a6029426
2025-07-21 14:33:30,727 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/13c0a1ab-4d6a-456c-9439-f41637801ec2
2025-07-21 14:33:30,735 - DEBUG - Route Targets: ['target:64512:8000013']
2025-07-21 14:33:30,735 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/920b6822-92d2-403c-a1e2-48f49b49c108
2025-07-21 14:33:30,748 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/460ae6ed-41d5-4398-ac17-6aa5a6029426
2025-07-21 14:33:30,767 - INFO - Verified VN network id 18 for VN 920b6822-92d2-403c-a1e2-48f49b49c108
2025-07-21 14:33:30,767 - INFO - Verifications in API Server for VN ctest-TestRbacBasic-57777976-81276570 passed
2025-07-21 14:33:30,767 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/920b6822-92d2-403c-a1e2-48f49b49c108
2025-07-21 14:33:30,784 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/460ae6ed-41d5-4398-ac17-6aa5a6029426
2025-07-21 14:33:30,795 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/460ae6ed-41d5-4398-ac17-6aa5a6029426
2025-07-21 14:33:30,806 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/13c0a1ab-4d6a-456c-9439-f41637801ec2
2025-07-21 14:33:30,830 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestRbacBasic-57777976:ctest-TestRbacBasic-57777976-81276570', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestRbacBasic-57777976', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10523619451982725180', 'uuid-lslong': '11664966200257528072'}, 'enable': 'true', 'created': '2025-07-21T14:33:29', 'last-modified': '2025-07-21T14:33:30', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.785311'}
2025-07-21 14:33:30,846 - DEBUG - Route Targets: ['target:64512:8000013']
2025-07-21 14:33:30,847 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-TestRbacBasic-57777976-81276570 passed
2025-07-21 14:33:30,847 - DEBUG - ====Verifying policy data for ctest-TestRbacBasic-57777976-81276570 in API_Server ======
2025-07-21 14:33:30,847 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-07-21 14:33:30,857 - DEBUG - Requesting: http://10.0.0.254:8082/domain/6f2dbf83-3a1f-4a4f-8676-305ebe4efd33
2025-07-21 14:33:30,880 - DEBUG - Requesting: http://10.0.0.254:8082/project/a1eda2ee-e942-4c23-a12e-69cdce9858b2
2025-07-21 14:33:31,003 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/920b6822-92d2-403c-a1e2-48f49b49c108
2025-07-21 14:33:31,030 - DEBUG - =>VN ctest-TestRbacBasic-57777976-81276570 has no policy to be verified
2025-07-21 14:33:31,031 - DEBUG - Verifying the vn in opserver
2025-07-21 14:33:31,031 - DEBUG - Verifying the default-domain:ctest-TestRbacBasic-57777976:ctest-TestRbacBasic-57777976-81276570 virtual network link through opserver 10.0.0.254
2025-07-21 14:33:31,031 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-07-21 14:33:31,057 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestRbacBasic-57777976:ctest-TestRbacBasic-57777976-81276570', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestRbacBasic-57777976:ctest-TestRbacBasic-57777976-81276570?flat'}
2025-07-21 14:33:31,058 - INFO - Validated that VN default-domain:ctest-TestRbacBasic-57777976:ctest-TestRbacBasic-57777976-81276570 is found in opserver
2025-07-21 14:33:31,090 - DEBUG - Do not have enough data to verify VN in agent
2025-07-21 14:33:31,105 - DEBUG - VRF ids for VN ctest-TestRbacBasic-57777976-81276570: {}
2025-07-21 14:33:31,513 - DEBUG - Requesting: http://10.0.0.254:8082/api-access-list/a0ffb7c9-1153-4d72-976f-4d5077dc49a7
2025-07-21 14:33:31,527 - DEBUG - API access-list (a0ffb7c9-1153-4d72-976f-4d5077dc49a7) found in api server
2025-07-21 14:33:31,527 - INFO - API access-list a0ffb7c9-1153-4d72-976f-4d5077dc49a7 verify on api server passed
2025-07-21 14:33:32,063 - DEBUG - Response for create_network : {'network': {'id': '6833eb9a-36b1-4dc8-b1b7-c418792025a1', 'name': 'ctest-TestRbacBasic-57777976-33636910', 'tenant_id': 'a1eda2eee9424c23a12e69cdce9858b2', 'project_id': 'a1eda2eee9424c23a12e69cdce9858b2', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRbacBasic-57777976', 'ctest-TestRbacBasic-57777976-33636910'], 'port_security_enabled': True, 'description': ''}}
2025-07-21 14:33:32,462 - DEBUG - Response for create_subnet : {'subnet': {'id': '80665390-1f3c-417b-8bfd-a1a47901a897', 'name': '', 'tenant_id': 'a1eda2eee9424c23a12e69cdce9858b2', 'network_id': '6833eb9a-36b1-4dc8-b1b7-c418792025a1', 'ip_version': 4, 'cidr': '43.12.229.192/26', 'allocation_pools': [{'start': '43.12.229.194', 'end': '43.12.229.254'}], 'gateway_ip': '43.12.229.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '43.12.229.194', 'tags': [], 'project_id': 'a1eda2eee9424c23a12e69cdce9858b2'}}
2025-07-21 14:33:32,530 - INFO - Created VN ctest-TestRbacBasic-57777976-33636910
2025-07-21 14:33:32,634 - DEBUG - VN ctest-TestRbacBasic-57777976-33636910 UUID is 6833eb9a-36b1-4dc8-b1b7-c418792025a1
2025-07-21 14:33:33,153 - DEBUG - Fetched VN: default-domain:ctest-TestRbacBasic-57777976:ctest-TestRbacBasic-57777976-33636910(6833eb9a-36b1-4dc8-b1b7-c418792025a1) with subnets ['43.12.229.192/26']
2025-07-21 14:33:33,154 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-07-21 14:33:33,171 - DEBUG - Requesting: http://10.0.0.254:8082/domain/6f2dbf83-3a1f-4a4f-8676-305ebe4efd33
2025-07-21 14:33:33,206 - DEBUG - Requesting: http://10.0.0.254:8082/project/a1eda2ee-e942-4c23-a12e-69cdce9858b2
2025-07-21 14:33:33,336 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/6833eb9a-36b1-4dc8-b1b7-c418792025a1
2025-07-21 14:33:33,362 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/6833eb9a-36b1-4dc8-b1b7-c418792025a1
2025-07-21 14:33:33,387 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/5f870897-0305-489c-8c19-2afa65ebc346
2025-07-21 14:33:33,403 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/5f870897-0305-489c-8c19-2afa65ebc346
2025-07-21 14:33:33,419 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/c73de585-9aa8-44e6-aa68-cfecf76ce436
2025-07-21 14:33:33,434 - DEBUG - Route Targets: ['target:64512:8000014']
2025-07-21 14:33:33,434 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/6833eb9a-36b1-4dc8-b1b7-c418792025a1
2025-07-21 14:33:33,470 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/5f870897-0305-489c-8c19-2afa65ebc346
2025-07-21 14:33:33,518 - INFO - Verified VN network id 19 for VN 6833eb9a-36b1-4dc8-b1b7-c418792025a1
2025-07-21 14:33:33,519 - INFO - Verifications in API Server for VN ctest-TestRbacBasic-57777976-33636910 passed
2025-07-21 14:33:33,519 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/6833eb9a-36b1-4dc8-b1b7-c418792025a1
2025-07-21 14:33:33,557 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/5f870897-0305-489c-8c19-2afa65ebc346
2025-07-21 14:33:33,582 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/5f870897-0305-489c-8c19-2afa65ebc346
2025-07-21 14:33:33,609 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/c73de585-9aa8-44e6-aa68-cfecf76ce436
2025-07-21 14:33:33,642 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestRbacBasic-57777976:ctest-TestRbacBasic-57777976-33636910', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestRbacBasic-19303513', 'owner-access': '7', 'group': 'ctest-TestRbacBasic-58542978', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7508604051331829192', 'uuid-lslong': '12805919674841703841'}, 'enable': 'true', 'created': '2025-07-21T14:33:32', 'last-modified': '2025-07-21T14:33:32', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.290310'}
2025-07-21 14:33:33,661 - DEBUG - Route Targets: ['target:64512:8000014']
2025-07-21 14:33:33,661 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-TestRbacBasic-57777976-33636910 passed
2025-07-21 14:33:33,661 - DEBUG - ====Verifying policy data for ctest-TestRbacBasic-57777976-33636910 in API_Server ======
2025-07-21 14:33:33,662 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-07-21 14:33:33,676 - DEBUG - Requesting: http://10.0.0.254:8082/domain/6f2dbf83-3a1f-4a4f-8676-305ebe4efd33
2025-07-21 14:33:33,713 - DEBUG - Requesting: http://10.0.0.254:8082/project/a1eda2ee-e942-4c23-a12e-69cdce9858b2
2025-07-21 14:33:33,831 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/6833eb9a-36b1-4dc8-b1b7-c418792025a1
2025-07-21 14:33:33,853 - DEBUG - =>VN ctest-TestRbacBasic-57777976-33636910 has no policy to be verified
2025-07-21 14:33:33,853 - DEBUG - Verifying the vn in opserver
2025-07-21 14:33:33,853 - DEBUG - Verifying the default-domain:ctest-TestRbacBasic-57777976:ctest-TestRbacBasic-57777976-33636910 virtual network link through opserver 10.0.0.254
2025-07-21 14:33:33,853 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-07-21 14:33:33,881 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestRbacBasic-57777976:ctest-TestRbacBasic-57777976-33636910', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestRbacBasic-57777976:ctest-TestRbacBasic-57777976-33636910?flat'}
2025-07-21 14:33:33,881 - INFO - Validated that VN default-domain:ctest-TestRbacBasic-57777976:ctest-TestRbacBasic-57777976-33636910 is found in opserver
2025-07-21 14:33:33,920 - DEBUG - Do not have enough data to verify VN in agent
2025-07-21 14:33:33,943 - DEBUG - VRF ids for VN ctest-TestRbacBasic-57777976-33636910: {}
2025-07-21 14:33:33,983 - INFO - Creating Security group: ['default-domain', 'ctest-TestRbacBasic-57777976', 'ctest-TestRbacBasic-57777976-41443954']
2025-07-21 14:33:35,805 - DEBUG - Security group: ['default-domain', 'ctest-TestRbacBasic-57777976', 'ctest-TestRbacBasic-57777976-41443954'] created succesfully
2025-07-21 14:33:35,806 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-07-21 14:33:35,853 - DEBUG - Requesting: http://10.0.0.254:8082/domain/6f2dbf83-3a1f-4a4f-8676-305ebe4efd33
2025-07-21 14:33:35,939 - DEBUG - Requesting: http://10.0.0.254:8082/project/a1eda2ee-e942-4c23-a12e-69cdce9858b2
2025-07-21 14:33:36,052 - DEBUG - Requesting: http://10.0.0.254:8082/security-group/6ad64197-bb6b-45f4-81ca-e1a57e43d583
2025-07-21 14:33:36,103 - INFO - Security group ctest-TestRbacBasic-57777976-41443954 found in the API Server
2025-07-21 14:33:36,103 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-07-21 14:33:36,145 - DEBUG - Requesting: http://10.0.0.254:8082/domain/6f2dbf83-3a1f-4a4f-8676-305ebe4efd33
2025-07-21 14:33:36,231 - DEBUG - Requesting: http://10.0.0.254:8082/project/a1eda2ee-e942-4c23-a12e-69cdce9858b2
2025-07-21 14:33:36,357 - DEBUG - Requesting: http://10.0.0.254:8082/access-control-lists
2025-07-21 14:33:36,498 - INFO - Validated that ACLs for Security group ctest-TestRbacBasic-57777976-41443954 are present in API Server
2025-07-21 14:33:36,516 - INFO - Validated that Security group ctest-TestRbacBasic-57777976-41443954 is found in control node 10.0.0.254
2025-07-21 14:33:36,537 - DEBUG - Security group ctest-TestRbacBasic-57777976-41443954 ACLs found in the control node 10.0.0.254
2025-07-21 14:33:36,537 - INFO - Validated SG ctest-TestRbacBasic-57777976-41443954 in Control nodes
2025-07-21 14:33:37,498 - WARNING - Unable to get the list of compute nodes
2025-07-21 14:33:39,478 - INFO - VM ([]) created on node: (None), Zone: (None)
2025-07-21 14:33:39,568 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2025-07-21 14:33:44,741 - DEBUG - VM is in ACTIVE state now
2025-07-21 14:33:44,742 - INFO - VM name : ctest-TestRbacBasic-57777976-04017054
2025-07-21 14:33:44,870 - DEBUG - VM ctest-TestRbacBasic-57777976-04017054 ID is 11a8bc00-dcb2-43fe-a586-632c279d4df8
2025-07-21 14:33:44,991 - DEBUG - VM is in ACTIVE state now
2025-07-21 14:33:45,037 - DEBUG - VM ctest-TestRbacBasic-57777976-04017054 launched on Node cn-jenkins-deploy-platform-ansible-os-3677-1.
2025-07-21 14:33:45,203 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-machine/11a8bc00-dcb2-43fe-a586-632c279d4df8
2025-07-21 14:33:45,248 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-machine/11a8bc00-dcb2-43fe-a586-632c279d4df8
2025-07-21 14:33:45,285 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-machine-interface/6c893754-c2e2-4bb6-aba0-655deddbbfdb
2025-07-21 14:33:45,324 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-machine/11a8bc00-dcb2-43fe-a586-632c279d4df8
2025-07-21 14:33:45,358 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-machine-interface/6c893754-c2e2-4bb6-aba0-655deddbbfdb
2025-07-21 14:33:45,387 - DEBUG - Requesting: http://10.0.0.254:8082/instance-ip/c1645cfd-845d-4797-9878-28bc855e4494
2025-07-21 14:33:45,411 - DEBUG - Verifying in api server 10.0.0.254
2025-07-21 14:33:45,412 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-machine/11a8bc00-dcb2-43fe-a586-632c279d4df8
2025-07-21 14:33:45,431 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-machine-interface/6c893754-c2e2-4bb6-aba0-655deddbbfdb
2025-07-21 14:33:45,458 - DEBUG - Requesting: http://10.0.0.254:8082/instance-ip/c1645cfd-845d-4797-9878-28bc855e4494
2025-07-21 14:33:45,484 - INFO - VM ctest-TestRbacBasic-57777976-04017054 verfication in all API Servers passed
2025-07-21 14:33:45,509 - DEBUG - VM ctest-TestRbacBasic-57777976-04017054 Tap interface: {'index': '3', 'name': 'tap6c893754-c2', 'uuid': '6c893754-c2e2-4bb6-aba0-655deddbbfdb', 'vrf_name': 'default-domain:ctest-TestRbacBasic-57777976:ctest-TestRbacBasic-57777976-33636910:ctest-TestRbacBasic-57777976-33636910', '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': '25', 'l2_label': '29', 'vxlan_id': '19', 'vn_name': 'default-domain:ctest-TestRbacBasic-57777976:ctest-TestRbacBasic-57777976-33636910', 'vm_uuid': '11a8bc00-dcb2-43fe-a586-632c279d4df8', 'vm_name': 'ctest-TestRbacBasic-57777976-04017054', 'ip_addr': '43.12.229.195', 'mac_addr': '02:6c:89:37:54:c2', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.3', 'service_vlan_list': None, 'os_ifindex': '214', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestRbacBasic-57777976:6c893754-c2e2-4bb6-aba0-655deddbbfdb', 'sg_uuid_list': ['e0d22cd1-e04b-44dc-863a-f01ad2e8afa7'], 'static_route_list': None, 'vm_project_uuid': 'a1eda2ee-e942-4c23-a12e-69cdce9858b2', 'admin_state': 'Enabled', 'flow_key_idx': '23', '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-07-21 14:33:45,509 - DEBUG - Agent 10.0.0.254 vrf name: default-domain:ctest-TestRbacBasic-57777976:ctest-TestRbacBasic-57777976-33636910:ctest-TestRbacBasic-57777976-33636910
2025-07-21 14:33:45,515 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestRbacBasic-57777976:ctest-TestRbacBasic-57777976-33636910:ctest-TestRbacBasic-57777976-33636910', 'ucindex': '2', 'mcindex': '2', 'l2index': '2', 'source': 'Config; ', 'uc6index': '2', 'vn': 'default-domain:ctest-TestRbacBasic-57777976:ctest-TestRbacBasic-57777976-33636910', 'table_label': '-1', 'vxlan_id': '19', 'evpnindex': '2', 'brindex': '2', 'mplsindex': '0', 'RD': '10.20.0.17:2', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]}
2025-07-21 14:33:46,505 - DEBUG - VM ctest-TestRbacBasic-57777976-04017054 : Tap interface tap6c893754-c2 is set to Active
2025-07-21 14:33:46,505 - DEBUG - Tap interface tap6c893754-c2 detail : {'index': '3', 'name': 'tap6c893754-c2', 'uuid': '6c893754-c2e2-4bb6-aba0-655deddbbfdb', 'vrf_name': 'default-domain:ctest-TestRbacBasic-57777976:ctest-TestRbacBasic-57777976-33636910:ctest-TestRbacBasic-57777976-33636910', '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': '25', 'l2_label': '29', 'vxlan_id': '19', 'vn_name': 'default-domain:ctest-TestRbacBasic-57777976:ctest-TestRbacBasic-57777976-33636910', 'vm_uuid': '11a8bc00-dcb2-43fe-a586-632c279d4df8', 'vm_name': 'ctest-TestRbacBasic-57777976-04017054', 'ip_addr': '43.12.229.195', 'mac_addr': '02:6c:89:37:54:c2', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.3', 'service_vlan_list': None, 'os_ifindex': '214', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestRbacBasic-57777976:6c893754-c2e2-4bb6-aba0-655deddbbfdb', 'sg_uuid_list': ['e0d22cd1-e04b-44dc-863a-f01ad2e8afa7'], 'static_route_list': None, 'vm_project_uuid': 'a1eda2ee-e942-4c23-a12e-69cdce9858b2', 'admin_state': 'Enabled', 'flow_key_idx': '23', '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-07-21 14:33:46,749 - DEBUG - Starting Layer 2 verification in Agent
2025-07-21 14:33:46,782 - DEBUG - Layer 2 path is seen for VM MAC 02:6c:89:37:54:c2 in agent 10.0.0.254
2025-07-21 14:33:46,782 - DEBUG - Active layer 2 route in agent is present for VMI tap6c893754-c2
2025-07-21 14:33:46,782 - DEBUG - L2 label(29) matches bw route table and interface table
2025-07-21 14:33:46,801 - DEBUG - VN default-domain:ctest-TestRbacBasic-57777976:ctest-TestRbacBasic-57777976-33636910 is found in Agent of node 10.0.0.254
2025-07-21 14:33:47,114 - DEBUG - VRF IDs of VN default-domain:ctest-TestRbacBasic-57777976:ctest-TestRbacBasic-57777976-33636910 is consistent in agent 10.0.0.254
2025-07-21 14:33:47,115 - DEBUG - Route for VM IP ['43.12.229.195'] is consistent in agent 10.0.0.254
2025-07-21 14:33:47,115 - DEBUG - VN default-domain:ctest-TestRbacBasic-57777976:ctest-TestRbacBasic-57777976-33636910 verification for VM ctest-TestRbacBasic-57777976-04017054 in Agent 10.0.0.254 passed
2025-07-21 14:33:47,369 - DEBUG - Starting all layer 2 verification in agent 10.0.0.254
2025-07-21 14:33:47,400 - DEBUG - Route for VM MAC 02:6c:89:37:54:c2 is consistent in agent 10.0.0.254
2025-07-21 14:33:50,709 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1007ms')
2025-07-21 14:33:50,709 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestRbacBasic-57777976-04017054 failed!
2025-07-21 14:33:50,841 - DEBUG - Gateway for vn default-domain:ctest-TestRbacBasic-57777976:ctest-TestRbacBasic-57777976-33636910 is 43.12.229.193 and allocation pool is NOT set
2025-07-21 14:33:52,885 - 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=14.7 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=1.71 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 1.711/8.198/14.685/6.487 ms')
2025-07-21 14:33:52,885 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestRbacBasic-57777976-04017054 passed
2025-07-21 14:33:52,885 - INFO - VM ctest-TestRbacBasic-57777976-04017054 verifications in Compute nodes passed
2025-07-21 14:33:53,420 - DEBUG - Validated VM route 43.12.229.195 in vrouter of 10.0.0.254
2025-07-21 14:33:53,424 - DEBUG - Remote Nh validation passed. Expected Tunnel, label: None, 0; Got None,0
2025-07-21 14:33:53,424 - INFO - Validated routes of VM ctest-TestRbacBasic-57777976-04017054 in all vrouters
2025-07-21 14:33:54,269 - INFO - Verification in Control-nodes for VM ctest-TestRbacBasic-57777976-04017054 passed
2025-07-21 14:33:54,270 - DEBUG - Verifying the vm in opserver
2025-07-21 14:33:54,270 - DEBUG - Verifying in collector 10.0.0.254 ...
2025-07-21 14:33:54,270 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-machine/11a8bc00-dcb2-43fe-a586-632c279d4df8?flat
2025-07-21 14:33:54,672 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-machine/11a8bc00-dcb2-43fe-a586-632c279d4df8?flat
2025-07-21 14:33:54,809 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestRbacBasic-57777976:6c893754-c2e2-4bb6-aba0-655deddbbfdb?flat
2025-07-21 14:33:55,052 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestRbacBasic-57777976:6c893754-c2e2-4bb6-aba0-655deddbbfdb?flat
2025-07-21 14:33:55,268 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-machine/11a8bc00-dcb2-43fe-a586-632c279d4df8?flat
2025-07-21 14:33:55,451 - DEBUG - Verifying vm in vn uve
2025-07-21 14:33:55,451 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestRbacBasic-57777976:6c893754-c2e2-4bb6-aba0-655deddbbfdb?flat
2025-07-21 14:33:55,684 - DEBUG - VM uve shows interface as {'virtual_network': 'default-domain:ctest-TestRbacBasic-57777976:ctest-TestRbacBasic-57777976-33636910', 'mac_address': '02:6c:89:37:54:c2', 'ip6_active': False, 'vhostuser_mode': 0, 'ip4_active': True, 'active': True, 'vn_uuid': '6833eb9a-36b1-4dc8-b1b7-c418792025a1', 'vm_name': 'ctest-TestRbacBasic-57777976-04017054', 'tx_vlan': 65535, 'uuid': '6c893754-c2e2-4bb6-aba0-655deddbbfdb', 'admin_state': True, 'rx_vlan': 65535, 'l2_active': True, 'is_health_check_active': True, 'fixed_ip4_list': ['43.12.229.195'], 'port_mirror_enabled': False, '__T': 1753108424762046, 'gateway': '43.12.229.193', 'ip_address': '43.12.229.195', 'ip6_address': '::', 'label': 25, 'vm_uuid': '11a8bc00-dcb2-43fe-a586-632c279d4df8'}
2025-07-21 14:33:55,684 - DEBUG - VM uve shows ip address as ['43.12.229.195', '::']
2025-07-21 14:33:55,684 - DEBUG - VM uve shows virtual network as default-domain:ctest-TestRbacBasic-57777976:ctest-TestRbacBasic-57777976-33636910
2025-07-21 14:33:55,684 - INFO - Verifying through opserver in 10.0.0.254
2025-07-21 14:33:55,685 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestRbacBasic-57777976:ctest-TestRbacBasic-57777976-33636910?flat
2025-07-21 14:33:55,993 - DEBUG - expected vm list 11a8bc00-dcb2-43fe-a586-632c279d4df8
2025-07-21 14:33:55,993 - DEBUG - Extracted vm list ['11a8bc00-dcb2-43fe-a586-632c279d4df8']
2025-07-21 14:33:55,993 - INFO - VM 11a8bc00-dcb2-43fe-a586-632c279d4df8 is present in default-domain:ctest-TestRbacBasic-57777976:ctest-TestRbacBasic-57777976-33636910
2025-07-21 14:33:55,994 - DEBUG - Verifying vm in vrouter uve
2025-07-21 14:33:55,994 - DEBUG - Getting info from collector 10.0.0.254..
2025-07-21 14:33:55,994 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-machine/11a8bc00-dcb2-43fe-a586-632c279d4df8?flat
2025-07-21 14:33:56,116 - DEBUG - VM uve shows vrouter as ['cn-jenkins-deploy-platform-ansible-os-3677-1']
2025-07-21 14:33:56,116 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/generator/cn-jenkins-deploy-platform-ansible-os-3677-1:Compute:contrail-vrouter-agent:0?flat
2025-07-21 14:33:56,633 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/generator/cn-jenkins-deploy-platform-ansible-os-3677-1:Compute:contrail-vrouter-agent:0?flat
2025-07-21 14:33:56,764 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/generator/cn-jenkins-deploy-platform-ansible-os-3677-1:Compute:contrail-vrouter-agent:0?flat
2025-07-21 14:33:56,937 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/vrouter/cn-jenkins-deploy-platform-ansible-os-3677-1?flat
2025-07-21 14:33:57,032 - DEBUG - VM 11a8bc00-dcb2-43fe-a586-632c279d4df8 is present in vrouter cn-jenkins-deploy-platform-ansible-os-3677-1 uve
2025-07-21 14:33:57,032 - DEBUG - Validated that VM ctest-TestRbacBasic-57777976-04017054 is in Vrouter cn-jenkins-deploy-platform-ansible-os-3677-1 UVE
2025-07-21 14:33:57,032 - DEBUG - Verifying vm tap interface/vn in vrouter uve
2025-07-21 14:33:57,054 - DEBUG - Expected tap interface of VM uuid 11a8bc00-dcb2-43fe-a586-632c279d4df8 is default-domain:ctest-TestRbacBasic-57777976:6c893754-c2e2-4bb6-aba0-655deddbbfdb
2025-07-21 14:33:57,055 - DEBUG - Expected VN of VM uuid 11a8bc00-dcb2-43fe-a586-632c279d4df8 is default-domain:ctest-TestRbacBasic-57777976:ctest-TestRbacBasic-57777976-33636910
2025-07-21 14:33:57,055 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/generator/cn-jenkins-deploy-platform-ansible-os-3677-1:Compute:contrail-vrouter-agent:0?flat
2025-07-21 14:33:57,520 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/generator/cn-jenkins-deploy-platform-ansible-os-3677-1:Compute:contrail-vrouter-agent:0?flat
2025-07-21 14:33:57,697 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/generator/cn-jenkins-deploy-platform-ansible-os-3677-1:Compute:contrail-vrouter-agent:0?flat
2025-07-21 14:33:57,864 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/vrouter/cn-jenkins-deploy-platform-ansible-os-3677-1?flat
2025-07-21 14:33:57,945 - DEBUG - VM 11a8bc00-dcb2-43fe-a586-632c279d4df8 is present in vrouter cn-jenkins-deploy-platform-ansible-os-3677-1 uve
2025-07-21 14:33:57,946 - INFO - tap interface default-domain:ctest-TestRbacBasic-57777976:6c893754-c2e2-4bb6-aba0-655deddbbfdb of vm 11a8bc00-dcb2-43fe-a586-632c279d4df8 is present in vrouter cn-jenkins-deploy-platform-ansible-os-3677-1 uve
2025-07-21 14:33:57,946 - DEBUG - Connected network default-domain:ctest-TestRbacBasic-57777976:ctest-TestRbacBasic-57777976-33636910 of vm 11a8bc00-dcb2-43fe-a586-632c279d4df8 is present in vrouter cn-jenkins-deploy-platform-ansible-os-3677-1 uve
2025-07-21 14:33:57,946 - DEBUG - Verifying the 11a8bc00-dcb2-43fe-a586-632c279d4df8 virtual network link through opserver 10.0.0.254
2025-07-21 14:33:57,946 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-machines
2025-07-21 14:33:58,077 - DEBUG - VM link and name as {'name': '11a8bc00-dcb2-43fe-a586-632c279d4df8', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-machine/11a8bc00-dcb2-43fe-a586-632c279d4df8?flat'}
2025-07-21 14:33:58,078 - INFO - VM ctest-TestRbacBasic-57777976-04017054 validations in Opserver passed
2025-07-21 14:33:58,525 - DEBUG - FIP pool floating-ip-pool already present, not creating it
2025-07-21 14:33:59,245 - DEBUG - Associating FIP:e58c1555-43e8-422a-8678-c1c915b3c114 with VMI:11a8bc00-dcb2-43fe-a586-632c279d4df8
2025-07-21 14:33:59,469 - INFO - FIP 48.247.70.3 verification for passed on all Control-nodes
2025-07-21 14:33:59,469 - INFO - FIP ID e58c1555-43e8-422a-8678-c1c915b3c114(48.247.70.3) validation in Control node passed
2025-07-21 14:33:59,510 - DEBUG - agent_label query returned:{'nh': {'type': 'interface', 'ref_count': '12', 'valid': 'true', 'policy': 'enabled', 'itf': 'tap6c893754-c2', 'mac': ['2:6c:89:37:54:c2'], 'mcast': 'disabled', 'nh_index': '23', 'vxlan_flag': 'false', 'intf_flags': '1', 'isid': '0', 'learning_enabled': 'false', 'etree_leaf': 'false', 'layer2_control_word': 'false', 'crypt_all_traffic': 'false', 'crypt_path_available': 'false', 'crypt_interface': None}, 'label': '25', 'vxlan_id': '0', 'peer': '10.20.0.17', 'dest_vn_list': ['default-domain:ctest-TestRbacBasic-57777976:ctest-TestRbacBasic-57777976-81276570'], 'unresolved': 'false', 'sg_list': ['8000012'], 'supported_tunnel_type': 'MPLSoGRE MPLSoUDP ', 'active_tunnel_type': 'MPLSoUDP', 'stale': 'false', 'path_preference_data': {'sequence': '0', 'preference': '200', 'ecmp': 'true'}, 'active_label': '25', 'ecmp_hashing_fields': 'l3-source-address,l3-destination-address,l4-protocol,l4-source-port,l4-destination-port,', 'communities': None, 'peer_sequence_number': '1', 'etree_leaf': 'false', 'layer2_control_word': 'false', 'tag_list': None, 'inactive': 'false', 'origin_vn': None}
2025-07-21 14:33:59,511 - DEBUG - Route for FIP IP 48.247.70.3 is present in agent 10.0.0.254
2025-07-21 14:33:59,511 - DEBUG - FIP 48.247.70.3 verification for VM ctest-TestRbacBasic-57777976-04017054 in Agent 10.0.0.254 passed
2025-07-21 14:33:59,511 - INFO - FIP ID e58c1555-43e8-422a-8678-c1c915b3c114(48.247.70.3) validation in agents passed
2025-07-21 14:33:59,511 - DEBUG - Requesting: http://10.0.0.254:8082/floating-ip/e58c1555-43e8-422a-8678-c1c915b3c114
2025-07-21 14:33:59,542 - DEBUG - FIP e58c1555-43e8-422a-8678-c1c915b3c114 is present in API server
2025-07-21 14:33:59,542 - INFO - FIP ID e58c1555-43e8-422a-8678-c1c915b3c114(48.247.70.3) validation in API Server passed
2025-07-21 14:34:00,289 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-machine/11a8bc00-dcb2-43fe-a586-632c279d4df8
2025-07-21 14:34:00,312 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-machine-interface/6c893754-c2e2-4bb6-aba0-655deddbbfdb
2025-07-21 14:34:00,339 - DEBUG - Security group ctest-TestRbacBasic-57777976-41443954 is attached to the VM ctest-TestRbacBasic-57777976-04017054
2025-07-21 14:34:00,427 - DEBUG - Agent: Security group ctest-TestRbacBasic-57777976-41443954 is attached to the VM ctest-TestRbacBasic-57777976-04017054
2025-07-21 14:34:00,625 - INFO - Validated that SG ctest-TestRbacBasic-57777976-41443954 is bound to VM ctest-TestRbacBasic-57777976-04017054
2025-07-21 14:34:00,689 - INFO - Deleting VM ctest-TestRbacBasic-57777976-04017054
2025-07-21 14:34:00,781 - DEBUG - Verifying in api server 10.0.0.254
2025-07-21 14:34:00,781 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-machine/11a8bc00-dcb2-43fe-a586-632c279d4df8
2025-07-21 14:34:00,834 - DEBUG - VM ID 11a8bc00-dcb2-43fe-a586-632c279d4df8 of VM ctest-TestRbacBasic-57777976-04017054 is still found in API Server
2025-07-21 14:34:02,835 - DEBUG - Verifying in api server 10.0.0.254
2025-07-21 14:34:02,835 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-machine/11a8bc00-dcb2-43fe-a586-632c279d4df8
2025-07-21 14:34:02,880 - DEBUG - Response Code: 404
2025-07-21 14:34:02,880 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-machine/11a8bc00-dcb2-43fe-a586-632c279d4df8
2025-07-21 14:34:02,916 - DEBUG - Response Code: 404
2025-07-21 14:34:02,917 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-machine/11a8bc00-dcb2-43fe-a586-632c279d4df8
2025-07-21 14:34:02,959 - DEBUG - Response Code: 404
2025-07-21 14:34:02,960 - INFO - VM ctest-TestRbacBasic-57777976-04017054 is fully removed in API-Server
2025-07-21 14:34:03,071 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found
2025-07-21 14:34:03,160 - DEBUG - Validated that vrouter 10.0.0.254 does not have VMs route for VN default-domain:ctest-TestRbacBasic-57777976:ctest-TestRbacBasic-57777976-33636910
2025-07-21 14:34:03,160 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestRbacBasic-57777976:ctest-TestRbacBasic-57777976-33636910
2025-07-21 14:34:03,161 - INFO - VM ctest-TestRbacBasic-57777976-04017054 is removed in Compute, and routes are removed in all compute nodes
2025-07-21 14:34:03,562 - INFO - Routes for VM ctest-TestRbacBasic-57777976-04017054 is removed in all control-nodes
2025-07-21 14:34:03,562 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/generator/cn-jenkins-deploy-platform-ansible-os-3677-1.:Compute:contrail-vrouter-agent:0?flat
2025-07-21 14:34:04,105 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/generator/cn-jenkins-deploy-platform-ansible-os-3677-1.:Compute:contrail-vrouter-agent:0?flat
2025-07-21 14:34:04,264 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/generator/cn-jenkins-deploy-platform-ansible-os-3677-1.:Compute:contrail-vrouter-agent:0?flat
2025-07-21 14:34:04,337 - WARNING - ops generator uve analytics/uves/generator/cn-jenkins-deploy-platform-ansible-os-3677-1.:Compute:contrail-vrouter-agent?flat not returned
2025-07-21 14:34:04,337 - ERROR - query returned none
2025-07-21 14:34:04,338 - DEBUG - can't get collector's generator
2025-07-21 14:34:07,339 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/generator/cn-jenkins-deploy-platform-ansible-os-3677-1.:Compute:contrail-vrouter-agent:0?flat
2025-07-21 14:34:07,370 - WARNING - ops generator uve analytics/uves/generator/cn-jenkins-deploy-platform-ansible-os-3677-1.:Compute:contrail-vrouter-agent?flat not returned
2025-07-21 14:34:07,370 - ERROR - query returned none
2025-07-21 14:34:07,370 - DEBUG - can't get collector's generator
2025-07-21 14:34:10,371 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/generator/cn-jenkins-deploy-platform-ansible-os-3677-1.:Compute:contrail-vrouter-agent:0?flat
2025-07-21 14:34:10,409 - WARNING - ops generator uve analytics/uves/generator/cn-jenkins-deploy-platform-ansible-os-3677-1.:Compute:contrail-vrouter-agent?flat not returned
2025-07-21 14:34:10,409 - ERROR - query returned none
2025-07-21 14:34:10,409 - DEBUG - can't get collector's generator
2025-07-21 14:34:13,410 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/generator/cn-jenkins-deploy-platform-ansible-os-3677-1.:Compute:contrail-vrouter-agent:0?flat
2025-07-21 14:34:13,440 - WARNING - ops generator uve analytics/uves/generator/cn-jenkins-deploy-platform-ansible-os-3677-1.:Compute:contrail-vrouter-agent?flat not returned
2025-07-21 14:34:13,440 - ERROR - query returned none
2025-07-21 14:34:13,440 - DEBUG - can't get collector's generator
2025-07-21 14:34:16,441 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/generator/cn-jenkins-deploy-platform-ansible-os-3677-1.:Compute:contrail-vrouter-agent:0?flat
2025-07-21 14:34:16,474 - WARNING - ops generator uve analytics/uves/generator/cn-jenkins-deploy-platform-ansible-os-3677-1.:Compute:contrail-vrouter-agent?flat not returned
2025-07-21 14:34:16,474 - ERROR - query returned none
2025-07-21 14:34:16,475 - DEBUG - can't get collector's generator
2025-07-21 14:34:19,476 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/generator/cn-jenkins-deploy-platform-ansible-os-3677-1.:Compute:contrail-vrouter-agent:0?flat
2025-07-21 14:34:19,508 - WARNING - ops generator uve analytics/uves/generator/cn-jenkins-deploy-platform-ansible-os-3677-1.:Compute:contrail-vrouter-agent?flat not returned
2025-07-21 14:34:19,509 - ERROR - query returned none
2025-07-21 14:34:19,509 - DEBUG - can't get collector's generator
2025-07-21 14:34:22,510 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/generator/cn-jenkins-deploy-platform-ansible-os-3677-1.:Compute:contrail-vrouter-agent:0?flat
2025-07-21 14:34:22,543 - WARNING - ops generator uve analytics/uves/generator/cn-jenkins-deploy-platform-ansible-os-3677-1.:Compute:contrail-vrouter-agent?flat not returned
2025-07-21 14:34:22,543 - ERROR - query returned none
2025-07-21 14:34:22,544 - DEBUG - can't get collector's generator
2025-07-21 14:34:25,545 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/generator/cn-jenkins-deploy-platform-ansible-os-3677-1.:Compute:contrail-vrouter-agent:0?flat
2025-07-21 14:34:25,581 - WARNING - ops generator uve analytics/uves/generator/cn-jenkins-deploy-platform-ansible-os-3677-1.:Compute:contrail-vrouter-agent?flat not returned
2025-07-21 14:34:25,581 - ERROR - query returned none
2025-07-21 14:34:25,582 - DEBUG - can't get collector's generator
2025-07-21 14:34:28,582 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/generator/cn-jenkins-deploy-platform-ansible-os-3677-1.:Compute:contrail-vrouter-agent:0?flat
2025-07-21 14:34:28,617 - WARNING - ops generator uve analytics/uves/generator/cn-jenkins-deploy-platform-ansible-os-3677-1.:Compute:contrail-vrouter-agent?flat not returned
2025-07-21 14:34:28,617 - ERROR - query returned none
2025-07-21 14:34:28,618 - DEBUG - can't get collector's generator
2025-07-21 14:34:31,619 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/generator/cn-jenkins-deploy-platform-ansible-os-3677-1.:Compute:contrail-vrouter-agent:0?flat
2025-07-21 14:34:31,645 - WARNING - ops generator uve analytics/uves/generator/cn-jenkins-deploy-platform-ansible-os-3677-1.:Compute:contrail-vrouter-agent?flat not returned
2025-07-21 14:34:31,645 - ERROR - query returned none
2025-07-21 14:34:31,645 - DEBUG - can't get collector's generator
2025-07-21 14:34:34,646 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/generator/cn-jenkins-deploy-platform-ansible-os-3677-1.:Compute:contrail-vrouter-agent:0?flat
2025-07-21 14:34:34,679 - WARNING - ops generator uve analytics/uves/generator/cn-jenkins-deploy-platform-ansible-os-3677-1.:Compute:contrail-vrouter-agent?flat not returned
2025-07-21 14:34:34,679 - ERROR - query returned none
2025-07-21 14:34:34,679 - DEBUG - can't get collector's generator
2025-07-21 14:34:37,680 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/generator/cn-jenkins-deploy-platform-ansible-os-3677-1.:Compute:contrail-vrouter-agent:0?flat
2025-07-21 14:34:37,711 - WARNING - ops generator uve analytics/uves/generator/cn-jenkins-deploy-platform-ansible-os-3677-1.:Compute:contrail-vrouter-agent?flat not returned
2025-07-21 14:34:37,711 - ERROR - query returned none
2025-07-21 14:34:37,711 - DEBUG - can't get collector's generator
2025-07-21 14:34:40,713 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/generator/cn-jenkins-deploy-platform-ansible-os-3677-1.:Compute:contrail-vrouter-agent:0?flat
2025-07-21 14:34:40,743 - WARNING - ops generator uve analytics/uves/generator/cn-jenkins-deploy-platform-ansible-os-3677-1.:Compute:contrail-vrouter-agent?flat not returned
2025-07-21 14:34:40,744 - ERROR - query returned none
2025-07-21 14:34:40,744 - DEBUG - can't get collector's generator
2025-07-21 14:34:43,747 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/generator/cn-jenkins-deploy-platform-ansible-os-3677-1.:Compute:contrail-vrouter-agent:0?flat
2025-07-21 14:34:43,778 - WARNING - ops generator uve analytics/uves/generator/cn-jenkins-deploy-platform-ansible-os-3677-1.:Compute:contrail-vrouter-agent?flat not returned
2025-07-21 14:34:43,778 - ERROR - query returned none
2025-07-21 14:34:43,778 - DEBUG - can't get collector's generator
2025-07-21 14:34:46,779 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/generator/cn-jenkins-deploy-platform-ansible-os-3677-1.:Compute:contrail-vrouter-agent:0?flat
2025-07-21 14:34:46,814 - WARNING - ops generator uve analytics/uves/generator/cn-jenkins-deploy-platform-ansible-os-3677-1.:Compute:contrail-vrouter-agent?flat not returned
2025-07-21 14:34:46,815 - ERROR - query returned none
2025-07-21 14:34:46,815 - DEBUG - can't get collector's generator
2025-07-21 14:34:49,816 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/generator/cn-jenkins-deploy-platform-ansible-os-3677-1.:Compute:contrail-vrouter-agent:0?flat
2025-07-21 14:34:49,839 - WARNING - ops generator uve analytics/uves/generator/cn-jenkins-deploy-platform-ansible-os-3677-1.:Compute:contrail-vrouter-agent?flat not returned
2025-07-21 14:34:49,839 - ERROR - query returned none
2025-07-21 14:34:49,839 - DEBUG - can't get collector's generator
2025-07-21 14:34:49,840 - INFO - Deleting Security group: ['default-domain', 'ctest-TestRbacBasic-57777976', 'ctest-TestRbacBasic-57777976-41443954']
2025-07-21 14:34:50,044 - INFO - Security group: ['default-domain', 'ctest-TestRbacBasic-57777976', 'ctest-TestRbacBasic-57777976-41443954'] deleted successfully.
2025-07-21 14:34:50,044 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-07-21 14:34:50,073 - DEBUG - Requesting: http://10.0.0.254:8082/domain/6f2dbf83-3a1f-4a4f-8676-305ebe4efd33
2025-07-21 14:34:50,114 - DEBUG - Requesting: http://10.0.0.254:8082/project/a1eda2ee-e942-4c23-a12e-69cdce9858b2
2025-07-21 14:34:50,214 - INFO - Security group ctest-TestRbacBasic-57777976-41443954 removed from the API Server
2025-07-21 14:34:50,215 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-07-21 14:34:50,235 - DEBUG - Requesting: http://10.0.0.254:8082/domain/6f2dbf83-3a1f-4a4f-8676-305ebe4efd33
2025-07-21 14:34:50,263 - DEBUG - Requesting: http://10.0.0.254:8082/project/a1eda2ee-e942-4c23-a12e-69cdce9858b2
2025-07-21 14:34:50,380 - DEBUG - Requesting: http://10.0.0.254:8082/access-control-lists
2025-07-21 14:34:50,453 - DEBUG - ACLs for Security group ctest-TestRbacBasic-57777976-41443954 removed from the API Server
2025-07-21 14:34:50,471 - INFO - security group ctest-TestRbacBasic-57777976-41443954 removed from Control-node 10.0.0.254
2025-07-21 14:34:50,483 - DEBUG - Security group ctest-TestRbacBasic-57777976-41443954 ACLs removed from the control node 10.0.0.254
2025-07-21 14:34:50,483 - INFO - Validated that SG ctest-TestRbacBasic-57777976-41443954 is not in control nodes
2025-07-21 14:34:50,483 - INFO - Deleting VN ctest-TestRbacBasic-57777976-33636910
2025-07-21 14:34:50,792 - DEBUG - Response for deleting network ()
2025-07-21 14:34:50,836 - DEBUG - Requesting: http://10.0.0.254:8082/api-access-list/a0ffb7c9-1153-4d72-976f-4d5077dc49a7
2025-07-21 14:34:50,845 - DEBUG - Response Code: 404
2025-07-21 14:34:50,846 - INFO - API access-list (a0ffb7c9-1153-4d72-976f-4d5077dc49a7) got deleted in api server
2025-07-21 14:34:51,004 - INFO - Deleting VN ctest-TestRbacBasic-57777976-81276570
2025-07-21 14:34:51,300 - DEBUG - Response for deleting network ()
2025-07-21 14:34:51,470 - DEBUG - Skipping xmpp flap check
2025-07-21 14:34:51,470 - INFO - END TEST : test_rbac_create_delete_vm : PASSED[0:01:22]
2025-07-21 14:34:51,471 - INFO - --------------------------------------------------------------------------------
2025-07-21 14:34:53,961 - INFO - Deleted project: ctest-TestRbacBasic-57777976, ID : a1eda2ee-e942-4c23-a12e-69cdce9858b2