GitHub user Hossein4185 added a comment to the discussion: Secondary Storage Vm
(SSVM) SSL Handshake failed error
@weizhouapache
2025-09-23T23:21:32,605 INFO [cloud.agent.AgentShell] (main:[]) Agent started
2025-09-23T23:21:32,612 INFO [cloud.agent.AgentShell] (main:[]) Implementation
Version is 4.21.0.0
2025-09-23T23:21:32,613 INFO [cloud.agent.AgentShell] (main:[])
agent.properties found at /usr/local/cloud/systemvm/conf/agent.properties
2025-09-23T23:21:32,634 DEBUG [agent.properties.AgentPropertiesFileHandler]
(main:[]) Property [guid] was altered. Now using the value [s-5333-VM].
2025-09-23T23:21:32,690 DEBUG [agent.properties.AgentPropertiesFileHandler]
(main:[]) Property [consoleproxy.httpListenPort] has empty or null value. Using
default value [443].
2025-09-23T23:21:32,691 DEBUG [agent.properties.AgentPropertiesFileHandler]
(main:[]) Property [ping.retries] has empty or null value. Using default value
[5].
2025-09-23T23:21:32,692 DEBUG [agent.properties.AgentPropertiesFileHandler]
(main:[]) Property [host.lb.check.interval] has empty or null value. Using
default value [null].
2025-09-23T23:21:32,693 DEBUG [cloud.agent.AgentShell] (main:[]) Found
property: private.network.device
2025-09-23T23:21:32,696 DEBUG [cloud.agent.AgentShell] (main:[]) Found
property: storagegateway
2025-09-23T23:21:32,696 DEBUG [cloud.agent.AgentShell] (main:[]) Found
property: eth2mask
2025-09-23T23:21:32,698 DEBUG [cloud.agent.AgentShell] (main:[]) Found
property: sslcopy
2025-09-23T23:21:32,698 DEBUG [cloud.agent.AgentShell] (main:[]) Found
property: eth0ip
2025-09-23T23:21:32,701 DEBUG [cloud.agent.AgentShell] (main:[]) Found
property: host
2025-09-23T23:21:32,704 DEBUG [cloud.agent.AgentShell] (main:[]) Found
property: nfsVersion
2025-09-23T23:21:32,705 DEBUG [cloud.agent.AgentShell] (main:[]) Found
property: template
2025-09-23T23:21:32,707 DEBUG [cloud.agent.AgentShell] (main:[]) Found
property: useHttpsToUpload
2025-09-23T23:21:32,708 DEBUG [cloud.agent.AgentShell] (main:[]) Found
property: workers
2025-09-23T23:21:32,708 DEBUG [cloud.agent.AgentShell] (main:[]) Found
property: storagenetmask
2025-09-23T23:21:32,709 DEBUG [cloud.agent.AgentShell] (main:[]) Found
property: dns2
2025-09-23T23:21:32,710 DEBUG [cloud.agent.AgentShell] (main:[]) Found
property: zone
2025-09-23T23:21:32,717 DEBUG [cloud.agent.AgentShell] (main:[]) Found
property: dns1
2025-09-23T23:21:32,717 DEBUG [cloud.agent.AgentShell] (main:[]) Found
property: keystore_password
2025-09-23T23:21:32,718 DEBUG [cloud.agent.AgentShell] (main:[]) Found
property: name
2025-09-23T23:21:32,720 DEBUG [cloud.agent.AgentShell] (main:[]) Found
property: mtu
2025-09-23T23:21:32,720 DEBUG [cloud.agent.AgentShell] (main:[]) Found
property: gateway
2025-09-23T23:21:32,721 DEBUG [cloud.agent.AgentShell] (main:[]) Found
property: eth1mask
2025-09-23T23:21:32,722 DEBUG [cloud.agent.AgentShell] (main:[]) Found
property: secondaryStorageServerAddress
2025-09-23T23:21:32,723 DEBUG [cloud.agent.AgentShell] (main:[]) Found
property: keystore.passphrase
2025-09-23T23:21:32,724 DEBUG [cloud.agent.AgentShell] (main:[]) Found
property: guid
2025-09-23T23:21:32,725 DEBUG [cloud.agent.AgentShell] (main:[]) Found
property: eth0mask
2025-09-23T23:21:32,726 DEBUG [cloud.agent.AgentShell] (main:[]) Found
property: resource
2025-09-23T23:21:32,726 DEBUG [cloud.agent.AgentShell] (main:[]) Found
property: role
2025-09-23T23:21:32,727 DEBUG [cloud.agent.AgentShell] (main:[]) Found
property: internaldns2
2025-09-23T23:21:32,728 DEBUG [cloud.agent.AgentShell] (main:[]) Found
property: internaldns1
2025-09-23T23:21:32,728 DEBUG [cloud.agent.AgentShell] (main:[]) Found
property: port
2025-09-23T23:21:32,729 DEBUG [cloud.agent.AgentShell] (main:[]) Found
property: eth3ip
2025-09-23T23:21:32,730 DEBUG [cloud.agent.AgentShell] (main:[]) Found
property: pod
2025-09-23T23:21:32,730 DEBUG [cloud.agent.AgentShell] (main:[]) Found
property: storageip
2025-09-23T23:21:32,732 DEBUG [cloud.agent.AgentShell] (main:[]) Found
property: eth3mask
2025-09-23T23:21:32,732 DEBUG [cloud.agent.AgentShell] (main:[]) Found
property: instance
2025-09-23T23:21:32,733 DEBUG [cloud.agent.AgentShell] (main:[]) Found
property: eth2ip
2025-09-23T23:21:32,734 DEBUG [cloud.agent.AgentShell] (main:[]) Found
property: localgw
2025-09-23T23:21:32,738 DEBUG [cloud.agent.AgentShell] (main:[]) Found
property: mgmtcidr
2025-09-23T23:21:32,739 DEBUG [cloud.agent.AgentShell] (main:[]) Found
property: eth1ip
2025-09-23T23:21:32,739 DEBUG [cloud.agent.AgentShell] (main:[]) Found
property: authorized_key
2025-09-23T23:21:32,740 DEBUG [cloud.agent.AgentShell] (main:[]) Found
property: public.network.device
2025-09-23T23:21:32,740 DEBUG [cloud.agent.AgentShell] (main:[]) Found
property: type
2025-09-23T23:21:32,740 INFO [cloud.agent.AgentShell] (main:[]) Defaulting to
using properties file for storage
2025-09-23T23:21:32,742 INFO [cloud.agent.AgentShell] (main:[]) Defaulting to
the constant time backoff algorithm
2025-09-23T23:21:32,787 INFO [cloud.utils.LogUtils] (main:[]) log4j
configuration found at /usr/local/cloud/systemvm/conf/log4j-cloud.xml
2025-09-23T23:21:32,792 INFO [cloud.agent.AgentShell] (main:[]) Using default
Java settings for IPv6 preference for agent connection
2025-09-23T23:21:32,793 DEBUG [cloud.agent.AgentShell] (main:[]) Checking to
see if agent.SecStorage.pid exists.
2025-09-23T23:21:32,796 DEBUG [cloud.utils.ProcessUtil] (main:[])
environment.properties could not be opened
2025-09-23T23:21:32,806 DEBUG [cloud.utils.ProcessUtil] (main:[]) Executing
command [bash -c echo $PPID ].
2025-09-23T23:21:32,836 DEBUG [cloud.utils.ProcessUtil] (main:[]) Successfully
executed process [7697] for command [bash -c echo $PPID ].
2025-09-23T23:21:32,848 DEBUG [agent.properties.AgentPropertiesFileHandler]
(main:[]) Property [resource] was altered. Now using the value
[com.cloud.storage.resource.PremiumSecondaryStorageResource].
2025-09-23T23:21:32,900 INFO [cloud.agent.Agent] (main:[]) Initialising agent
[id: , uuid: null, name: null]
2025-09-23T23:21:32,901 DEBUG [storage.resource.NfsSecondaryStorageResource]
(main:[]) conf secondary.storage.vm is true, act as if executing in SSVM
2025-09-23T23:21:32,909 DEBUG [utils.net.NetUtils] (main:[]) Retrieving network
interface [eth1].
2025-09-23T23:21:32,909 DEBUG [utils.net.NetUtils] (main:[]) Retrieving network
interface [eth2].
2025-09-23T23:21:32,910 DEBUG [utils.net.NetUtils] (main:[]) Retrieving network
interface [null].
2025-09-23T23:21:32,911 DEBUG [utils.net.NetUtils] (main:[]) Retrieving network
interface [null].
2025-09-23T23:21:32,912 DEBUG [utils.net.NetUtils] (main:[]) Retrieving network
params of NIC [name:eth1 (eth1)].
2025-09-23T23:21:32,917 DEBUG [utils.net.NetUtils] (main:[]) Retrieving params
of address [/172.17.26.82/24 [/172.17.26.255]] of NIC [name:eth1 (eth1)].
2025-09-23T23:21:32,924 DEBUG [utils.script.Script] (main:[]) Looking for
config_ssl.sh in the classpath
2025-09-23T23:21:32,925 DEBUG [utils.script.Script] (main:[]) System resource:
file:/usr/local/cloud/systemvm/config_ssl.sh
2025-09-23T23:21:32,926 DEBUG [utils.script.Script] (main:[]) Absolute path =
/usr/local/cloud/systemvm/config_ssl.sh
2025-09-23T23:21:32,928 INFO [storage.resource.NfsSecondaryStorageResource]
(main:[]) config_ssl.sh found in /usr/local/cloud/systemvm/config_ssl.sh
2025-09-23T23:21:32,928 DEBUG [utils.script.Script] (main:[]) Looking for
config_auth.sh in the classpath
2025-09-23T23:21:32,929 DEBUG [utils.script.Script] (main:[]) System resource:
file:/usr/local/cloud/systemvm/config_auth.sh
2025-09-23T23:21:32,929 DEBUG [utils.script.Script] (main:[]) Absolute path =
/usr/local/cloud/systemvm/config_auth.sh
2025-09-23T23:21:32,929 INFO [storage.resource.NfsSecondaryStorageResource]
(main:[]) config_auth.sh found in /usr/local/cloud/systemvm/config_auth.sh
2025-09-23T23:21:32,930 DEBUG [utils.script.Script] (main:[]) Looking for
ipfirewall.sh in the classpath
2025-09-23T23:21:32,932 DEBUG [utils.script.Script] (main:[]) System resource:
file:/usr/local/cloud/systemvm/ipfirewall.sh
2025-09-23T23:21:32,932 DEBUG [utils.script.Script] (main:[]) Absolute path =
/usr/local/cloud/systemvm/ipfirewall.sh
2025-09-23T23:21:32,933 INFO [storage.resource.NfsSecondaryStorageResource]
(main:[]) _configIpFirewallScr found in /usr/local/cloud/systemvm/ipfirewall.sh
2025-09-23T23:21:32,936 DEBUG [utils.script.Script] (main:[]) Looking for
create_privatetemplate_from_snapshot_xen.sh in the classpath
2025-09-23T23:21:32,936 DEBUG [utils.script.Script] (main:[]) System resource:
null
2025-09-23T23:21:32,937 DEBUG [utils.script.Script] (main:[]) Classpath
resource:
file:/usr/local/cloud/systemvm/scripts/storage/secondary/create_privatetemplate_from_snapshot_xen.sh
2025-09-23T23:21:32,938 DEBUG [utils.script.Script] (main:[]) Absolute path =
/usr/local/cloud/systemvm/scripts/storage/secondary/create_privatetemplate_from_snapshot_xen.sh
2025-09-23T23:21:32,939 INFO [storage.resource.NfsSecondaryStorageResource]
(main:[]) Secondary storage runs in role templateProcessor
2025-09-23T23:21:32,951 DEBUG [storage.resource.NfsSecondaryStorageResource]
(main:[]) addRouteToInternalIp: localgw=172.17.26.1, eth1ip=172.17.26.82,
eth1mask=255.255.255.0,destIp=172.24.48.133
2025-09-23T23:21:32,957 DEBUG [storage.resource.NfsSecondaryStorageResource]
(main:[]) Executing command [/bin/bash -c ip route delete 172.24.48.133 ].
2025-09-23T23:21:32,982 DEBUG [storage.resource.NfsSecondaryStorageResource]
(main:[]) Successfully executed process [7700] for command [/bin/bash -c ip
route delete 172.24.48.133 ].
2025-09-23T23:21:32,983 DEBUG [storage.resource.NfsSecondaryStorageResource]
(main:[]) Executing command [/bin/bash -c ip route add 172.24.48.133 via
172.17.26.1 ].
2025-09-23T23:21:32,988 DEBUG [storage.resource.NfsSecondaryStorageResource]
(main:[]) Successfully executed process [7702] for command [/bin/bash -c ip
route add 172.24.48.133 via 172.17.26.1 ].
2025-09-23T23:21:32,989 DEBUG [storage.resource.NfsSecondaryStorageResource]
(main:[]) addRouteToInternalIp: added route to internal ip=172.24.48.133 via
172.17.26.1
2025-09-23T23:21:32,989 DEBUG [storage.resource.NfsSecondaryStorageResource]
(main:[]) addRouteToInternalIp: localgw=172.17.26.1, eth1ip=172.17.26.82,
eth1mask=255.255.255.0,destIp=172.24.48.134
2025-09-23T23:21:32,990 DEBUG [storage.resource.NfsSecondaryStorageResource]
(main:[]) Executing command [/bin/bash -c ip route delete 172.24.48.134 ].
2025-09-23T23:21:32,993 DEBUG [storage.resource.NfsSecondaryStorageResource]
(main:[]) Successfully executed process [7704] for command [/bin/bash -c ip
route delete 172.24.48.134 ].
2025-09-23T23:21:32,994 DEBUG [storage.resource.NfsSecondaryStorageResource]
(main:[]) Executing command [/bin/bash -c ip route add 172.24.48.134 via
172.17.26.1 ].
2025-09-23T23:21:32,998 DEBUG [storage.resource.NfsSecondaryStorageResource]
(main:[]) Successfully executed process [7706] for command [/bin/bash -c ip
route add 172.24.48.134 via 172.17.26.1 ].
2025-09-23T23:21:33,000 DEBUG [storage.resource.NfsSecondaryStorageResource]
(main:[]) addRouteToInternalIp: added route to internal ip=172.24.48.134 via
172.17.26.1
2025-09-23T23:21:33,000 DEBUG [storage.resource.NfsSecondaryStorageResource]
(main:[]) addRouteToInternalIp: localgw=172.17.26.1, eth1ip=172.17.26.82,
eth1mask=255.255.255.0,destIp=172.24.48.195
2025-09-23T23:21:33,001 DEBUG [storage.resource.NfsSecondaryStorageResource]
(main:[]) Executing command [/bin/bash -c ip route delete 172.24.48.195 ].
2025-09-23T23:21:33,009 DEBUG [storage.resource.NfsSecondaryStorageResource]
(main:[]) Successfully executed process [7708] for command [/bin/bash -c ip
route delete 172.24.48.195 ].
2025-09-23T23:21:33,010 DEBUG [storage.resource.NfsSecondaryStorageResource]
(main:[]) Executing command [/bin/bash -c ip route add 172.24.48.195 via
172.17.26.1 ].
2025-09-23T23:21:33,015 DEBUG [storage.resource.NfsSecondaryStorageResource]
(main:[]) Successfully executed process [7710] for command [/bin/bash -c ip
route add 172.24.48.195 via 172.17.26.1 ].
2025-09-23T23:21:33,016 DEBUG [storage.resource.NfsSecondaryStorageResource]
(main:[]) addRouteToInternalIp: added route to internal ip=172.24.48.195 via
172.17.26.1
2025-09-23T23:21:33,017 DEBUG [storage.resource.NfsSecondaryStorageResource]
(main:[]) addRouteToInternalIp: localgw=172.17.26.1, eth1ip=172.17.26.82,
eth1mask=255.255.255.0,destIp=172.24.48.196
2025-09-23T23:21:33,018 DEBUG [storage.resource.NfsSecondaryStorageResource]
(main:[]) Executing command [/bin/bash -c ip route delete 172.24.48.196 ].
2025-09-23T23:21:33,025 DEBUG [storage.resource.NfsSecondaryStorageResource]
(main:[]) Successfully executed process [7712] for command [/bin/bash -c ip
route delete 172.24.48.196 ].
2025-09-23T23:21:33,026 DEBUG [storage.resource.NfsSecondaryStorageResource]
(main:[]) Executing command [/bin/bash -c ip route add 172.24.48.196 via
172.17.26.1 ].
2025-09-23T23:21:33,031 DEBUG [storage.resource.NfsSecondaryStorageResource]
(main:[]) Successfully executed process [7714] for command [/bin/bash -c ip
route add 172.24.48.196 via 172.17.26.1 ].
2025-09-23T23:21:33,032 DEBUG [storage.resource.NfsSecondaryStorageResource]
(main:[]) addRouteToInternalIp: added route to internal ip=172.24.48.196 via
172.17.26.1
2025-09-23T23:21:33,033 DEBUG [storage.resource.NfsSecondaryStorageResource]
(main:[]) Executing command [/bin/systemctl restart ssh ].
2025-09-23T23:21:33,134 DEBUG [storage.resource.NfsSecondaryStorageResource]
(main:[]) Successfully executed process [7716] for command [/bin/systemctl
restart ssh ].
2025-09-23T23:21:33,135 INFO [storage.resource.IpTablesHelper] (main:[])
Adding rule [-i eth1 -p tcp -m state --state NEW -m tcp --dport 3922 -j ACCEPT]
to [INPUT] if required.
2025-09-23T23:21:33,139 DEBUG [storage.resource.IpTablesHelper] (main:[])
Executing command [/bin/bash -c iptables -C INPUT -i eth1 -p tcp -m state
--state NEW -m tcp --dport 3922 -j ACCEPT ].
2025-09-23T23:21:33,155 DEBUG [storage.resource.IpTablesHelper] (main:[])
Successfully executed process [7720] for command [/bin/bash -c iptables -C
INPUT -i eth1 -p tcp -m state --state NEW -m tcp --dport 3922 -j ACCEPT ].
2025-09-23T23:21:33,155 DEBUG [storage.resource.IpTablesHelper] (main:[]) Rule
[-i eth1 -p tcp -m state --state NEW -m tcp --dport 3922 -j ACCEPT], doesn't
need adding to [INPUT] : null
2025-09-23T23:21:33,156 WARN [storage.resource.IpTablesHelper] (main:[]) Rule
already defined in SVM: -i eth1 -p tcp -m state --state NEW -m tcp --dport 3922
-j ACCEPT
2025-09-23T23:21:33,160 INFO [storage.template.DownloadManagerImpl] (main:[])
DownloadManager: starting additional services since we are inside system vm
2025-09-23T23:21:33,160 DEBUG [storage.template.DownloadManagerImpl] (main:[])
Executing command [/bin/systemctl stop apache2 ].
2025-09-23T23:21:33,288 DEBUG [storage.template.DownloadManagerImpl] (main:[])
Successfully executed process [7721] for command [/bin/systemctl stop apache2 ].
2025-09-23T23:21:33,290 INFO [storage.resource.IpTablesHelper] (main:[])
Adding rule [-i eth2 -p tcp -m state --state NEW -m tcp --dport 80 -j ACCEPT]
to [INPUT] if required.
2025-09-23T23:21:33,290 DEBUG [storage.resource.IpTablesHelper] (main:[])
Executing command [/bin/bash -c iptables -C INPUT -i eth2 -p tcp -m state
--state NEW -m tcp --dport 80 -j ACCEPT ].
2025-09-23T23:21:33,296 DEBUG [storage.resource.IpTablesHelper] (main:[])
Successfully executed process [7727] for command [/bin/bash -c iptables -C
INPUT -i eth2 -p tcp -m state --state NEW -m tcp --dport 80 -j ACCEPT ].
2025-09-23T23:21:33,296 DEBUG [storage.resource.IpTablesHelper] (main:[]) Rule
[-i eth2 -p tcp -m state --state NEW -m tcp --dport 80 -j ACCEPT], doesn't need
adding to [INPUT] : null
2025-09-23T23:21:33,297 WARN [storage.resource.IpTablesHelper] (main:[]) Rule
already defined in SVM: -i eth2 -p tcp -m state --state NEW -m tcp --dport 80
-j ACCEPT
2025-09-23T23:21:33,297 INFO [storage.resource.IpTablesHelper] (main:[])
Adding rule [-i eth2 -p tcp -m state --state NEW -m tcp --dport 443 -j ACCEPT;]
to [INPUT] if required.
2025-09-23T23:21:33,300 DEBUG [storage.resource.IpTablesHelper] (main:[])
Executing command [/bin/bash -c iptables -C INPUT -i eth2 -p tcp -m state
--state NEW -m tcp --dport 443 -j ACCEPT; ].
2025-09-23T23:21:33,309 DEBUG [storage.resource.IpTablesHelper] (main:[])
Successfully executed process [7728] for command [/bin/bash -c iptables -C
INPUT -i eth2 -p tcp -m state --state NEW -m tcp --dport 443 -j ACCEPT; ].
2025-09-23T23:21:33,309 DEBUG [storage.resource.IpTablesHelper] (main:[]) Rule
[-i eth2 -p tcp -m state --state NEW -m tcp --dport 443 -j ACCEPT;], doesn't
need adding to [INPUT] : null
2025-09-23T23:21:33,310 WARN [storage.resource.IpTablesHelper] (main:[]) Rule
already defined in SVM: -i eth2 -p tcp -m state --state NEW -m tcp --dport 443
-j ACCEPT;
2025-09-23T23:21:33,311 DEBUG [storage.template.DownloadManagerImpl] (main:[])
Executing command [/bin/systemctl start apache2 ].
2025-09-23T23:21:33,352 DEBUG [storage.template.DownloadManagerImpl] (main:[])
Successfully executed process [7729] for command [/bin/systemctl start apache2
].
2025-09-23T23:21:33,352 DEBUG [storage.template.DownloadManagerImpl] (main:[])
Executing command [/bin/su -s /bin/bash -c mkdir -p /var/www/html/copy/template
www-data ].
2025-09-23T23:21:33,565 DEBUG [storage.template.DownloadManagerImpl] (main:[])
Successfully executed process [7735] for command [/bin/su -s /bin/bash -c mkdir
-p /var/www/html/copy/template www-data ].
2025-09-23T23:21:33,566 INFO [storage.resource.IpTablesHelper] (main:[])
Adding rule [-o eth1 -p tcp -m state --state NEW -m tcp --dport 80 -j REJECT;]
to [OUTPUT] if required.
2025-09-23T23:21:33,566 DEBUG [storage.resource.IpTablesHelper] (main:[])
Executing command [/bin/bash -c iptables -C OUTPUT -o eth1 -p tcp -m state
--state NEW -m tcp --dport 80 -j REJECT; ].
2025-09-23T23:21:33,573 DEBUG [storage.resource.IpTablesHelper] (main:[])
Successfully executed process [7810] for command [/bin/bash -c iptables -C
OUTPUT -o eth1 -p tcp -m state --state NEW -m tcp --dport 80 -j REJECT; ].
2025-09-23T23:21:33,574 DEBUG [storage.resource.IpTablesHelper] (main:[]) Rule
[-o eth1 -p tcp -m state --state NEW -m tcp --dport 80 -j REJECT;], doesn't
need adding to [OUTPUT] : null
2025-09-23T23:21:33,575 WARN [storage.resource.IpTablesHelper] (main:[]) Rule
already defined in SVM: -o eth1 -p tcp -m state --state NEW -m tcp --dport 80
-j REJECT;
2025-09-23T23:21:33,575 INFO [storage.resource.IpTablesHelper] (main:[])
Adding rule [-o eth1 -p tcp -m state --state NEW -m tcp --dport 443 -j REJECT;]
to [OUTPUT] if required.
2025-09-23T23:21:33,575 DEBUG [storage.resource.IpTablesHelper] (main:[])
Executing command [/bin/bash -c iptables -C OUTPUT -o eth1 -p tcp -m state
--state NEW -m tcp --dport 443 -j REJECT; ].
2025-09-23T23:21:33,579 DEBUG [storage.resource.IpTablesHelper] (main:[])
Successfully executed process [7811] for command [/bin/bash -c iptables -C
OUTPUT -o eth1 -p tcp -m state --state NEW -m tcp --dport 443 -j REJECT; ].
2025-09-23T23:21:33,579 DEBUG [storage.resource.IpTablesHelper] (main:[]) Rule
[-o eth1 -p tcp -m state --state NEW -m tcp --dport 443 -j REJECT;], doesn't
need adding to [OUTPUT] : null
2025-09-23T23:21:33,581 WARN [storage.resource.IpTablesHelper] (main:[]) Rule
already defined in SVM: -o eth1 -p tcp -m state --state NEW -m tcp --dport 443
-j REJECT;
2025-09-23T23:21:33,581 DEBUG [utils.script.Script] (main:[]) Looking for
listvmtmplt.sh in the classpath
2025-09-23T23:21:33,582 DEBUG [utils.script.Script] (main:[]) System resource:
null
2025-09-23T23:21:33,582 DEBUG [utils.script.Script] (main:[]) Classpath
resource:
file:/usr/local/cloud/systemvm/scripts/storage/secondary/listvmtmplt.sh
2025-09-23T23:21:33,583 DEBUG [utils.script.Script] (main:[]) Absolute path =
/usr/local/cloud/systemvm/scripts/storage/secondary/listvmtmplt.sh
2025-09-23T23:21:33,584 INFO [storage.template.DownloadManagerImpl] (main:[])
listvmtmplt.sh found in
/usr/local/cloud/systemvm/scripts/storage/secondary/listvmtmplt.sh
2025-09-23T23:21:33,585 DEBUG [utils.script.Script] (main:[]) Looking for
createtmplt.sh in the classpath
2025-09-23T23:21:33,588 DEBUG [utils.script.Script] (main:[]) System resource:
null
2025-09-23T23:21:33,589 DEBUG [utils.script.Script] (main:[]) Classpath
resource:
file:/usr/local/cloud/systemvm/scripts/storage/secondary/createtmplt.sh
2025-09-23T23:21:33,589 DEBUG [utils.script.Script] (main:[]) Absolute path =
/usr/local/cloud/systemvm/scripts/storage/secondary/createtmplt.sh
2025-09-23T23:21:33,590 INFO [storage.template.DownloadManagerImpl] (main:[])
createtmplt.sh found in
/usr/local/cloud/systemvm/scripts/storage/secondary/createtmplt.sh
2025-09-23T23:21:33,592 DEBUG [utils.script.Script] (main:[]) Looking for
listvolume.sh in the classpath
2025-09-23T23:21:33,593 DEBUG [utils.script.Script] (main:[]) System resource:
null
2025-09-23T23:21:33,595 DEBUG [utils.script.Script] (main:[]) Classpath
resource: file:/usr/local/cloud/systemvm/scripts/storage/secondary/listvolume.sh
2025-09-23T23:21:33,596 DEBUG [utils.script.Script] (main:[]) Absolute path =
/usr/local/cloud/systemvm/scripts/storage/secondary/listvolume.sh
2025-09-23T23:21:33,596 INFO [storage.template.DownloadManagerImpl] (main:[])
listvolume.sh found in
/usr/local/cloud/systemvm/scripts/storage/secondary/listvolume.sh
2025-09-23T23:21:33,599 DEBUG [utils.script.Script] (main:[]) Looking for
createvolume.sh in the classpath
2025-09-23T23:21:33,600 DEBUG [utils.script.Script] (main:[]) System resource:
null
2025-09-23T23:21:33,601 DEBUG [utils.script.Script] (main:[]) Classpath
resource:
file:/usr/local/cloud/systemvm/scripts/storage/secondary/createvolume.sh
2025-09-23T23:21:33,601 DEBUG [utils.script.Script] (main:[]) Absolute path =
/usr/local/cloud/systemvm/scripts/storage/secondary/createvolume.sh
2025-09-23T23:21:33,601 INFO [storage.template.DownloadManagerImpl] (main:[])
createvolume.sh found in
/usr/local/cloud/systemvm/scripts/storage/secondary/createvolume.sh
2025-09-23T23:21:33,615 INFO [storage.template.UploadManagerImpl] (main:[])
UploadManager: starting additional services since we are inside system vm
2025-09-23T23:21:33,622 DEBUG [storage.template.UploadManagerImpl] (main:[])
Executing command [rm -rf /mnt/SecStorage/extractmnt ].
2025-09-23T23:21:33,628 DEBUG [storage.template.UploadManagerImpl] (main:[])
Successfully executed process [7812] for command [rm -rf
/mnt/SecStorage/extractmnt ].
2025-09-23T23:21:33,628 DEBUG [storage.template.UploadManagerImpl] (main:[])
Executing command [touch /mnt/SecStorage/extractmnt ].
2025-09-23T23:21:33,636 DEBUG [storage.template.UploadManagerImpl] (main:[])
Successfully executed process [7813] for command [touch
/mnt/SecStorage/extractmnt ].
2025-09-23T23:21:33,637 DEBUG [storage.template.UploadManagerImpl] (main:[])
Executing command [/bin/bash -c ln -sf null /mnt/SecStorage/extractmnt ].
2025-09-23T23:21:33,642 DEBUG [storage.template.UploadManagerImpl] (main:[])
Successfully executed process [7814] for command [/bin/bash -c ln -sf null
/mnt/SecStorage/extractmnt ].
2025-09-23T23:21:33,670 INFO [cloud.serializer.GsonHelper] (main:[]) Default
Builder inited.
2025-09-23T23:21:33,701 DEBUG [agent.properties.AgentPropertiesFileHandler]
(main:[]) Property [ssl.handshake.timeout] has empty or null value. Using
default value [30].
2025-09-23T23:21:33,703 INFO [cloud.agent.Agent] (main:[]) Agent [id = new,
uuid = null, name = null, type = PremiumSecondaryStorageResource, zone = 3, pod
= 3, workers = 5, port = 8250] with host = 172.24.48.133, local id = 1
2025-09-23T23:21:33,704 DEBUG [utils.script.Script] (main:[]) Looking for
keystore-setup in the classpath
2025-09-23T23:21:33,705 DEBUG [utils.script.Script] (main:[]) System resource:
null
2025-09-23T23:21:33,707 DEBUG [utils.script.Script] (main:[]) Classpath
resource: file:/usr/local/cloud/systemvm/scripts/util/keystore-setup
2025-09-23T23:21:33,708 DEBUG [utils.script.Script] (main:[]) Absolute path =
/usr/local/cloud/systemvm/scripts/util/keystore-setup
2025-09-23T23:21:33,709 DEBUG [utils.script.Script] (main:[]) Looking for
keystore-cert-import in the classpath
2025-09-23T23:21:33,711 DEBUG [utils.script.Script] (main:[]) System resource:
null
2025-09-23T23:21:33,711 DEBUG [utils.script.Script] (main:[]) Classpath
resource: file:/usr/local/cloud/systemvm/scripts/util/keystore-cert-import
2025-09-23T23:21:33,712 DEBUG [utils.script.Script] (main:[]) Absolute path =
/usr/local/cloud/systemvm/scripts/util/keystore-cert-import
2025-09-23T23:21:33,713 INFO [utils.nio.NioClient] (main:[]) Connecting to
172.24.48.133:8250
2025-09-23T23:21:33,730 INFO [utils.nio.NioClient] (main:[]) Connected to
172.24.48.133:8250
2025-09-23T23:21:33,734 INFO [utils.nio.Link] (main:[]) Conf file found:
/usr/local/cloud/systemvm/conf/agent.properties
2025-09-23T23:22:04,338 WARN [utils.nio.Link] (main:[]) SSL Handshake has
taken more than 30000 ms to connect to: /172.24.48.133:8250 while status:
NEED_UNWRAP. Please investigate this connection.
2025-09-23T23:22:04,341 ERROR [utils.nio.NioClient] (main:[]) IOException while
connecting to 172.24.48.133:8250 java.io.IOException: SSL Handshake failed
while connecting to host: 172.24.48.133:8250
at com.cloud.utils.nio.NioClient.init(NioClient.java:75)
at com.cloud.utils.nio.NioConnection.start(NioConnection.java:109)
at com.cloud.agent.Agent.start(Agent.java:327)
at com.cloud.agent.AgentShell.launchNewAgent(AgentShell.java:477)
at
com.cloud.agent.AgentShell.launchAgentFromClassInfo(AgentShell.java:454)
at com.cloud.agent.AgentShell.launchAgent(AgentShell.java:438)
at com.cloud.agent.AgentShell.start(AgentShell.java:539)
at com.cloud.agent.AgentShell.main(AgentShell.java:569)
2025-09-23T23:22:04,342 ERROR [utils.nio.NioClient] (main:[]) Unable to
initialize the threads. java.io.IOException: SSL Handshake failed while
connecting to host: 172.24.48.133:8250
at com.cloud.utils.nio.NioClient.init(NioClient.java:75)
at com.cloud.utils.nio.NioConnection.start(NioConnection.java:109)
at com.cloud.agent.Agent.start(Agent.java:327)
at com.cloud.agent.AgentShell.launchNewAgent(AgentShell.java:477)
at
com.cloud.agent.AgentShell.launchAgentFromClassInfo(AgentShell.java:454)
at com.cloud.agent.AgentShell.launchAgent(AgentShell.java:438)
at com.cloud.agent.AgentShell.start(AgentShell.java:539)
at com.cloud.agent.AgentShell.main(AgentShell.java:569)
2025-09-23T23:22:04,344 WARN [cloud.agent.Agent] (main:[]) Attempt to connect
to server generated NIO Connection Exception SSL Handshake failed while
connecting to host: 172.24.48.133:8250, trying again
2025-09-23T23:22:09,346 DEBUG [agent.properties.AgentPropertiesFileHandler]
(main:[]) Property [ssl.handshake.timeout] has empty or null value. Using
default value [30].
2025-09-23T23:22:09,348 INFO [cloud.agent.Agent] (main:[]) Connecting to host:
172.24.48.134
2025-09-23T23:22:09,351 INFO [utils.nio.NioClient] (main:[]) Connecting to
172.24.48.134:8250
2025-09-23T23:22:09,353 INFO [utils.nio.NioClient] (main:[]) Connected to
172.24.48.134:8250
2025-09-23T23:22:09,355 INFO [utils.nio.Link] (main:[]) Conf file found:
/usr/local/cloud/systemvm/conf/agent.properties
GitHub link:
https://github.com/apache/cloudstack/discussions/11694#discussioncomment-14485163
----
This is an automatically sent email for [email protected].
To unsubscribe, please send an email to: [email protected]
