坂上さま
木次です。
3.0.1で入れなおしてみました。
secondaryのストレージのエラーがmanagement-server.logに出ておりました。
nfs://storage3/export/secondary
storage3は、NFSでマウントできることは確認できています。
何か考えられることはありますでしょうか。
情報お持ちでしたら、ご教授頂けましたら幸いです。
以下management-server.logになります。
2012-04-04 12:24:19,927 DEBUG [cloud.server.StatsCollector]
(StatsCollector-1:null) StorageCollector is running...
2012-04-04 12:24:20,025 DEBUG [agent.transport.Request]
(AgentManager-Handler-6:null) Seq 4-1613758472: Processing: { Ans: ,
MgmtId: 24793718363207, via: 4, Ver: v1, Flags: 10,
[{"Answer":
{"result":false,"details":"com.cloud.utils.exception.CloudRuntimeException:
GetRootDir for nfs://storage3/export/secondary failed due to
java.net.UnknownHostException: storage3\n\tat
com.cloud.storage.resource.NfsSecondaryStorageResource.getRootDir(NfsSecondaryStorageResource.java:
928)\n\tat
com.cloud.storage.resource.NfsSecondaryStorageResource.execute(NfsSecondaryStorageResource.java:
826)\n\tat
com.cloud.storage.resource.NfsSecondaryStorageResource.executeRequest(NfsSecondaryStorageResource.java:
141)\n\tat
com.cloud.storage.resource.PremiumSecondaryStorageResource.defaultAction(PremiumSecondaryStorageResource.java:
57)\n\tat
com.cloud.storage.resource.PremiumSecondaryStorageResource.executeRequest(PremiumSecondaryStorageResource.java:
53)\n\tat
com.cloud.agent.Agent.processRequest(Agent.java:517)\n\tat
com.cloud.agent.Agent$AgentRequestHandler.doTask(Agent.java:827)\n
\tat
com.cloud.utils.nio.Task.run(Task.java:79)\n\tat
java.util.concurrent.ThreadPoolExecutor
$Worker.runTask(ThreadPoolExecutor.java:886)\n\tat
java.util.concurrent.ThreadPoolExecutor
$Worker.run(ThreadPoolExecutor.java:908)\n\tat
java.lang.Thread.run(Thread.java:662)\n","wait":0}}] }
2012-04-04 12:24:20,042 WARN [cloud.vm.VirtualMachineManagerImpl]
(AgentManager-Handler-6:null) Cleanup failed due to
com.cloud.utils.exception.CloudRuntimeException: GetRootDir for
nfs://storage3/export/secondary failed due to
java.net.UnknownHostException: storage3
at
com.cloud.storage.resource.NfsSecondaryStorageResource.getRootDir(NfsSecondaryStorageResource.java:
928)
at
com.cloud.storage.resource.NfsSecondaryStorageResource.execute(NfsSecondaryStorageResource.java:
826)
at
com.cloud.storage.resource.NfsSecondaryStorageResource.executeRequest(NfsSecondaryStorageResource.java:
141)
at
com.cloud.storage.resource.PremiumSecondaryStorageResource.defaultAction(PremiumSecondaryStorageResource.java:
57)
at
com.cloud.storage.resource.PremiumSecondaryStorageResource.executeRequest(PremiumSecondaryStorageResource.java:
53)
at com.cloud.agent.Agent.processRequest(Agent.java:517)
at com.cloud.agent.Agent$AgentRequestHandler.doTask(Agent.java:827)
at com.cloud.utils.nio.Task.run(Task.java:79)
at
java.util.concurrent.ThreadPoolExecutor
$Worker.runTask(ThreadPoolExecutor.java:886)
at
java.util.concurrent.ThreadPoolExecutor
$Worker.run(ThreadPoolExecutor.java:908)
at java.lang.Thread.run(Thread.java:662)
2012-04-04 12:24:20,042 DEBUG [agent.transport.Request]
(StatsCollector-1:null) Seq 4-1613758472: Received: { Ans: , MgmtId:
24793718363207, via: 4, Ver: v1, Flags: 10, { Answer } }
2012-04-04 12:24:20,042 WARN [cloud.vm.VirtualMachineManagerImpl]
(StatsCollector-1:null) Cleanup failed due to
com.cloud.utils.exception.CloudRuntimeException: GetRootDir for
nfs://storage3/export/secondary failed due to
java.net.UnknownHostException: storage3
at
com.cloud.storage.resource.NfsSecondaryStorageResource.getRootDir(NfsSecondaryStorageResource.java:
928)
at
com.cloud.storage.resource.NfsSecondaryStorageResource.execute(NfsSecondaryStorageResource.java:
826)
at
com.cloud.storage.resource.NfsSecondaryStorageResource.executeRequest(NfsSecondaryStorageResource.java:
141)
at
com.cloud.storage.resource.PremiumSecondaryStorageResource.defaultAction(PremiumSecondaryStorageResource.java:
57)
at
com.cloud.storage.resource.PremiumSecondaryStorageResource.executeRequest(PremiumSecondaryStorageResource.java:
53)
at com.cloud.agent.Agent.processRequest(Agent.java:517)
at com.cloud.agent.Agent$AgentRequestHandler.doTask(Agent.java:827)
at com.cloud.utils.nio.Task.run(Task.java:79)
at
java.util.concurrent.ThreadPoolExecutor
$Worker.runTask(ThreadPoolExecutor.java:886)
at
java.util.concurrent.ThreadPoolExecutor
$Worker.run(ThreadPoolExecutor.java:908)
at java.lang.Thread.run(Thread.java:662)
2012-04-04 12:24:20,043 DEBUG [agent.manager.AgentManagerImpl]
(StatsCollector-1:null) Details from executing class
com.cloud.agent.api.GetStorageStatsCommand:
com.cloud.utils.exception.CloudRuntimeException: GetRootDir for
nfs://storage3/export/secondary failed due to
java.net.UnknownHostException: storage3
at
com.cloud.storage.resource.NfsSecondaryStorageResource.getRootDir(NfsSecondaryStorageResource.java:
928)
at
com.cloud.storage.resource.NfsSecondaryStorageResource.execute(NfsSecondaryStorageResource.java:
826)
at
com.cloud.storage.resource.NfsSecondaryStorageResource.executeRequest(NfsSecondaryStorageResource.java:
141)
at
com.cloud.storage.resource.PremiumSecondaryStorageResource.defaultAction(PremiumSecondaryStorageResource.java:
57)
at
com.cloud.storage.resource.PremiumSecondaryStorageResource.executeRequest(PremiumSecondaryStorageResource.java:
53)
at com.cloud.agent.Agent.processRequest(Agent.java:517)
at com.cloud.agent.Agent$AgentRequestHandler.doTask(Agent.java:827)
at com.cloud.utils.nio.Task.run(Task.java:79)
at
java.util.concurrent.ThreadPoolExecutor
$Worker.runTask(ThreadPoolExecutor.java:886)
at
java.util.concurrent.ThreadPoolExecutor
$Worker.run(ThreadPoolExecutor.java:908)
at java.lang.Thread.run(Thread.java:662)
2012-04-04 12:24:20,094 DEBUG [cloud.vm.VirtualMachineManagerImpl]
(AgentManager-Handler-7:null) Cleanup succeeded. Details null
2012-04-04 12:24:20,094 DEBUG [agent.transport.Request]
(StatsCollector-1:null) Seq 1-858325034: Received: { Ans: , MgmtId:
24793718363207, via: 1, Ver: v1, Flags: 10,
{ GetStorageStatsAnswer } }
2012-04-04 12:24:20,103 DEBUG [cloud.vm.VirtualMachineManagerImpl]
(StatsCollector-1:null) Cleanup succeeded. Details null
2012-04-04 12:24:20,176 DEBUG [cloud.cluster.ClusterManagerImpl]
(Cluster-Heartbeat-1:null) Detected management node left, id:2,
nodeIP:192.168.6.12
2012-04-04 12:24:20,176 DEBUG
[cloud.cluster.ClusterServiceServletImpl]
(Cluster-Heartbeat-1:null) Ping at
http://192.168.6.12:9090/clusterservice
2012-04-04 12:24:20,178 DEBUG
[cloud.cluster.ClusterServiceServletHttpHandler]
(Cluster-Listener-1:null) Handle ping request from 24793718363207
2012-04-04 12:24:20,178 DEBUG
[cloud.cluster.ClusterServiceServletImpl]
(Cluster-Heartbeat-1:null) POST
http://192.168.6.12:9090/clusterservice
response :true, responding time: 2 ms
2012-04-04 12:24:20,178 INFO [cloud.cluster.ClusterManagerImpl]
(Cluster-Heartbeat-1:null) Management node 2 is detected inactive by
timestamp but is pingable
2012-04-04 12:24:21,600 DEBUG [cloud.cluster.ClusterManagerImpl]
(Cluster-Heartbeat-1:null) Detected management node left, id:2,
nodeIP:192.168.6.12
2012-04-04 12:24:21,600 DEBUG
[cloud.cluster.ClusterServiceServletImpl]
(Cluster-Heartbeat-1:null) Ping at
http://192.168.6.12:9090/clusterservice
2012-04-04 12:24:21,602 DEBUG
[cloud.cluster.ClusterServiceServletHttpHandler]
(Cluster-Listener-1:null) Handle ping request from 24793718363207
2012-04-04 12:24:21,602 DEBUG
[cloud.cluster.ClusterServiceServletImpl]
(Cluster-Heartbeat-1:null) POST
http://192.168.6.12:9090/clusterservice
response :true, responding time: 2 ms
2012-04-04 12:24:21,602 INFO [cloud.cluster.ClusterManagerImpl]
(Cluster-Heartbeat-1:null) Management node 2 is detected inactive by
timestamp but is pingable
2012-04-04 12:24:23,096 DEBUG [cloud.cluster.ClusterManagerImpl]
(Cluster-Heartbeat-1:null) Detected management node left, id:2,
nodeIP:192.168.6.12
2012-04-04 12:24:23,096 DEBUG
[cloud.cluster.ClusterServiceServletImpl]
(Cluster-Heartbeat-1:null) Ping at
http://192.168.6.12:9090/clusterservice
2012-04-04 12:24:23,098 DEBUG
[cloud.cluster.ClusterServiceServletHttpHandler]
(Cluster-Listener-1:null) Handle ping request from 24793718363207
2012-04-04 12:24:23,098 DEBUG
[cloud.cluster.ClusterServiceServletImpl]
(Cluster-Heartbeat-1:null) POST
http://192.168.6.12:9090/clusterservice
response :true, responding time: 2 ms
2012-04-04 12:24:23,098 INFO [cloud.cluster.ClusterManagerImpl]
(Cluster-Heartbeat-1:null) Management node 2 is detected inactive by
timestamp but is pingable
2012-04-04 12:24:23,433 DEBUG [cloud.server.StatsCollector]
(StatsCollector-2:null) HostStatsCollector is running...
2012-04-04 12:24:24,010 DEBUG [cloud.vm.VirtualMachineManagerImpl]
(AgentManager-Handler-9:null) Cleanup succeeded. Details null
2012-04-04 12:24:24,010 DEBUG [agent.transport.Request]
(StatsCollector-2:null) Seq 1-858325035: Received: { Ans: , MgmtId:
24793718363207, via: 1, Ver: v1, Flags: 10, { GetHostStatsAnswer } }
2012-04-04 12:24:24,010 DEBUG [cloud.vm.VirtualMachineManagerImpl]
(StatsCollector-2:null) Cleanup succeeded. Details null
2012-04-04 12:24:24,612 DEBUG [cloud.cluster.ClusterManagerImpl]
(Cluster-Heartbeat-1:null) Detected management node left, id:2,
nodeIP:192.168.6.12
2012-04-04 12:24:24,612 DEBUG
[cloud.cluster.ClusterServiceServletImpl]
(Cluster-Heartbeat-1:null) Ping at
http://192.168.6.12:9090/clusterservice
2012-04-04 12:24:24,613 DEBUG
[cloud.cluster.ClusterServiceServletHttpHandler]
(Cluster-Listener-1:null) Handle ping request from 24793718363207
2012-04-04 12:24:24,613 DEBUG
[cloud.cluster.ClusterServiceServletImpl]
(Cluster-Heartbeat-1:null) POST
http://192.168.6.12:9090/clusterservice
response :true, responding time: 1 ms
2012-04-04 12:24:24,614 INFO [cloud.cluster.ClusterManagerImpl]
(Cluster-Heartbeat-1:null) Management node 2 is detected inactive by
timestamp but is pingable
2012-04-04 12:24:26,068 DEBUG [cloud.cluster.ClusterManagerImpl]
(Cluster-Heartbeat-1:null) Detected management node left, id:2,
nodeIP:192.168.6.12
2012-04-04 12:24:26,068 DEBUG
[cloud.cluster.ClusterServiceServletImpl]
(Cluster-Heartbeat-1:null) Ping at
http://192.168.6.12:9090/clusterservice
2012-04-04 12:24:26,069 DEBUG
[cloud.cluster.ClusterServiceServletHttpHandler]
(Cluster-Listener-1:null) Handle ping request from 24793718363207
2012-04-04 12:24:26,070 DEBUG
[cloud.cluster.ClusterServiceServletImpl]
(Cluster-Heartbeat-1:null) POST
http://192.168.6.12:9090/clusterservice
response :true, responding time: 2 ms
2012-04-04 12:24:26,070 INFO [cloud.cluster.ClusterManagerImpl]
(Cluster-Heartbeat-1:null) Management node 2 is detected inactive by
timestamp but is pingable
2012-04-04 12:24:27,594 DEBUG [cloud.cluster.ClusterManagerImpl]
(Cluster-Heartbeat-1:null) Detected management node left, id:2,
nodeIP:192.168.6.12
2012-04-04 12:24:27,594 DEBUG
[cloud.cluster.ClusterServiceServletImpl]
(Cluster-Heartbeat-1:null) Ping at
http://192.168.6.12:9090/clusterservice
2012-04-04 12:24:27,595 DEBUG
[cloud.cluster.ClusterServiceServletHttpHandler]
(Cluster-Listener-1:null) Handle ping request from 24793718363207
2012-04-04 12:24:27,595 DEBUG
[cloud.cluster.ClusterServiceServletImpl]
(Cluster-Heartbeat-1:null) POST
http://192.168.6.12:9090/clusterservice
response :true, responding time: 1 ms
2012-04-04 12:24:27,596 INFO [cloud.cluster.ClusterManagerImpl]
(Cluster-Heartbeat-1:null) Management node 2 is detected inactive by
timestamp but is pingable
2012-04-04 12:24:29,078 DEBUG [cloud.cluster.ClusterManagerImpl]
(Cluster-Heartbeat-1:null) Detected management node left, id:2,
nodeIP:192.168.6.12
2012-04-04 12:24:29,079 DEBUG
[cloud.cluster.ClusterServiceServletImpl]
(Cluster-Heartbeat-1:null) Ping at
http://192.168.6.12:9090/clusterservice
2012-04-04 12:24:29,080 DEBUG
[cloud.cluster.ClusterServiceServletHttpHandler]
(Cluster-Listener-1:null) Handle ping request from 24793718363207
2012-04-04 12:24:29,080 DEBUG
[cloud.cluster.ClusterServiceServletImpl]
(Cluster-Heartbeat-1:null) POST
http://192.168.6.12:9090/clusterservice
response :true, responding time: 1 ms
2012-04-04 12:24:29,080 INFO [cloud.cluster.ClusterManagerImpl]
(Cluster-Heartbeat-1:null) Management node 2 is detected inactive by
timestamp but is pingable
2012-04-04 12:24:29,836 DEBUG [agent.manager.AgentManagerImpl]
(AgentManager-Handler-10:null) Ping from 1
2012-04-04 12:24:30,595 DEBUG [cloud.cluster.ClusterManagerImpl]
(Cluster-Heartbeat-1:null) Detected management node left, id:2,
nodeIP:192.168.6.12
2012-04-04 12:24:30,595 DEBUG
[cloud.cluster.ClusterServiceServletImpl]
(Cluster-Heartbeat-1:null) Ping at
http://192.168.6.12:9090/clusterservice
2012-04-04 12:24:30,596 DEBUG
[cloud.cluster.ClusterServiceServletHttpHandler]
(Cluster-Listener-1:null) Handle ping request from 24793718363207
2012-04-04 12:24:30,597 DEBUG
[cloud.cluster.ClusterServiceServletImpl]
(Cluster-Heartbeat-1:null) POST
http://192.168.6.12:9090/clusterservice
response :true, responding time: 2 ms
2012-04-04 12:24:30,597 INFO [cloud.cluster.ClusterManagerImpl]
(Cluster-Heartbeat-1:null) Management node 2 is detected inactive by
timestamp but is pingable
2012-04-04 12:24:32,090 DEBUG [cloud.cluster.ClusterManagerImpl]
(Cluster-Heartbeat-1:null) Detected management node left, id:2,
nodeIP:192.168.6.12
2012-04-04 12:24:32,090 DEBUG
[cloud.cluster.ClusterServiceServletImpl]
(Cluster-Heartbeat-1:null) Ping at
http://192.168.6.12:9090/clusterservice
2012-04-04 12:24:32,092 DEBUG
[cloud.cluster.ClusterServiceServletHttpHandler]
(Cluster-Listener-1:null) Handle ping request from 24793718363207
2012-04-04 12:24:32,092 DEBUG
[cloud.cluster.ClusterServiceServletImpl]
(Cluster-Heartbeat-1:null) POST
http://192.168.6.12:9090/clusterservice
response :true, responding time: 2 ms
2012-04-04 12:24:32,092 INFO [cloud.cluster.ClusterManagerImpl]
(Cluster-Heartbeat-1:null) Management node 2 is detected inactive by
timestamp but is pingable
2012-04-04 12:24:33,565 DEBUG [cloud.cluster.ClusterManagerImpl]
(Cluster-Heartbeat-1:null) Detected management node left, id:2,
nodeIP:192.168.6.12
2012-04-04 12:24:33,565 DEBUG
[cloud.cluster.ClusterServiceServletImpl]
(Cluster-Heartbeat-1:null) Ping at
http://192.168.6.12:9090/clusterservice
2012-04-04 12:24:33,567 DEBUG
[cloud.cluster.ClusterServiceServletHttpHandler]
(Cluster-Listener-1:null) Handle ping request from 24793718363207
2012-04-04 12:24:33,567 DEBUG
[cloud.cluster.ClusterServiceServletImpl]
(Cluster-Heartbeat-1:null) POST
http://192.168.6.12:9090/clusterservice
response :true, responding time: 2 ms
2012-04-04 12:24:33,567 INFO [cloud.cluster.ClusterManagerImpl]
(Cluster-Heartbeat-1:null) Management node 2 is detected inactive by
timestamp but is pingable
2012-04-04 12:24:34,454 DEBUG
[storage.secondary.SecondaryStorageManagerImpl] (secstorage-1:null)
Zone 1 is ready to launch secondary storage VM
2012-04-04 12:24:34,561 DEBUG
[cloud.consoleproxy.ConsoleProxyManagerImpl] (consoleproxy-1:null)
Zone
1 is ready to launch console proxy
2012-04-04 12:24:35,067 DEBUG
[network.router.VirtualNetworkApplianceManagerImpl]
(RouterStatusMonitor-1:null) Found 0 routers.
2012-04-04 12:24:35,091 DEBUG [cloud.cluster.ClusterManagerImpl]
(Cluster-Heartbeat-1:null) Detected management node left, id:2,
nodeIP:192.168.6.12
2012-04-04 12:24:35,091 DEBUG
[cloud.cluster.ClusterServiceServletImpl]
(Cluster-Heartbeat-1:null) Ping at
http://192.168.6.12:9090/clusterservice
2012-04-04 12:24:35,093 DEBUG
[cloud.cluster.ClusterServiceServletHttpHandler]
(Cluster-Listener-1:null) Handle ping request from 24793718363207
2012-04-04 12:24:35,093 DEBUG
[cloud.cluster.ClusterServiceServletImpl]
(Cluster-Heartbeat-1:null) POST
http://192.168.6.12:9090/clusterservice
response :true, responding time: 2 ms
2012-04-04 12:24:35,093 INFO [cloud.cluster.ClusterManagerImpl]
(Cluster-Heartbeat-1:null) Management node 2 is detected inactive by
timestamp but is pingable
2012-04-04 12:24:36,587 DEBUG [cloud.cluster.ClusterManagerImpl]
(Cluster-Heartbeat-1:null) Detected management node left, id:2,
nodeIP:192.168.6.12
2012-04-04 12:24:36,587 DEBUG
[cloud.cluster.ClusterServiceServletImpl]
(Cluster-Heartbeat-1:null) Ping at
http://192.168.6.12:9090/clusterservice
2012-04-04 12:24:36,589 DEBUG
[cloud.cluster.ClusterServiceServletHttpHandler]
(Cluster-Listener-1:null) Handle ping request from 24793718363207
2012-04-04 12:24:36,589 DEBUG
[cloud.cluster.ClusterServiceServletImpl]
(Cluster-Heartbeat-1:null) POST
http://192.168.6.12:9090/clusterservice
response :true, responding time: 2 ms
2012-04-04 12:24:36,589 INFO [cloud.cluster.ClusterManagerImpl]
(Cluster-Heartbeat-1:null) Management node 2 is detected inactive by
timestamp but is pingable
2012-04-04 12:24:38,094 DEBUG [cloud.cluster.ClusterManagerImpl]
(Cluster-Heartbeat-1:null) Detected management node left, id:2,
nodeIP:192.168.6.12
2012-04-04 12:24:38,094 DEBUG
[cloud.cluster.ClusterServiceServletImpl]
(Cluster-Heartbeat-1:null) Ping at
http://192.168.6.12:9090/clusterservice
2012-04-04 12:24:38,096 DEBUG
[cloud.cluster.ClusterServiceServletHttpHandler]
(Cluster-Listener-1:null) Handle ping request from 24793718363207
2012-04-04 12:24:38,096 DEBUG
[cloud.cluster.ClusterServiceServletImpl]
(Cluster-Heartbeat-1:null) POST
http://192.168.6.12:9090/clusterservice
response :true, responding time: 2 ms
2012-04-04 12:24:38,096 INFO [cloud.cluster.ClusterManagerImpl]
(Cluster-Heartbeat-1:null) Management node 2 is detected inactive by
timestamp but is pingable
2012-04-04 12:24:39,609 DEBUG [cloud.cluster.ClusterManagerImpl]
(Cluster-Heartbeat-1:null) Detected management node left, id:2,
nodeIP:192.168.6.12
2012-04-04 12:24:39,609 DEBUG
[cloud.cluster.ClusterServiceServletImpl]
(Cluster-Heartbeat-1:null) Ping at
http://192.168.6.12:9090/clusterservice
2012-04-04 12:24:39,611 DEBUG
[cloud.cluster.ClusterServiceServletHttpHandler]
(Cluster-Listener-1:null) Handle ping request from 24793718363207