NullPointerException when trying to add host #12798
Description
I'm trying to add another host to the cluster in Apache Cloudstack 4.20.
When I try to create a host on the Management Server
2026-03-12 17:15:22,634 DEBUG [o.a.c.a.StaticRoleBasedAPIAccessChecker] (qtp170949260-341:[ctx-5dc40c0e, ctx-5c4f9f14]) (logid:5002afee) RoleService is enabled. We will use it instead of StaticRoleBasedAPIAccessChecker.
2026-03-12 17:15:22,634 DEBUG [o.a.c.r.ApiRateLimitServiceImpl] (qtp170949260-341:[ctx-5dc40c0e, ctx-5c4f9f14]) (logid:5002afee) API rate limiting is disabled. We will not use ApiRateLimitService.
2026-03-12 17:15:22,635 INFO [c.c.a.ApiServlet] (qtp170949260-341:[ctx-5dc40c0e, ctx-5c4f9f14]) (logid:5002afee) (userId=2 accountId=2 sessionId=node0lktn5cfqqp7l1u5qucjbsta600) 192.168.127.250 -- GET command=readyForShutdown&response=json&sessionkey=iCTtB_t-JpOcEXhb7MqLS7OPZ9A 200 {"readyforshutdownresponse":{"readyforshutdown":{"readyforshutdown":true,"shutdowntriggered":false,"pendingjobscount":0}}}
2026-03-12 17:15:22,635 DEBUG [c.c.a.ApiServlet] (qtp170949260-341:[ctx-5dc40c0e, ctx-5c4f9f14]) (logid:5002afee) ===END=== 192.168.127.250 -- GET command=readyForShutdown&response=json&sessionkey=iCTtB_t-JpOcEXhb7MqLS7OPZ9A
2026-03-12 17:15:23,543 DEBUG [c.c.a.m.ClusteredAgentManagerImpl] (AgentManager-Handler-11:[]) (logid:) SeqA 26-22355: Seq 26-22355: { Cmd , MgmtId: -1, via: 26, Ver: v1, Flags: 11, [{"com.cloud.agent.api.ConsoleProxyLoadReportCommand":{"_proxyVmId":"2863","_loadInfo":"{
"connections": [],
"removedSessions": []
}","wait":"0","bypassHostMaintenance":"false"}}] }
2026-03-12 17:15:23,547 DEBUG [c.c.a.m.ClusteredAgentManagerImpl] (AgentManager-Handler-11:[]) (logid:) SeqA 26-: Sending 22355
2026-03-12 17:15:25,403 DEBUG [o.a.c.h.H.HAManagerBgPollTask] (BackgroundTaskPollManager-2:[ctx-39ffbe07]) (logid:9f493717) HA health check task is running...
2026-03-12 17:15:25,670 DEBUG [c.c.u.s.SSHCmdHelper] (qtp170949260-334:[ctx-8a54628c, ctx-175a9bd1]) (logid:3341f99b) SSH command: cloudstack-setup-agent -m 192.168.127.60 -z 1 -p 1 -c 1 -g 04b35c97-4313-3db4-b8e9-0ff7f861ede6 -a -s --pubNic=cloudbr0 --prvNic=cloudbr0 --guestNic=cloudbr0 --hypervisor=kvm
SSH command output:Starting to configure your system:
Configure Host... [OK]
Configure Apparmor... [OK]
Configure Network...[OK]
Configure Libvirt... [OK]
Configure Firewall...[OK]
Configure Nfs... [OK]
Configure cloudAgent... [OK]
CloudStack Agent setup is done!
2026-03-12 17:15:27,305 DEBUG [o.a.c.c.p.RootCACustomTrustManager] (pool-537-thread-1:[]) (logid:) A client/agent attempting connection from address=192.168.127.90 has presented these certificate(s):
Certificate [1] :
Serial: e08b7c01504878c1
Not Before:Thu Mar 12 05:14:46 UTC 2026
Not After:Fri Mar 12 17:14:46 UTC 2027
Signature Algorithm:SHA256withRSA
Version:3
Subject DN:C=cloudstack, O=cloudstack, OU=cloudstack, CN=acs-host
Issuer DN:CN=ca.cloudstack.apache.org
Alternative Names:[[7, 192.168.127.90], [2, 192.168.127.90]]
Certificate [2] :
Serial: df018901874f103c
Not Before:Mon May 12 23:45:22 UTC 2025
Not After:Thu May 06 11:45:22 UTC 2055
Signature Algorithm:SHA256withRSA
Version:3
Subject DN:CN=ca.cloudstack.apache.org
Issuer DN:CN=ca.cloudstack.apache.org
Alternative Names:null
2026-03-12 17:15:27,306 DEBUG [o.a.c.c.p.RootCACustomTrustManager] (pool-537-thread-1:[]) (logid:) Client/agent connection from ip=192.168.127.90 has been validated and trusted.
026-03-12 17:15:29,155 DEBUG [c.c.a.m.ClusteredAgentAttache] (AgentManager-Handler-5:[]) (logid:) Seq 32-4580442296012505096: Sending now. is current sequence.
2026-03-12 17:15:29,156 INFO [c.c.a.m.ClusteredAgentManagerImpl] (AgentManager-Handler-5:[]) (logid:) SeqA 32-4580442296012505093: Response is not processed: Seq 32-4580442296012505093: { Ans: , MgmtId: 200536954146485, via: 32, Ver: v1, Flags: 110, [{"com.cloud.agent.api.Answer":{"result":"true","wait":"0","bypassHostMaintenance":"false"}}] }
2026-03-12 17:15:29,243 DEBUG [c.c.a.t.Request] (AgentManager-Handler-7:[]) (logid:) Seq 32-4580442296012505096: Processing: { Ans: , MgmtId: 200536954146485, via: 32, Ver: v1, Flags: 110, [{"com.cloud.agent.api.Answer":{"result":"false","details":"java.lang.NullPointerException
at java.base/java.util.concurrent.ConcurrentHashMap.putVal(ConcurrentHashMap.java:1011)
at java.base/java.util.concurrent.ConcurrentHashMap.put(ConcurrentHashMap.java:1006)
at java.base/java.util.Properties.put(Properties.java:1304)
at java.base/java.util.Properties.setProperty(Properties.java:232)
at com.cloud.agent.dao.impl.PropertiesStorage.persist(PropertiesStorage.java:55)
at com.cloud.agent.AgentShell.setPersistentProperty(AgentShell.java:215)
at com.cloud.agent.Agent.setUuid(Agent.java:422)
at com.cloud.agent.Agent.processReadyCommand(Agent.java:956)
at com.cloud.agent.Agent.processRequest(Agent.java:774)
at com.cloud.agent.Agent$AgentRequestHandler.doTask(Agent.java:1193)
at com.cloud.utils.nio.Task.call(Task.java:83)
at com.cloud.utils.nio.Task.call(Task.java:29)
at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1136)
at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635)
at java.base/java.lang.Thread.run(Thread.java:840)
","wait":"0","bypassHostMaintenance":"false"}}] }
2026-03-12 17:15:29,243 DEBUG [c.c.a.m.ClusteredAgentAttache] (AgentManager-Handler-7:[]) (logid:) Seq 32-4580442296012505096: No more commands found
2026-03-12 17:15:29,243 DEBUG [c.c.a.t.Request] (AgentConnectTaskPool-527:[ctx-662b55a3]) (logid:92f84aad) Seq 32-4580442296012505096: Received: { Ans: , MgmtId: 200536954146485, via: 32(acs-host), Ver: v1, Flags: 110, { Answer } }
2026-03-12 17:15:29,243 DEBUG [c.c.a.m.ClusteredAgentManagerImpl] (AgentConnectTaskPool-527:[ctx-662b55a3]) (logid:92f84aad) Details from executing class com.cloud.agent.api.ReadyCommand: java.lang.NullPointerException
at java.base/java.util.concurrent.ConcurrentHashMap.putVal(ConcurrentHashMap.java:1011)
at java.base/java.util.concurrent.ConcurrentHashMap.put(ConcurrentHashMap.java:1006)
at java.base/java.util.Properties.put(Properties.java:1304)
at java.base/java.util.Properties.setProperty(Properties.java:232)
at com.cloud.agent.dao.impl.PropertiesStorage.persist(PropertiesStorage.java:55)
at com.cloud.agent.AgentShell.setPersistentProperty(AgentShell.java:215)
at com.cloud.agent.Agent.setUuid(Agent.java:422)
at com.cloud.agent.Agent.processReadyCommand(Agent.java:956)
at com.cloud.agent.Agent.processRequest(Agent.java:774)
at com.cloud.agent.Agent$AgentRequestHandler.doTask(Agent.java:1193)
at com.cloud.utils.nio.Task.call(Task.java:83)
at com.cloud.utils.nio.Task.call(Task.java:29)
at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1136)
at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635)
at java.base/java.lang.Thread.run(Thread.java:840)
Apache Cloudstack Agent:
2026-03-12 09:15:13,380 INFO [cloud.utils.LogUtils] (main:[]) (logid:) log4j configuration found at /etc/cloudstack/agent/log4j-cloud.xml
2026-03-12 09:15:13,380 INFO [cloud.agent.AgentShell] (main:[]) (logid:) Using default Java settings for IPv6 preference for agent connection
2026-03-12 09:15:13,418 INFO [cloud.agent.Agent] (main:[]) (logid:) Initialising agent [id: , uuid: null, name: null]
2026-03-12 09:15:13,422 ERROR [kvm.resource.LibvirtComputingResource] (main:[]) (logid:) uefi properties file not found due to: Unable to find file uefi.properties.
2026-03-12 09:15:13,441 INFO [kvm.resource.LibvirtConnection] (main:[]) (logid:) No existing libvirtd connection found. Opening a new one
2026-03-12 09:15:13,533 WARN [kvm.resource.LibvirtComputingResource] (main:[]) (logid:) Ignoring libvirt error. org.libvirt.LibvirtException: Network not found: no network with matching name 'default'
at org.libvirt.ErrorHandler.processError(Unknown Source)
at org.libvirt.ErrorHandler.processError(Unknown Source)
at org.libvirt.Connect.networkLookupByName(Unknown Source)
at com.cloud.hypervisor.kvm.resource.LibvirtComputingResource.configure(LibvirtComputingResource.java:1189)
at com.cloud.agent.Agent.<init>(Agent.java:235)
at com.cloud.agent.AgentShell.launchNewAgent(AgentShell.java:457)
at com.cloud.agent.AgentShell.launchAgentFromClassInfo(AgentShell.java:436)
at com.cloud.agent.AgentShell.launchAgent(AgentShell.java:420)
at com.cloud.agent.AgentShell.start(AgentShell.java:521)
at com.cloud.agent.AgentShell.main(AgentShell.java:551)
2026-03-12 09:15:14,247 INFO [kvm.resource.LibvirtComputingResource] (main:[]) (logid:) IO uring driver for Qemu: enabled
2026-03-12 09:15:14,308 INFO [kvm.storage.KVMStoragePoolManager] (main:[]) (logid:) Adding storage adaptor for com.cloud.hypervisor.kvm.storage.LinstorStorageAdaptor
2026-03-12 09:15:14,309 INFO [kvm.storage.KVMStoragePoolManager] (main:[]) (logid:) Adding storage adaptor for com.cloud.hypervisor.kvm.storage.StorPoolStorageAdaptor
2026-03-12 09:15:14,310 INFO [kvm.storage.KVMStoragePoolManager] (main:[]) (logid:) Adding storage adaptor for com.cloud.hypervisor.kvm.storage.ScaleIOStorageAdaptor
2026-03-12 09:15:14,310 INFO [kvm.storage.KVMStoragePoolManager] (main:[]) (logid:) Adding storage adaptor for com.cloud.hypervisor.kvm.storage.IscsiAdmStorageAdaptor
2026-03-12 09:15:14,310 INFO [kvm.storage.KVMStoragePoolManager] (main:[]) (logid:) Adding storage adaptor for com.cloud.hypervisor.kvm.storage.ManagedNfsStorageAdaptor
2026-03-12 09:15:14,314 INFO [kvm.storage.FiberChannelAdapter] (main:[]) (logid:) Loaded FiberChannelAdapter for StorageLayer
2026-03-12 09:15:14,315 INFO [kvm.storage.FiberChannelAdapter] (main:[]) (logid:) Loaded FiberChannelAdapter for StorageLayer on host [acs-host]
2026-03-12 09:15:14,315 INFO [kvm.storage.KVMStoragePoolManager] (main:[]) (logid:) Adding storage adaptor for com.cloud.hypervisor.kvm.storage.FiberChannelAdapter
2026-03-12 09:15:14,315 INFO [kvm.resource.LibvirtComputingResource] (main:[]) (logid:) No libvirt.vif.driver specified. Defaults to BridgeVifDriver.
2026-03-12 09:15:14,405 INFO [cloud.serializer.GsonHelper] (main:[]) (logid:) Default Builder inited.
2026-03-12 09:15:14,405 INFO [kvm.resource.LibvirtComputingResource] (main:[]) (logid:) iscsi session clean up is disabled
2026-03-12 09:15:14,407 INFO [kvm.resource.LibvirtComputingResource] (main:[]) (logid:) Skipping the memory balloon stats period setting, since there are no VMs (active Libvirt domains) on this host.
2026-03-12 09:15:14,407 INFO [kvm.resource.LibvirtComputingResource] (main:[]) (logid:) The [vm.memballoon.stats.period] property is set to '0', this prevents memory statistics from being displayed correctly. Adjust (increase) the value of this parameter to correct this.
2026-03-12 09:15:14,409 INFO [cloud.agent.Agent] (main:[]) (logid:) Agent [id = new, uuid = null, name = null, type = LibvirtComputingResource, zone = 1, pod = 1, workers = 5, port = 8250] with host = 192.168.127.60, local id = 1
2026-03-12 09:15:14,410 INFO [utils.nio.NioClient] (main:[]) (logid:) Connecting to 192.168.127.60:8250
2026-03-12 09:15:14,413 INFO [utils.nio.NioClient] (main:[]) (logid:) Connected to 192.168.127.60:8250
2026-03-12 09:15:14,414 INFO [utils.nio.Link] (main:[]) (logid:) Conf file found: /etc/cloudstack/agent/agent.properties
2026-03-12 09:15:14,632 INFO [utils.nio.NioClient] (main:[]) (logid:) SSL: Handshake done
2026-03-12 09:15:14,681 INFO [utils.linux.KVMHostInfo] (Agent-Handler-1:[]) (logid:) Fetching CPU speed from command "lscpu".
2026-03-12 09:15:14,734 ERROR [utils.linux.KVMHostInfo] (Agent-Handler-1:[]) (logid:) Unable to retrieve the CPU speed from lscpu. java.lang.NullPointerException: Cannot invoke "String.trim()" because "in" is null
at java.base/jdk.internal.math.FloatingDecimal.readJavaFormatString(FloatingDecimal.java:1838)
at java.base/jdk.internal.math.FloatingDecimal.parseFloat(FloatingDecimal.java:122)
at java.base/java.lang.Float.parseFloat(Float.java:476)
at org.apache.cloudstack.utils.linux.KVMHostInfo.getCpuSpeedFromCommandLscpu(KVMHostInfo.java:141)
at org.apache.cloudstack.utils.linux.KVMHostInfo.getCpuSpeed(KVMHostInfo.java:115)
at org.apache.cloudstack.utils.linux.KVMHostInfo.getHostInfoFromLibvirt(KVMHostInfo.java:207)
at org.apache.cloudstack.utils.linux.KVMHostInfo.<init>(KVMHostInfo.java:68)
at com.cloud.hypervisor.kvm.resource.LibvirtComputingResource.initialize(LibvirtComputingResource.java:3766)
at com.cloud.agent.Agent.sendStartup(Agent.java:541)
at com.cloud.agent.Agent$ServerHandler.doTask(Agent.java:1212)
at com.cloud.utils.nio.Task.call(Task.java:83)
at com.cloud.utils.nio.Task.call(Task.java:29)
at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1136)
at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635)
at java.base/java.lang.Thread.run(Thread.java:840)
2026-03-12 09:15:14,788 ERROR [utils.linux.KVMHostInfo] (Agent-Handler-1:[]) (logid:) Unable to retrieve the CPU speed from lscpu. java.lang.NullPointerException: Cannot invoke "String.trim()" because "in" is null
at java.base/jdk.internal.math.FloatingDecimal.readJavaFormatString(FloatingDecimal.java:1838)
at java.base/jdk.internal.math.FloatingDecimal.parseFloat(FloatingDecimal.java:122)
at java.base/java.lang.Float.parseFloat(Float.java:476)
at org.apache.cloudstack.utils.linux.KVMHostInfo.getCpuSpeedFromCommandLscpu(KVMHostInfo.java:150)
at org.apache.cloudstack.utils.linux.KVMHostInfo.getCpuSpeed(KVMHostInfo.java:115)
at org.apache.cloudstack.utils.linux.KVMHostInfo.getHostInfoFromLibvirt(KVMHostInfo.java:207)
at org.apache.cloudstack.utils.linux.KVMHostInfo.<init>(KVMHostInfo.java:68)
at com.cloud.hypervisor.kvm.resource.LibvirtComputingResource.initialize(LibvirtComputingResource.java:3766)
at com.cloud.agent.Agent.sendStartup(Agent.java:541)
at com.cloud.agent.Agent$ServerHandler.doTask(Agent.java:1212)
at com.cloud.utils.nio.Task.call(Task.java:83)
at com.cloud.utils.nio.Task.call(Task.java:29)
at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1136)
at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635)
at java.base/java.lang.Thread.run(Thread.java:840)
2026-03-12 09:15:14,789 INFO [utils.linux.KVMHostInfo] (Agent-Handler-1:[]) (logid:) Fetching CPU speed from file [/sys/devices/system/cpu/cpu0/cpufreq/base_frequency].
2026-03-12 09:15:14,789 ERROR [utils.linux.KVMHostInfo] (Agent-Handler-1:[]) (logid:) Unable to retrieve the CPU speed from file [/sys/devices/system/cpu/cpu0/cpufreq/base_frequency] java.io.FileNotFoundException: /sys/devices/system/cpu/cpu0/cpufreq/base_frequency (No such file or directory)
at java.base/java.io.FileInputStream.open0(Native Method)
at java.base/java.io.FileInputStream.open(FileInputStream.java:216)
at java.base/java.io.FileInputStream.<init>(FileInputStream.java:157)
at java.base/java.io.FileInputStream.<init>(FileInputStream.java:111)
at java.base/java.io.FileReader.<init>(FileReader.java:60)
at org.apache.cloudstack.utils.linux.KVMHostInfo.getCpuSpeedFromFile(KVMHostInfo.java:162)
at org.apache.cloudstack.utils.linux.KVMHostInfo.getCpuSpeed(KVMHostInfo.java:120)
at org.apache.cloudstack.utils.linux.KVMHostInfo.getHostInfoFromLibvirt(KVMHostInfo.java:207)
at org.apache.cloudstack.utils.linux.KVMHostInfo.<init>(KVMHostInfo.java:68)
at com.cloud.hypervisor.kvm.resource.LibvirtComputingResource.initialize(LibvirtComputingResource.java:3766)
at com.cloud.agent.Agent.sendStartup(Agent.java:541)
at com.cloud.agent.Agent$ServerHandler.doTask(Agent.java:1212)
at com.cloud.utils.nio.Task.call(Task.java:83)
at com.cloud.utils.nio.Task.call(Task.java:29)
at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1136)
at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635)
at java.base/java.lang.Thread.run(Thread.java:840)
2026-03-12 09:15:14,789 INFO [utils.linux.KVMHostInfo] (Agent-Handler-1:[]) (logid:) Fetching CPU speed from file [/sys/devices/system/cpu/cpu0/cpufreq/scaling_max_freq].
2026-03-12 09:15:14,789 ERROR [utils.linux.KVMHostInfo] (Agent-Handler-1:[]) (logid:) Unable to retrieve the CPU speed from file [/sys/devices/system/cpu/cpu0/cpufreq/scaling_max_freq] java.io.FileNotFoundException: /sys/devices/system/cpu/cpu0/cpufreq/scaling_max_freq (No such file or directory)
at java.base/java.io.FileInputStream.open0(Native Method)
at java.base/java.io.FileInputStream.open(FileInputStream.java:216)
at java.base/java.io.FileInputStream.<init>(FileInputStream.java:157)
at java.base/java.io.FileInputStream.<init>(FileInputStream.java:111)
at java.base/java.io.FileReader.<init>(FileReader.java:60)
at org.apache.cloudstack.utils.linux.KVMHostInfo.getCpuSpeedFromFile(KVMHostInfo.java:162)
at org.apache.cloudstack.utils.linux.KVMHostInfo.getCpuSpeed(KVMHostInfo.java:120)
at org.apache.cloudstack.utils.linux.KVMHostInfo.getHostInfoFromLibvirt(KVMHostInfo.java:207)
at org.apache.cloudstack.utils.linux.KVMHostInfo.<init>(KVMHostInfo.java:68)
at com.cloud.hypervisor.kvm.resource.LibvirtComputingResource.initialize(LibvirtComputingResource.java:3766)
at com.cloud.agent.Agent.sendStartup(Agent.java:541)
at com.cloud.agent.Agent$ServerHandler.doTask(Agent.java:1212)
at com.cloud.utils.nio.Task.call(Task.java:83)
at com.cloud.utils.nio.Task.call(Task.java:29)
at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1136)
at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635)
at java.base/java.lang.Thread.run(Thread.java:840)
2026-03-12 09:15:14,789 INFO [utils.linux.KVMHostInfo] (Agent-Handler-1:[]) (logid:) Fetching CPU speed from "host capabilities"
2026-03-12 09:15:14,795 INFO [utils.linux.KVMHostInfo] (Agent-Handler-1:[]) (logid:) Retrieved value [2399999000] from "host capabilities". This corresponds to a CPU speed of [2399] MHz.
2026-03-12 09:15:14,796 INFO [utils.linux.KVMHostInfo] (Agent-Handler-1:[]) (logid:) Fetching host CPU arch
2026-03-12 09:15:14,804 INFO [kvm.resource.LibvirtComputingResource] (Agent-Handler-1:[]) (logid:) Host uses control group [cgroup2fs].
2026-03-12 09:15:14,804 INFO [kvm.resource.LibvirtComputingResource] (Agent-Handler-1:[]) (logid:) Calculating the max shares of the host.
2026-03-12 09:15:14,804 INFO [kvm.resource.LibvirtComputingResource] (Agent-Handler-1:[]) (logid:) The max shares of the host is [460608].
2026-03-12 09:15:14,878 WARN [utils.script.Script] (Agent-Handler-1:[]) (logid:) Execution of process [104952] for command [/bin/bash -c dpkg -l nbdkit ] failed.
2026-03-12 09:15:14,878 WARN [utils.script.Script] (Agent-Handler-1:[]) (logid:) Process [104952] for command [/bin/bash -c dpkg -l nbdkit ] encountered the error: [1].
2026-03-12 09:15:14,881 WARN [utils.script.Script] (Agent-Handler-1:[]) (logid:) Execution of process [104953] for command [/bin/bash -c ovftool --version ] failed.
2026-03-12 09:15:14,881 WARN [utils.script.Script] (Agent-Handler-1:[]) (logid:) Process [104953] for command [/bin/bash -c ovftool --version ] encountered the error: [127].
2026-03-12 09:15:14,881 INFO [kvm.storage.LibvirtStorageAdaptor] (Agent-Handler-1:[]) (logid:) Attempting to create storage pool 543daf9e-d4d8-4b01-bce2-34ff5333e3b4 (Filesystem) in libvirt
2026-03-12 09:15:14,882 WARN [kvm.storage.LibvirtStorageAdaptor] (Agent-Handler-1:[]) (logid:) Storage pool 543daf9e-d4d8-4b01-bce2-34ff5333e3b4 was not found running in libvirt. Need to create it.
2026-03-12 09:15:14,882 INFO [kvm.storage.LibvirtStorageAdaptor] (Agent-Handler-1:[]) (logid:) Didn't find an existing storage pool 543daf9e-d4d8-4b01-bce2-34ff5333e3b4 by UUID, checking for pools with duplicate paths
2026-03-12 09:15:14,889 INFO [kvm.storage.LibvirtStorageAdaptor] (Agent-Handler-1:[]) (logid:) Trying to fetch storage pool 543daf9e-d4d8-4b01-bce2-34ff5333e3b4 from libvirt
2026-03-12 09:15:15,933 INFO [cloud.agent.Agent] (Agent-Handler-2:[]) (logid:) Process agent startup answer, agent [id: 0, uuid: null, name: null] connected to the server
2026-03-12 09:15:15,939 ERROR [cloud.agent.Agent] (Agent-Handler-2:[]) (logid:) Error parsing task java.lang.NullPointerException
at java.base/java.util.concurrent.ConcurrentHashMap.putVal(ConcurrentHashMap.java:1011)
at java.base/java.util.concurrent.ConcurrentHashMap.put(ConcurrentHashMap.java:1006)
at java.base/java.util.Properties.put(Properties.java:1304)
at java.base/java.util.Properties.setProperty(Properties.java:232)
at com.cloud.agent.dao.impl.PropertiesStorage.persist(PropertiesStorage.java:55)
at com.cloud.agent.AgentShell.setPersistentProperty(AgentShell.java:215)
at com.cloud.agent.Agent.setUuid(Agent.java:422)
at com.cloud.agent.Agent.processStartupAnswer(Agent.java:687)
at com.cloud.agent.Agent.processResponse(Agent.java:931)
at com.cloud.agent.Agent$ServerHandler.doTask(Agent.java:1219)
at com.cloud.utils.nio.Task.call(Task.java:83)
at com.cloud.utils.nio.Task.call(Task.java:29)
at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1136)
at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635)
at java.base/java.lang.Thread.run(Thread.java:840)
2026-03-12 09:15:16,011 INFO [kvm.storage.LibvirtStorageAdaptor] (AgentRequest-Handler-3:[]) (logid:) Attempting to create storage pool f6257a01-2b05-3e14-b3f8-a6cbc3613986 (RBD) in libvirt
2026-03-12 09:15:16,012 WARN [kvm.storage.LibvirtStorageAdaptor] (AgentRequest-Handler-3:[]) (logid:) Storage pool f6257a01-2b05-3e14-b3f8-a6cbc3613986 was not found running in libvirt. Need to create it.
2026-03-12 09:15:16,012 INFO [kvm.storage.LibvirtStorageAdaptor] (AgentRequest-Handler-3:[]) (logid:) Didn't find an existing storage pool f6257a01-2b05-3e14-b3f8-a6cbc3613986 by UUID, checking for pools with duplicate paths
2026-03-12 09:15:16,161 INFO [kvm.storage.LibvirtStorageAdaptor] (AgentRequest-Handler-3:[]) (logid:) Trying to fetch storage pool f6257a01-2b05-3e14-b3f8-a6cbc3613986 from libvirt
2026-03-12 09:15:16,229 INFO [kvm.storage.LibvirtStorageAdaptor] (AgentRequest-Handler-4:[]) (logid:) Attempting to create storage pool 87a89064-a632-3056-ba4e-64e032252b19 (NetworkFilesystem) in libvirt
2026-03-12 09:15:16,231 INFO [kvm.storage.LibvirtStorageAdaptor] (AgentRequest-Handler-4:[]) (logid:) Found existing defined storage pool 87a89064-a632-3056-ba4e-64e032252b19, using it.
2026-03-12 09:15:16,234 INFO [kvm.storage.LibvirtStorageAdaptor] (AgentRequest-Handler-4:[]) (logid:) Trying to fetch storage pool 87a89064-a632-3056-ba4e-64e032252b19 from libvirt
2026-03-12 09:15:16,484 INFO [cloud.agent.Agent] (AgentRequest-Handler-5:[]) (logid:) Processing agent ready command, agent id = 32, uuid = null, name = null
2026-03-12 09:15:16,484 WARN [cloud.agent.Agent] (AgentRequest-Handler-5:[]) (logid:) Caught: java.lang.NullPointerException
at java.base/java.util.concurrent.ConcurrentHashMap.putVal(ConcurrentHashMap.java:1011)
at java.base/java.util.concurrent.ConcurrentHashMap.put(ConcurrentHashMap.java:1006)
at java.base/java.util.Properties.put(Properties.java:1304)
at java.base/java.util.Properties.setProperty(Properties.java:232)
at com.cloud.agent.dao.impl.PropertiesStorage.persist(PropertiesStorage.java:55)
at com.cloud.agent.AgentShell.setPersistentProperty(AgentShell.java:215)
at com.cloud.agent.Agent.setUuid(Agent.java:422)
at com.cloud.agent.Agent.processReadyCommand(Agent.java:956)
at com.cloud.agent.Agent.processRequest(Agent.java:774)
at com.cloud.agent.Agent$AgentRequestHandler.doTask(Agent.java:1193)
at com.cloud.utils.nio.Task.call(Task.java:83)
at com.cloud.utils.nio.Task.call(Task.java:29)
at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1136)
at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635)
at java.base/java.lang.Thread.run(Thread.java:840)
2026-03-12 09:15:16,529 INFO [cloud.agent.Agent] (Agent-Handler-1:[]) (logid:) Lost connection to host: 192.168.127.60. Attempting reconnection while we still have 0 commands in progress.
2026-03-12 09:15:16,530 INFO [utils.nio.NioClient] (Agent-Handler-1:[]) (logid:) NioClient connection closed
2026-03-12 09:15:21,530 INFO [cloud.agent.Agent] (Agent-Handler-1:[]) (logid:) Reconnecting to host: 192.168.127.60
2026-03-12 09:15:21,531 INFO [utils.nio.NioClient] (Agent-Handler-1:[]) (logid:) Connecting to 192.168.127.60:8250
2026-03-12 09:15:21,531 INFO [utils.nio.NioClient] (Agent-Handler-1:[]) (logid:) Connected to 192.168.127.60:8250
2026-03-12 09:15:21,531 INFO [utils.nio.Link] (Agent-Handler-1:[]) (logid:) Conf file found: /etc/cloudstack/agent/agent.properties
2026-03-12 09:15:21,607 INFO [utils.nio.NioClient] (Agent-Handler-1:[]) (logid:) SSL: Handshake done
2026-03-12 09:15:21,650 INFO [utils.linux.KVMHostInfo] (Agent-Handler-1:[]) (logid:) Fetching CPU speed from command "lscpu".
2026-03-12 09:15:21,703 ERROR [utils.linux.KVMHostInfo] (Agent-Handler-1:[]) (logid:) Unable to retrieve the CPU speed from lscpu. java.lang.NullPointerException: Cannot invoke "String.trim()" because "in" is null
at java.base/jdk.internal.math.FloatingDecimal.readJavaFormatString(FloatingDecimal.java:1838)
at java.base/jdk.internal.math.FloatingDecimal.parseFloat(FloatingDecimal.java:122)
at java.base/java.lang.Float.parseFloat(Float.java:476)
at org.apache.cloudstack.utils.linux.KVMHostInfo.getCpuSpeedFromCommandLscpu(KVMHostInfo.java:141)
at org.apache.cloudstack.utils.linux.KVMHostInfo.getCpuSpeed(KVMHostInfo.java:115)
at org.apache.cloudstack.utils.linux.KVMHostInfo.getHostInfoFromLibvirt(KVMHostInfo.java:207)
at org.apache.cloudstack.utils.linux.KVMHostInfo.<init>(KVMHostInfo.java:68)
at com.cloud.hypervisor.kvm.resource.LibvirtComputingResource.initialize(LibvirtComputingResource.java:3766)
at com.cloud.agent.Agent.sendStartup(Agent.java:541)
at com.cloud.agent.Agent$ServerHandler.doTask(Agent.java:1212)
at com.cloud.utils.nio.Task.call(Task.java:83)
at com.cloud.utils.nio.Task.call(Task.java:29)
at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1136)
at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635)
at java.base/java.lang.Thread.run(Thread.java:840)
2026-03-12 09:15:21,758 ERROR [utils.linux.KVMHostInfo] (Agent-Handler-1:[]) (logid:) Unable to retrieve the CPU speed from lscpu. java.lang.NullPointerException: Cannot invoke "String.trim()" because "in" is null
at java.base/jdk.internal.math.FloatingDecimal.readJavaFormatString(FloatingDecimal.java:1838)
at java.base/jdk.internal.math.FloatingDecimal.parseFloat(FloatingDecimal.java:122)
at java.base/java.lang.Float.parseFloat(Float.java:476)
at org.apache.cloudstack.utils.linux.KVMHostInfo.getCpuSpeedFromCommandLscpu(KVMHostInfo.java:150)
at org.apache.cloudstack.utils.linux.KVMHostInfo.getCpuSpeed(KVMHostInfo.java:115)
at org.apache.cloudstack.utils.linux.KVMHostInfo.getHostInfoFromLibvirt(KVMHostInfo.java:207)
at org.apache.cloudstack.utils.linux.KVMHostInfo.<init>(KVMHostInfo.java:68)
at com.cloud.hypervisor.kvm.resource.LibvirtComputingResource.initialize(LibvirtComputingResource.java:3766)
at com.cloud.agent.Agent.sendStartup(Agent.java:541)
at com.cloud.agent.Agent$ServerHandler.doTask(Agent.java:1212)
at com.cloud.utils.nio.Task.call(Task.java:83)
at com.cloud.utils.nio.Task.call(Task.java:29)
at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1136)
at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635)
at java.base/java.lang.Thread.run(Thread.java:840)
2026-03-12 09:15:21,759 INFO [utils.linux.KVMHostInfo] (Agent-Handler-1:[]) (logid:) Fetching CPU speed from file [/sys/devices/system/cpu/cpu0/cpufreq/base_frequency].
2026-03-12 09:15:21,759 ERROR [utils.linux.KVMHostInfo] (Agent-Handler-1:[]) (logid:) Unable to retrieve the CPU speed from file [/sys/devices/system/cpu/cpu0/cpufreq/base_frequency] java.io.FileNotFoundException: /sys/devices/system/cpu/cpu0/cpufreq/base_frequency (No such file or directory)
at java.base/java.io.FileInputStream.open0(Native Method)
at java.base/java.io.FileInputStream.open(FileInputStream.java:216)
at java.base/java.io.FileInputStream.<init>(FileInputStream.java:157)
at java.base/java.io.FileInputStream.<init>(FileInputStream.java:111)
at java.base/java.io.FileReader.<init>(FileReader.java:60)
at org.apache.cloudstack.utils.linux.KVMHostInfo.getCpuSpeedFromFile(KVMHostInfo.java:162)
at org.apache.cloudstack.utils.linux.KVMHostInfo.getCpuSpeed(KVMHostInfo.java:120)
at org.apache.cloudstack.utils.linux.KVMHostInfo.getHostInfoFromLibvirt(KVMHostInfo.java:207)
at org.apache.cloudstack.utils.linux.KVMHostInfo.<init>(KVMHostInfo.java:68)
at com.cloud.hypervisor.kvm.resource.LibvirtComputingResource.initialize(LibvirtComputingResource.java:3766)
at com.cloud.agent.Agent.sendStartup(Agent.java:541)
at com.cloud.agent.Agent$ServerHandler.doTask(Agent.java:1212)
at com.cloud.utils.nio.Task.call(Task.java:83)
at com.cloud.utils.nio.Task.call(Task.java:29)
at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1136)
at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635)
at java.base/java.lang.Thread.run(Thread.java:840)
2026-03-12 09:15:21,759 INFO [utils.linux.KVMHostInfo] (Agent-Handler-1:[]) (logid:) Fetching CPU speed from file [/sys/devices/system/cpu/cpu0/cpufreq/scaling_max_freq].
2026-03-12 09:15:21,759 ERROR [utils.linux.KVMHostInfo] (Agent-Handler-1:[]) (logid:) Unable to retrieve the CPU speed from file [/sys/devices/system/cpu/cpu0/cpufreq/scaling_max_freq] java.io.FileNotFoundException: /sys/devices/system/cpu/cpu0/cpufreq/scaling_max_freq (No such file or directory)
at java.base/java.io.FileInputStream.open0(Native Method)
at java.base/java.io.FileInputStream.open(FileInputStream.java:216)
at java.base/java.io.FileInputStream.<init>(FileInputStream.java:157)
at java.base/java.io.FileInputStream.<init>(FileInputStream.java:111)
at java.base/java.io.FileReader.<init>(FileReader.java:60)
at org.apache.cloudstack.utils.linux.KVMHostInfo.getCpuSpeedFromFile(KVMHostInfo.java:162)
at org.apache.cloudstack.utils.linux.KVMHostInfo.getCpuSpeed(KVMHostInfo.java:120)
at org.apache.cloudstack.utils.linux.KVMHostInfo.getHostInfoFromLibvirt(KVMHostInfo.java:207)
at org.apache.cloudstack.utils.linux.KVMHostInfo.<init>(KVMHostInfo.java:68)
at com.cloud.hypervisor.kvm.resource.LibvirtComputingResource.initialize(LibvirtComputingResource.java:3766)
at com.cloud.agent.Agent.sendStartup(Agent.java:541)
at com.cloud.agent.Agent$ServerHandler.doTask(Agent.java:1212)
at com.cloud.utils.nio.Task.call(Task.java:83)
at com.cloud.utils.nio.Task.call(Task.java:29)
at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1136)
at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635)
at java.base/java.lang.Thread.run(Thread.java:840)
2026-03-12 09:15:21,759 INFO [utils.linux.KVMHostInfo] (Agent-Handler-1:[]) (logid:) Fetching CPU speed from "host capabilities"
2026-03-12 09:15:21,762 INFO [utils.linux.KVMHostInfo] (Agent-Handler-1:[]) (logid:) Retrieved value [2399999000] from "host capabilities". This corresponds to a CPU speed of [2399] MHz.
2026-03-12 09:15:21,762 INFO [utils.linux.KVMHostInfo] (Agent-Handler-1:[]) (logid:) Fetching host CPU arch
2026-03-12 09:15:21,770 INFO [kvm.resource.LibvirtComputingResource] (Agent-Handler-1:[]) (logid:) Host uses control group [cgroup2fs].
2026-03-12 09:15:21,770 INFO [kvm.resource.LibvirtComputingResource] (Agent-Handler-1:[]) (logid:) Calculating the max shares of the host.
2026-03-12 09:15:21,770 INFO [kvm.resource.LibvirtComputingResource] (Agent-Handler-1:[]) (logid:) The max shares of the host is [460608].
2026-03-12 09:15:21,841 WARN [utils.script.Script] (Agent-Handler-1:[]) (logid:) Execution of process [105068] for command [/bin/bash -c dpkg -l nbdkit ] failed.
2026-03-12 09:15:21,842 WARN [utils.script.Script] (Agent-Handler-1:[]) (logid:) Process [105068] for command [/bin/bash -c dpkg -l nbdkit ] encountered the error: [1].
2026-03-12 09:15:21,844 WARN [utils.script.Script] (Agent-Handler-1:[]) (logid:) Execution of process [105069] for command [/bin/bash -c ovftool --version ] failed.
2026-03-12 09:15:21,844 WARN [utils.script.Script] (Agent-Handler-1:[]) (logid:) Process [105069] for command [/bin/bash -c ovftool --version ] encountered the error: [127].
2026-03-12 09:15:21,844 INFO [kvm.storage.LibvirtStorageAdaptor] (Agent-Handler-1:[]) (logid:) Attempting to create storage pool 543daf9e-d4d8-4b01-bce2-34ff5333e3b4 (Filesystem) in libvirt
2026-03-12 09:15:21,845 INFO [kvm.storage.LibvirtStorageAdaptor] (Agent-Handler-1:[]) (logid:) Found existing defined storage pool 543daf9e-d4d8-4b01-bce2-34ff5333e3b4, using it.
2026-03-12 09:15:21,845 INFO [kvm.storage.LibvirtStorageAdaptor] (Agent-Handler-1:[]) (logid:) Trying to fetch storage pool 543daf9e-d4d8-4b01-bce2-34ff5333e3b4 from libvirt
2026-03-12 09:15:22,869 INFO [cloud.agent.Agent] (Agent-Handler-2:[]) (logid:) Process agent startup answer, agent [id: 0, uuid: null, name: null] connected to the server
2026-03-12 09:15:22,869 ERROR [cloud.agent.Agent] (Agent-Handler-2:[]) (logid:) Error parsing task java.lang.NullPointerException
at java.base/java.util.concurrent.ConcurrentHashMap.putVal(ConcurrentHashMap.java:1011)
at java.base/java.util.concurrent.ConcurrentHashMap.put(ConcurrentHashMap.java:1006)
at java.base/java.util.Properties.put(Properties.java:1304)
at java.base/java.util.Properties.setProperty(Properties.java:232)
at com.cloud.agent.dao.impl.PropertiesStorage.persist(PropertiesStorage.java:55)
at com.cloud.agent.AgentShell.setPersistentProperty(AgentShell.java:215)
at com.cloud.agent.Agent.setUuid(Agent.java:422)
at com.cloud.agent.Agent.processStartupAnswer(Agent.java:687)
at com.cloud.agent.Agent.processResponse(Agent.java:931)
at com.cloud.agent.Agent$ServerHandler.doTask(Agent.java:1219)
at com.cloud.utils.nio.Task.call(Task.java:83)
at com.cloud.utils.nio.Task.call(Task.java:29)
at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1136)
at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635)
at java.base/java.lang.Thread.run(Thread.java:840)