MartinEmrich commented on issue #8177:
URL: https://github.com/apache/cloudstack/issues/8177#issuecomment-1790972973
yes, the tool uses `updateVirtualMachine` to update the VM name. From the
logs, I isolated two Context (?) IDs. one for the API request:
````
# grep ctx-ca192b9b cs-8177-cleanup.log
2023-11-02 08:49:44,889 DEBUG [c.c.a.ApiServlet]
(qtp1444635922-151964:ctx-ca192b9b) (logid:7a0443fc) ===START=== 10.12.0.248
-- GET
name=test-ci-12-CDSDEL&id=933103d7-9a77-429b-9e51-93b70c06bb5d&command=updateVirtualMachine&apiKey=XXX&signature=XXX
2023-11-02 08:49:44,893 DEBUG [c.c.a.ApiServer]
(qtp1444635922-151964:ctx-ca192b9b ctx-03f9a4cb) (logid:7a0443fc) CIDRs from
which account 'Account
[{"accountName":"admin","id":39,"uuid":"813308c2-8405-46c3-aa09-0e6bbd90e3b1"}]'
is allowed to perform API calls: 0.0.0.0/0,::/0
2023-11-02 08:49:44,896 WARN [o.a.c.a.ProjectRoleBasedApiAccessChecker]
(qtp1444635922-151964:ctx-ca192b9b ctx-03f9a4cb) (logid:7a0443fc) Project is
null, ProjectRoleBasedApiAccessChecker only applies to projects, returning API
[updateVirtualMachine] for user [User
{"username":"admin","uuid":"5be0d35b-ba21-4240-966a-3d4901cabf0c"}.] as allowed.
2023-11-02 08:49:44,896 DEBUG [o.a.c.a.StaticRoleBasedAPIAccessChecker]
(qtp1444635922-151964:ctx-ca192b9b ctx-03f9a4cb) (logid:7a0443fc) RoleService
is enabled. We will use it instead of StaticRoleBasedAPIAccessChecker.
2023-11-02 08:49:44,897 DEBUG [o.a.c.r.ApiRateLimitServiceImpl]
(qtp1444635922-151964:ctx-ca192b9b ctx-03f9a4cb) (logid:7a0443fc) API rate
limiting is disabled. We will not use ApiRateLimitService.
2023-11-02 08:49:44,897 DEBUG [o.a.c.a.BaseCmd]
(qtp1444635922-151964:ctx-ca192b9b ctx-03f9a4cb ctx-a6398ec2) (logid:7a0443fc)
Ignoring parameter displayvm as the caller is not authorized to pass it in
2023-11-02 08:49:44,897 DEBUG [o.a.c.a.BaseCmd]
(qtp1444635922-151964:ctx-ca192b9b ctx-03f9a4cb ctx-a6398ec2) (logid:7a0443fc)
Ignoring parameter instancename as the caller is not authorized to pass it in
2023-11-02 08:49:44,897 DEBUG [o.a.c.a.BaseCmd]
(qtp1444635922-151964:ctx-ca192b9b ctx-03f9a4cb ctx-a6398ec2) (logid:7a0443fc)
Ignoring parameter extraconfig as the caller is not authorized to pass it in
2023-11-02 08:49:44,897 DEBUG [o.a.c.a.BaseCmd]
(qtp1444635922-151964:ctx-ca192b9b ctx-03f9a4cb ctx-a6398ec2) (logid:7a0443fc)
Ignoring parameter customid as the caller is not authorized to pass it in
2023-11-02 08:49:44,902 DEBUG [c.c.u.AccountManagerImpl]
(qtp1444635922-151964:ctx-ca192b9b ctx-03f9a4cb ctx-a6398ec2) (logid:7a0443fc)
Access to Account
[{"accountName":"admin","id":39,"uuid":"813308c2-8405-46c3-aa09-0e6bbd90e3b1"}]
granted to Account
[{"accountName":"admin","id":39,"uuid":"c8ef4b22-e803-49ed-9275-1363122ae4b6"}]
by DomainChecker
2023-11-02 08:49:44,904 DEBUG [c.c.u.AccountManagerImpl]
(qtp1444635922-151964:ctx-ca192b9b ctx-03f9a4cb ctx-a6398ec2) (logid:7a0443fc)
Access to VM[User|test-ci-12] granted to Account
[{"accountName":"admin","id":39,"uuid":"c8ef4b22-e803-49ed-9275-1363122ae4b6"}]
by DomainChecker
2023-11-02 08:49:44,906 DEBUG [o.a.c.a.BaseCmd]
(qtp1444635922-151964:ctx-ca192b9b ctx-03f9a4cb ctx-a6398ec2) (logid:7a0443fc)
Ignoring parameter displayvm as the caller is not authorized to pass it in
2023-11-02 08:49:44,906 DEBUG [o.a.c.a.BaseCmd]
(qtp1444635922-151964:ctx-ca192b9b ctx-03f9a4cb ctx-a6398ec2) (logid:7a0443fc)
Ignoring parameter instancename as the caller is not authorized to pass it in
2023-11-02 08:49:44,906 DEBUG [o.a.c.a.BaseCmd]
(qtp1444635922-151964:ctx-ca192b9b ctx-03f9a4cb ctx-a6398ec2) (logid:7a0443fc)
Ignoring parameter extraconfig as the caller is not authorized to pass it in
2023-11-02 08:49:44,906 DEBUG [o.a.c.a.BaseCmd]
(qtp1444635922-151964:ctx-ca192b9b ctx-03f9a4cb ctx-a6398ec2) (logid:7a0443fc)
Ignoring parameter customid as the caller is not authorized to pass it in
2023-11-02 08:49:44,910 DEBUG [c.c.u.AccountManagerImpl]
(qtp1444635922-151964:ctx-ca192b9b ctx-03f9a4cb ctx-a6398ec2) (logid:7a0443fc)
Access to VM instance
{"id":6843,"instanceName":"i-39-6843-VM","type":"User","uuid":"933103d7-9a77-429b-9e51-93b70c06bb5d"}
granted to Account
[{"accountName":"admin","id":39,"uuid":"c8ef4b22-e803-49ed-9275-1363122ae4b6"}]
by DomainChecker
2023-11-02 08:49:44,942 DEBUG [c.c.a.t.Request]
(qtp1444635922-151964:ctx-ca192b9b ctx-03f9a4cb ctx-a6398ec2) (logid:7a0443fc)
Seq 21-4298404369348438752: Sending { Cmd , MgmtId: 139289717972276, via:
21(XXX), Ver: v1, Flags: 100011,
[{"com.cloud.agent.api.routing.DhcpEntryCommand":{"vmMac":"1e:00:5a:00:21:8d","vmIpAddress":"10.12.96.9","vmName":"test-ci-12-CDSDEL","defaultRouter":"10.12.96.1","defaultDns":"10.12.96.3","duid":"00:03:00:01:1e:00:5a:00:21:8d","isDefault":"true","executeInSequence":"false","remove":"false","accessDetails":{"router.name":"r-6849-VM","router.guest.ip":"10.12.96.3","router.ip":"169.254.3.7","zone.network.type":"Advanced"},"wait":"0","bypassHostMaintenance":"false"}}]
}
2023-11-02 08:49:44,942 DEBUG [c.c.a.t.Request]
(qtp1444635922-151964:ctx-ca192b9b ctx-03f9a4cb ctx-a6398ec2) (logid:7a0443fc)
Seq 21-4298404369348438752: Executing: { Cmd , MgmtId: 139289717972276, via:
21(XXX), Ver: v1, Flags: 100011,
[{"com.cloud.agent.api.routing.DhcpEntryCommand":{"vmMac":"1e:00:5a:00:21:8d","vmIpAddress":"10.12.96.9","vmName":"test-ci-12-CDSDEL","defaultRouter":"10.12.96.1","defaultDns":"10.12.96.3","duid":"00:03:00:01:1e:00:5a:00:21:8d","isDefault":"true","executeInSequence":"false","remove":"false","accessDetails":{"router.name":"r-6849-VM","router.guest.ip":"10.12.96.3","router.ip":"169.254.3.7","zone.network.type":"Advanced"},"wait":"0","bypassHostMaintenance":"false"}}]
}
2023-11-02 08:49:47,248 DEBUG [c.c.a.t.Request]
(qtp1444635922-151964:ctx-ca192b9b ctx-03f9a4cb ctx-a6398ec2) (logid:7a0443fc)
Seq 21-4298404369348438752: Received: { Ans: , MgmtId: 139289717972276, via:
21(XXX), Ver: v1, Flags: 10, { GroupAnswer } }
2023-11-02 08:49:47,250 DEBUG [c.c.n.NetworkModelImpl]
(qtp1444635922-151964:ctx-ca192b9b ctx-03f9a4cb ctx-a6398ec2) (logid:7a0443fc)
Service SecurityGroup is not supported in the network id=285
2023-11-02 08:49:47,259 DEBUG [o.a.c.n.t.BasicNetworkTopology]
(qtp1444635922-151964:ctx-ca192b9b ctx-03f9a4cb ctx-a6398ec2) (logid:7a0443fc)
SAVE USERDATA TO ROUTE RULES
2023-11-02 08:49:47,259 DEBUG [o.a.c.n.t.BasicNetworkTopology]
(qtp1444635922-151964:ctx-ca192b9b ctx-03f9a4cb ctx-a6398ec2) (logid:7a0443fc)
Applying save userdata entry in network Network {"id": 285, "name":
"CDSKLNetwork", "uuid": "6710de0c-d4f5-48bf-bd8b-1543c4d3f888",
"networkofferingid": 15}
2023-11-02 08:49:47,277 DEBUG [c.c.a.t.Request]
(qtp1444635922-151964:ctx-ca192b9b ctx-03f9a4cb ctx-a6398ec2) (logid:7a0443fc)
Seq 21-4298404369348438753: Sending { Cmd , MgmtId: 139289717972276, via:
21(XXX), Ver: v1, Flags: 100011,
[{"com.cloud.agent.api.routing.VmDataCommand":{"vmIpAddress":"10.12.96.9","vmName":"test-ci-12-CDSDEL","executeInSequence":"false","accessDetails":{"router.name":"r-6849-VM","router.guest.ip":"10.12.96.3","router.ip":"169.254.3.7","zone.network.type":"Advanced"},"wait":"0","bypassHostMaintenance":"false"}}]
}
2023-11-02 08:49:47,277 DEBUG [c.c.a.t.Request]
(qtp1444635922-151964:ctx-ca192b9b ctx-03f9a4cb ctx-a6398ec2) (logid:7a0443fc)
Seq 21-4298404369348438753: Executing: { Cmd , MgmtId: 139289717972276, via:
21(XXX), Ver: v1, Flags: 100011,
[{"com.cloud.agent.api.routing.VmDataCommand":{"vmIpAddress":"10.12.96.9","vmName":"test-ci-12-CDSDEL","executeInSequence":"false","accessDetails":{"router.name":"r-6849-VM","router.guest.ip":"10.12.96.3","router.ip":"169.254.3.7","zone.network.type":"Advanced"},"wait":"0","bypassHostMaintenance":"false"}}]
}
2023-11-02 08:49:52,092 DEBUG [c.c.a.t.Request]
(qtp1444635922-151964:ctx-ca192b9b ctx-03f9a4cb ctx-a6398ec2) (logid:7a0443fc)
Seq 21-4298404369348438753: Received: { Ans: , MgmtId: 139289717972276, via:
21(XXX), Ver: v1, Flags: 10, { GroupAnswer } }
2023-11-02 08:49:52,092 DEBUG [c.c.v.UserVmManagerImpl]
(qtp1444635922-151964:ctx-ca192b9b ctx-03f9a4cb ctx-a6398ec2) (logid:7a0443fc)
User data successfully updated for vm id: 6843
2023-11-02 08:49:52,240 DEBUG [c.c.a.ApiServlet]
(qtp1444635922-151964:ctx-ca192b9b ctx-03f9a4cb ctx-a6398ec2) (logid:7a0443fc)
===END=== 10.12.0.248 -- GET
name=test-ci-12-CDSDEL&id=933103d7-9a77-429b-9e51-93b70c06bb5d&command=updateVirtualMachine&apiKey=XXX&signature=XXX
````
And another one doing the Router update?
````
# grep ctx-27e7e986 cs-8177-cleanup.log
2023-11-02 08:49:44,942 DEBUG [c.c.a.m.DirectAgentAttache]
(DirectAgent-5:ctx-27e7e986) (logid:447e0724) Seq 21-4298404369348438752:
Executing request
2023-11-02 08:49:44,942 DEBUG [c.c.a.r.v.VirtualRoutingResource]
(DirectAgent-5:ctx-27e7e986) (logid:7a0443fc) Transforming
com.cloud.agent.api.routing.DhcpEntryCommand to ConfigItems
2023-11-02 08:49:44,943 DEBUG [c.c.a.r.v.f.AbstractConfigItemFacade]
(DirectAgent-5:ctx-27e7e986) (logid:7a0443fc) Transformed filename:
vm_dhcp_entry.json to: vm_dhcp_entry.json.bd5bf82c-70da-48eb-a2d6-f60c6e535872
2023-11-02 08:49:44,946 DEBUG [c.c.h.x.r.CitrixResourceBase]
(DirectAgent-5:ctx-27e7e986) (logid:7a0443fc) Copying VR with ip 169.254.3.7
config file into host 10.12.16.29
2023-11-02 08:49:45,737 DEBUG [c.c.h.x.r.CitrixResourceBase]
(DirectAgent-5:ctx-27e7e986) (logid:7a0443fc) VR Config file
vm_dhcp_entry.json.bd5bf82c-70da-48eb-a2d6-f60c6e535872 got created in VR, IP:
169.254.3.7 with content
2023-11-02 08:49:45,737 DEBUG [c.c.a.r.v.VirtualRoutingResource]
(DirectAgent-5:ctx-27e7e986) (logid:7a0443fc) Processing FileConfigItem,
copying 290 characters to
vm_dhcp_entry.json.bd5bf82c-70da-48eb-a2d6-f60c6e535872 took 794ms
2023-11-02 08:49:45,737 DEBUG [c.c.h.x.r.CitrixResourceBase]
(DirectAgent-5:ctx-27e7e986) (logid:7a0443fc) Executing command in VR:
/opt/cloud/bin/router_proxy.sh update_config.py 169.254.3.7
vm_dhcp_entry.json.bd5bf82c-70da-48eb-a2d6-f60c6e535872
2023-11-02 08:49:47,244 DEBUG [c.c.a.r.v.VirtualRoutingResource]
(DirectAgent-5:ctx-27e7e986) (logid:7a0443fc) Processing ScriptConfigItem,
executing update_config.py
vm_dhcp_entry.json.bd5bf82c-70da-48eb-a2d6-f60c6e535872 took 1507ms
2023-11-02 08:49:47,244 DEBUG [c.c.a.m.DirectAgentAttache]
(DirectAgent-5:ctx-27e7e986) (logid:7a0443fc) Seq 21-4298404369348438752:
Response Received:
2023-11-02 08:49:47,248 DEBUG [c.c.a.t.Request] (DirectAgent-5:ctx-27e7e986)
(logid:7a0443fc) Seq 21-4298404369348438752: Processing: { Ans: , MgmtId:
139289717972276, via: 21(XXX), Ver: v1, Flags: 10,
[{"com.cloud.agent.api.routing.GroupAnswer":{"results":["null - success:
","null - success:
"],"result":"true","wait":"0","bypassHostMaintenance":"false"}}] }
````
I found one of the old VR alert mails, the body was
````
2020-02-01 02:48:08,201 [ALERT] [dnsmasq] The process detected as running
````
So possibly unrelated.
--
This is an automated message from the Apache Git Service.
To respond to the message, please log on to GitHub and use the
URL above to go to the specific comment.
To unsubscribe, e-mail: [email protected]
For queries about this service, please contact Infrastructure at:
[email protected]
