2023-02-15 09:35:35,936 ERROR [storage.resource.NfsSecondaryStorageResource] (agentRequest-Handler-2:null) Unable to mount NFSServerIP:/data/secondary at /mnt/SecStorage/91de6d1c-4c04-359c-82b5-fdcfe4a83da7 due to mount.nfs: parsing error on 'vers=' option
2023-02-15 09:35:35,936 ERROR [storage.resource.NfsSecondaryStorageResource] (agentRequest-Handler-2:null) GetRootDir for nfs://NFSServerIP/data/secondary failed due to com.cloud.utils.exception.CloudRuntimeException: Unable to mount NFSServerIP:/data/secondary at /mnt/SecStorage/91de6d1c-4c04-359c-82b5-fdcfe4a83da7 due to mount.nfs: parsing error on 'vers=' option
com.cloud.utils.exception.CloudRuntimeException: Unable to mount NFSServerIP:/data/secondary at /mnt/SecStorage/91de6d1c-4c04-359c-82b5-fdcfe4a83da7 due to mount.nfs: parsing error on 'vers=' option
at org.apache.cloudstack.storage.resource.NfsSecondaryStorageResource.attemptMount(NfsSecondaryStorageResource.java:2983)
at org.apache.cloudstack.storage.resource.NfsSecondaryStorageResource.mount(NfsSecondaryStorageResource.java:2934)
at org.apache.cloudstack.storage.resource.NfsSecondaryStorageResource.mountUri(NfsSecondaryStorageResource.java:2922)
at org.apache.cloudstack.storage.resource.NfsSecondaryStorageResource.getRootDir(NfsSecondaryStorageResource.java:2567)
at org.apache.cloudstack.storage.resource.NfsSecondaryStorageResource.execute(NfsSecondaryStorageResource.java:2166)
at org.apache.cloudstack.storage.resource.NfsSecondaryStorageResource.executeRequest(NfsSecondaryStorageResource.java:300)
at com.cloud.storage.resource.PremiumSecondaryStorageResource.defaultAction(PremiumSecondaryStorageResource.java:64)
at com.cloud.storage.resource.PremiumSecondaryStorageResource.executeRequest(PremiumSecondaryStorageResource.java:60)
at com.cloud.agent.Agent.processRequest(Agent.java:660)
at com.cloud.agent.Agent$AgentRequestHandler.doTask(Agent.java:1080)
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:1128)
at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
at java.base/java.lang.Thread.run(Thread.java:829)
2023-02-15 09:35:35,937 WARN [cloud.agent.Agent] (agentRequest-Handler-2:null) Caught:
com.cloud.utils.exception.CloudRuntimeException: GetRootDir for nfs://NFSServerIP/data/secondary failed due to com.cloud.utils.exception.CloudRuntimeException: Unable to mount NFSServerIP:/data/secondary at /mnt/SecStorage/91de6d1c-4c04-359c-82b5-fdcfe4a83da7 due to mount.nfs: parsing error on 'vers=' option
at org.apache.cloudstack.storage.resource.NfsSecondaryStorageResource.getRootDir(NfsSecondaryStorageResource.java:2572)
at org.apache.cloudstack.storage.resource.NfsSecondaryStorageResource.execute(NfsSecondaryStorageResource.java:2166)
at org.apache.cloudstack.storage.resource.NfsSecondaryStorageResource.executeRequest(NfsSecondaryStorageResource.java:300)
at com.cloud.storage.resource.PremiumSecondaryStorageResource.defaultAction(PremiumSecondaryStorageResource.java:64)
at com.cloud.storage.resource.PremiumSecondaryStorageResource.executeRequest(PremiumSecondaryStorageResource.java:60)
at com.cloud.agent.Agent.processRequest(Agent.java:660)
at com.cloud.agent.Agent$AgentRequestHandler.doTask(Agent.java:1080)
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:1128)
at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
at java.base/java.lang.Thread.run(Thread.java:829)
2023-02-15 09:35:35,956 INFO [storage.resource.NfsSecondaryStorageResource] (agentRequest-Handler-1:null) Determined host NFSServerIP corresponds to IP NFSServerIP
2023-02-15 09:37:35,372 ERROR [storage.resource.NfsSecondaryStorageResource] (agentRequest-Handler-1:null) Unable to mount NFSServerIP:/data/secondary at /mnt/SecStorage/91de6d1c-4c04-359c-82b5-fdcfe4a83da7 due to
2023-02-15 09:37:35,375 ERROR [storage.resource.NfsSecondaryStorageResource] (agentRequest-Handler-1:null) GetRootDir for nfs://NFSServerIP/data/secondary failed due to com.cloud.utils.exception.CloudRuntimeException: Unable to mount NFSServerIP:/data/secondary at /mnt/SecStorage/91de6d1c-4c04-359c-82b5-fdcfe4a83da7 due to
com.cloud.utils.exception.CloudRuntimeException: Unable to mount NFSServerIP:/data/secondary at /mnt/SecStorage/91de6d1c-4c04-359c-82b5-fdcfe4a83da7 due to
at org.apache.cloudstack.storage.resource.NfsSecondaryStorageResource.attemptMount(NfsSecondaryStorageResource.java:2983)
at org.apache.cloudstack.storage.resource.NfsSecondaryStorageResource.mount(NfsSecondaryStorageResource.java:2934)
at org.apache.cloudstack.storage.resource.NfsSecondaryStorageResource.mountUri(NfsSecondaryStorageResource.java:2922)
at org.apache.cloudstack.storage.resource.NfsSecondaryStorageResource.getRootDir(NfsSecondaryStorageResource.java:2567)
at org.apache.cloudstack.storage.resource.NfsSecondaryStorageResource.execute(NfsSecondaryStorageResource.java:2144)
at org.apache.cloudstack.storage.resource.NfsSecondaryStorageResource.executeRequest(NfsSecondaryStorageResource.java:298)
at com.cloud.storage.resource.PremiumSecondaryStorageResource.defaultAction(PremiumSecondaryStorageResource.java:64)
at com.cloud.storage.resource.PremiumSecondaryStorageResource.executeRequest(PremiumSecondaryStorageResource.java:60)
at com.cloud.agent.Agent.processRequest(Agent.java:660)
at com.cloud.agent.Agent$AgentRequestHandler.doTask(Agent.java:1080)
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:1128)
at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
at java.base/java.lang.Thread.run(Thread.java:829)
2023-02-15 09:37:35,377 INFO [storage.resource.NfsSecondaryStorageResource] (agentRequest-Handler-4:null) Determined host 192.168.50.2 corresponds to IP 192.168.50.2
2023-02-15 09:37:35,378 WARN [cloud.agent.Agent] (agentRequest-Handler-1:null) Caught:
com.cloud.utils.exception.CloudRuntimeException: GetRootDir for nfs://NFSServerIP/data/secondary failed due to com.cloud.utils.exception.CloudRuntimeException: Unable to mount NFSServerIP:/data/secondary at /mnt/SecStorage/91de6d1c-4c04-359c-82b5-fdcfe4a83da7 due to
at org.apache.cloudstack.storage.resource.NfsSecondaryStorageResource.getRootDir(NfsSecondaryStorageResource.java:2572)
at org.apache.cloudstack.storage.resource.NfsSecondaryStorageResource.execute(NfsSecondaryStorageResource.java:2144)
at org.apache.cloudstack.storage.resource.NfsSecondaryStorageResource.executeRequest(NfsSecondaryStorageResource.java:298)
at com.cloud.storage.resource.PremiumSecondaryStorageResource.defaultAction(PremiumSecondaryStorageResource.java:64)
at com.cloud.storage.resource.PremiumSecondaryStorageResource.executeRequest(PremiumSecondaryStorageResource.java:60)
at com.cloud.agent.Agent.processRequest(Agent.java:660)
at com.cloud.agent.Agent$AgentRequestHandler.doTask(Agent.java:1080)
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:1128)
at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
at java.base/java.lang.Thread.run(Thread.java:829)
2023-02-15 09:37:35,395 INFO [cloud.agent.Agent] (agentRequest-Handler-5:null) Processing agent ready command, agent id = 46
2023-02-15 09:37:35,395 INFO [cloud.agent.Agent] (agentRequest-Handler-5:null) Set agent id 46
2023-02-15 09:37:35,396 INFO [cloud.agent.Agent] (agentRequest-Handler-5:null) Ready command is processed for agent id = 46
2023-02-15 09:37:35,399 INFO [cloud.agent.Agent] (AgentShutdownThread:null) Stopping the agent: Reason = sig.kill
2023-02-15 09:37:38,372 INFO [cloud.agent.AgentShell] (main:null) Agent started
2023-02-15 09:37:38,392 INFO [cloud.agent.AgentShell] (main:null) Implementation Version is 4.17.2.0
2023-02-15 09:37:38,395 INFO [cloud.agent.AgentShell] (main:null) agent.properties found at /usr/local/cloud/systemvm/conf/agent.properties
2023-02-15 09:37:38,418 INFO [cloud.agent.AgentShell] (main:null) Defaulting to using properties file for storage
2023-02-15 09:37:38,420 INFO [cloud.agent.AgentShell] (main:null) Defaulting to the constant time backoff algorithm
2023-02-15 09:37:38,486 INFO [cloud.utils.LogUtils] (main:null) log4j configuration found at /usr/local/cloud/systemvm/conf/log4j-cloud.xml
2023-02-15 09:37:38,487 INFO [cloud.agent.AgentShell] (main:null) Using default Java settings for IPv6 preference for agent connection
2023-02-15 09:37:38,735 INFO [cloud.agent.Agent] (main:null) id is 46
2023-02-15 09:37:38,765 INFO [storage.resource.NfsSecondaryStorageResource] (main:null) config_ssl.sh found in /usr/local/cloud/systemvm/config_ssl.sh
2023-02-15 09:37:38,767 INFO [storage.resource.NfsSecondaryStorageResource] (main:null) config_auth.sh found in /usr/local/cloud/systemvm/config_auth.sh
2023-02-15 09:37:38,768 INFO [storage.resource.NfsSecondaryStorageResource] (main:null) _configIpFirewallScr found in /usr/local/cloud/systemvm/ipfirewall.sh
2023-02-15 09:37:38,776 INFO [storage.resource.NfsSecondaryStorageResource] (main:null) Secondary storage runs in role templateProcessor
2023-02-15 09:37:39,005 INFO [storage.template.DownloadManagerImpl] (main:null) DownloadManager: starting additional services since we are inside system vm
2023-02-15 09:37:39,326 INFO [storage.template.DownloadManagerImpl] (main:null) listvmtmplt.sh found in /usr/local/cloud/systemvm/scripts/storage/secondary/listvmtmplt.sh
2023-02-15 09:37:39,328 INFO [storage.template.DownloadManagerImpl] (main:null) createtmplt.sh found in /usr/local/cloud/systemvm/scripts/storage/secondary/createtmplt.sh
2023-02-15 09:37:39,336 INFO [storage.template.DownloadManagerImpl] (main:null) listvolume.sh found in /usr/local/cloud/systemvm/scripts/storage/secondary/listvolume.sh
2023-02-15 09:37:39,344 INFO [storage.template.DownloadManagerImpl] (main:null) createvolume.sh found in /usr/local/cloud/systemvm/scripts/storage/secondary/createvolume.sh
2023-02-15 09:37:39,371 INFO [storage.template.UploadManagerImpl] (main:null) UploadManager: starting additional services since we are inside system vm
2023-02-15 09:37:39,512 INFO [cloud.serializer.GsonHelper] (main:null) Default Builder inited.
2023-02-15 09:37:39,574 INFO [cloud.agent.Agent] (main:null) Agent [id = 46 : type = PremiumSecondaryStorageResource : zone = 16 : pod = 15 : workers = 5 : host = MGMNTServerIP : port = 8250
2023-02-15 09:37:39,600 INFO [utils.nio.NioClient] (main:null) Connecting to MGMNTServerIP:8250
2023-02-15 09:37:39,605 INFO [utils.nio.Link] (main:null) Conf file found: /usr/local/cloud/systemvm/conf/agent.properties
2023-02-15 09:37:40,853 INFO [utils.nio.NioClient] (main:null) SSL: Handshake done
2023-02-15 09:37:40,863 INFO [utils.nio.NioClient] (main:null) Connected to MGMNTServerIP:8250
2023-02-15 09:37:40,978 INFO [cloud.agent.Agent] (Agent-Handler-2:null) Process agent startup answer, agent id = 0
2023-02-15 09:37:40,980 INFO [cloud.agent.Agent] (Agent-Handler-2:null) Set agent id 0
2023-02-15 09:37:41,030 INFO [cloud.agent.Agent] (Agent-Handler-2:null) Startup Response Received: agent id = 0
2023-02-15 09:37:41,051 INFO [storage.resource.NfsSecondaryStorageResource] (agentRequest-Handler-2:null) Determined host 192.168.50.2 corresponds to IP 192.168.50.2
2023-02-15 09:37:41,061 INFO [storage.resource.NfsSecondaryStorageResource] (agentRequest-Handler-2:null) Determined host 192.168.50.2 corresponds to IP 192.168.50.2
2023-02-15 09:37:43,728 INFO [storage.resource.NfsSecondaryStorageResource] (agentRequest-Handler-2:null) created a thread to start post upload server
2023-02-15 09:37:43,804 INFO [storage.resource.NfsSecondaryStorageResource] (agentRequest-Handler-3:null) Determined host NFSServerIP corresponds to IP NFSServerIP
2023-02-15 09:37:43,845 INFO [storage.resource.NfsSecondaryStorageResource] (agentRequest-Handler-3:null) Determined host NFSServerIP corresponds to IP NFSServerIP
2023-02-15 09:37:43,989 INFO [handler.logging.LoggingHandler] (nioEventLoopGroup-2-1:null) [id: 0x74ad3664] REGISTERED
2023-02-15 09:37:43,996 INFO [handler.logging.LoggingHandler] (nioEventLoopGroup-2-1:null) [id: 0x74ad3664] BIND: 0.0.0.0/0.0.0.0:8210
2023-02-15 09:37:44,003 INFO [storage.resource.NfsSecondaryStorageResource] (Thread-2:null) Started post upload server on port 8210 with 15 workers
2023-02-15 09:37:44,005 INFO [handler.logging.LoggingHandler] (nioEventLoopGroup-2-1:null) [id: 0x74ad3664, L:/0.0.0.0:8210] ACTIVE
root@s-141-VM:~#