Wed Feb  6 10:02:54 2013 [ONE][I]: Starting OpenNebula 3.8.3
----------------------------------------
     OpenNebula Configuration File      
----------------------------------------
AUTH_MAD=AUTHN=ssh,x509,ldap,server_cipher,server_x509,EXECUTABLE=one_auth_mad
DATASTORE_LOCATION=/var/lib/one//datastores
DATASTORE_MAD=ARGUMENTS=-t 15 -d fs,vmware,vmfs,iscsi,lvm,EXECUTABLE=one_datastore
DB=BACKEND=sqlite
DEBUG_LEVEL=3
DEFAULT_DEVICE_PREFIX=hd
DEFAULT_IMAGE_TYPE=OS
ENABLE_OTHER_PERMISSIONS=YES
HM_MAD=EXECUTABLE=one_hm
HOST_MONITORING_EXPIRATION_TIME=86400
HOST_MONITORING_INTERVAL=600
HOST_PER_INTERVAL=15
IMAGE_RESTRICTED_ATTR=SOURCE
IM_MAD=ARGUMENTS=-r 0 -t 15 kvm,EXECUTABLE=one_im_ssh,NAME=im_kvm
MAC_PREFIX=02:00
MANAGER_TIMER=15
NETWORK_SIZE=254
PORT=2633
SCRIPTS_REMOTE_DIR=/var/tmp/one
SESSION_EXPIRATION_TIME=900
TM_MAD=ARGUMENTS=-t 15 -d dummy,lvm,shared,qcow2,ssh,vmfs,iscsi,EXECUTABLE=one_tm
VM_MAD=ARGUMENTS=-t 15 -r 0 kvm,DEFAULT=vmm_exec/vmm_exec_kvm.conf,EXECUTABLE=one_vmm_exec,NAME=vmm_kvm,TYPE=kvm
VM_MONITORING_EXPIRATION_TIME=86400
VM_PER_INTERVAL=5
VM_POLLING_INTERVAL=600
VM_RESTRICTED_ATTR=CONTEXT/FILES
VM_RESTRICTED_ATTR=NIC/MAC
VM_RESTRICTED_ATTR=NIC/VLAN_ID
VM_RESTRICTED_ATTR=RANK
VNC_BASE_PORT=5900
----------------------------------------
Wed Feb  6 10:02:54 2013 [ONE][I]: Log level:3 [0=ERROR,1=WARNING,2=INFO,3=DEBUG]
Wed Feb  6 10:02:54 2013 [ONE][I]: Checking database version.
Wed Feb  6 10:02:54 2013 [VMM][I]: Starting Virtual Machine Manager...
Wed Feb  6 10:02:54 2013 [LCM][I]: Starting Life-cycle Manager...
Wed Feb  6 10:02:54 2013 [VMM][I]: Virtual Machine Manager started.
Wed Feb  6 10:02:54 2013 [LCM][I]: Life-cycle Manager started.
Wed Feb  6 10:02:54 2013 [InM][I]: Starting Information Manager...
Wed Feb  6 10:02:54 2013 [InM][I]: Information Manager started.
Wed Feb  6 10:02:54 2013 [TrM][I]: Starting Transfer Manager...
Wed Feb  6 10:02:54 2013 [TrM][I]: Transfer Manager started.
Wed Feb  6 10:02:54 2013 [DiM][I]: Starting Dispatch Manager...
Wed Feb  6 10:02:54 2013 [DiM][I]: Dispatch Manager started.
Wed Feb  6 10:02:54 2013 [HKM][I]: Starting Hook Manager...
Wed Feb  6 10:02:54 2013 [AuM][I]: Starting Auth Manager...
Wed Feb  6 10:02:54 2013 [HKM][I]: Hook Manager started.
Wed Feb  6 10:02:54 2013 [AuM][I]: Authorization Manager started.
Wed Feb  6 10:02:54 2013 [ImM][I]: Starting Image Manager...
Wed Feb  6 10:02:54 2013 [ImM][I]: Image Manager started.
Wed Feb  6 10:02:54 2013 [ReM][I]: Starting Request Manager...
Wed Feb  6 10:02:54 2013 [ReM][I]: Starting XML-RPC server, port 2633 ...
Wed Feb  6 10:02:54 2013 [ReM][I]: Request Manager started.
Wed Feb  6 10:02:56 2013 [VMM][I]: Loading Virtual Machine Manager drivers.
Wed Feb  6 10:02:56 2013 [VMM][I]: 	Loading driver: vmm_kvm (KVM)
Wed Feb  6 10:02:58 2013 [VMM][I]: 	Driver vmm_kvm loaded.
Wed Feb  6 10:02:58 2013 [InM][I]: Loading Information Manager drivers.
Wed Feb  6 10:02:58 2013 [InM][I]: 	Loading driver: im_kvm
Wed Feb  6 10:02:59 2013 [InM][I]: 	Driver im_kvm loaded
Wed Feb  6 10:02:59 2013 [TM][I]: Loading Transfer Manager driver.
Wed Feb  6 10:02:59 2013 [TM][I]: 	Transfer manager driver loaded
Wed Feb  6 10:02:59 2013 [HKM][I]: Loading Hook Manager driver.
Wed Feb  6 10:03:00 2013 [HKM][I]: 	Hook Manager loaded
Wed Feb  6 10:03:00 2013 [ImM][I]: Loading Image Manager driver.
Wed Feb  6 10:03:00 2013 [ImM][I]: 	Image Manager loaded
Wed Feb  6 10:03:00 2013 [AuM][I]: Loading Auth. Manager driver.
Wed Feb  6 10:03:00 2013 [AuM][I]: 	Auth Manager loaded
Wed Feb  6 10:03:01 2013 [AuM][D]: Message received: LOG I 0 ExitCode: 0

Wed Feb  6 10:03:01 2013 [AuM][I]: ExitCode: 0
Wed Feb  6 10:03:01 2013 [AuM][D]: Message received: AUTHENTICATE SUCCESS 0 -

Wed Feb  6 10:03:01 2013 [ReM][D]: Req:1920 UID:1 UserPoolInfo invoked
Wed Feb  6 10:03:01 2013 [ReM][D]: Req:1920 UID:1 UserPoolInfo result SUCCESS, "<USER_POOL><USER><ID..."
Wed Feb  6 10:03:08 2013 [InM][I]: Monitoring host host1 (2)
Wed Feb  6 10:03:09 2013 [InM][I]: Monitoring host host2 (3)
Wed Feb  6 10:03:09 2013 [InM][I]: Monitoring host oneadmin (4)
Wed Feb  6 10:03:09 2013 [InM][I]: Command execution fail: 'if [ -x "/var/tmp/one/im/run_probes" ]; then /var/tmp/one/im/run_probes kvm 2 host1; else                              exit 42; fi'
Wed Feb  6 10:03:09 2013 [InM][I]: Command execution fail: 'if [ -x "/var/tmp/one/im/run_probes" ]; then /var/tmp/one/im/run_probes kvm 3 host2; else                              exit 42; fi'
Wed Feb  6 10:03:09 2013 [InM][I]: error: Failed to connect socket to '/var/run/libvirt/libvirt-sock': No such file or directory
Wed Feb  6 10:03:09 2013 [InM][I]: error: Failed to connect socket to '/var/run/libvirt/libvirt-sock': No such file or directory
Wed Feb  6 10:03:09 2013 [InM][I]: error: failed to connect to the hypervisor
Wed Feb  6 10:03:09 2013 [InM][I]: error: failed to connect to the hypervisor
Wed Feb  6 10:03:09 2013 [InM][E]: Error executing kvm.rb
Wed Feb  6 10:03:09 2013 [InM][E]: Error executing kvm.rb
Wed Feb  6 10:03:09 2013 [InM][I]: ExitCode: 255
Wed Feb  6 10:03:09 2013 [InM][I]: ExitCode: 255
Wed Feb  6 10:03:09 2013 [InM][E]: Error monitoring host 2 : MONITOR FAILURE 2 Error executing kvm.rb

Wed Feb  6 10:03:10 2013 [InM][E]: Error monitoring host 3 : MONITOR FAILURE 3 Error executing kvm.rb

Wed Feb  6 10:03:10 2013 [InM][I]: Command execution fail: 'if [ -x "/var/tmp/one/im/run_probes" ]; then /var/tmp/one/im/run_probes kvm 4 oneadmin; else                              exit 42; fi'
Wed Feb  6 10:03:10 2013 [InM][I]: ssh: Could not resolve hostname oneadmin: Name or service not known
Wed Feb  6 10:03:10 2013 [InM][I]: ExitCode: 255
Wed Feb  6 10:03:10 2013 [InM][E]: Error monitoring host 4 : MONITOR FAILURE 4 -

Wed Feb  6 10:03:23 2013 [ReM][D]: Req:7440 UID:0 HostPoolInfo invoked
Wed Feb  6 10:03:23 2013 [ReM][D]: Req:7440 UID:0 HostPoolInfo result SUCCESS, "<HOST_POOL><HOST><ID..."
Wed Feb  6 10:03:23 2013 [ReM][D]: Req:7680 UID:0 VirtualMachinePoolInfo invoked, -2, -1, -1, -1
Wed Feb  6 10:03:23 2013 [ReM][D]: Req:7680 UID:0 VirtualMachinePoolInfo result SUCCESS, "<VM_POOL><VM><ID>1</..."
Wed Feb  6 10:03:23 2013 [ReM][D]: Req:8160 UID:0 AclInfo invoked
Wed Feb  6 10:03:23 2013 [ReM][D]: Req:8160 UID:0 AclInfo result SUCCESS, "<ACL_POOL><ACL><ID>0..."
Wed Feb  6 10:03:52 2013 [ReM][D]: Req:4592 UID:0 HostPoolInfo invoked
Wed Feb  6 10:03:52 2013 [ReM][D]: Req:4592 UID:0 HostPoolInfo result SUCCESS, "<HOST_POOL><HOST><ID..."
Wed Feb  6 10:03:52 2013 [ReM][D]: Req:224 UID:0 VirtualMachinePoolInfo invoked, -2, -1, -1, -1
Wed Feb  6 10:03:52 2013 [ReM][D]: Req:224 UID:0 VirtualMachinePoolInfo result SUCCESS, "<VM_POOL><VM><ID>1</..."
Wed Feb  6 10:03:52 2013 [ReM][D]: Req:9488 UID:0 AclInfo invoked
Wed Feb  6 10:03:52 2013 [ReM][D]: Req:9488 UID:0 AclInfo result SUCCESS, "<ACL_POOL><ACL><ID>0..."
Wed Feb  6 10:04:21 2013 [ReM][D]: Req:928 UID:0 HostPoolInfo invoked
Wed Feb  6 10:04:21 2013 [ReM][D]: Req:928 UID:0 HostPoolInfo result SUCCESS, "<HOST_POOL><HOST><ID..."
Wed Feb  6 10:04:21 2013 [ReM][D]: Req:3216 UID:0 VirtualMachinePoolInfo invoked, -2, -1, -1, -1
Wed Feb  6 10:04:21 2013 [ReM][D]: Req:3216 UID:0 VirtualMachinePoolInfo result SUCCESS, "<VM_POOL><VM><ID>1</..."
Wed Feb  6 10:04:22 2013 [ReM][D]: Req:9648 UID:0 AclInfo invoked
Wed Feb  6 10:04:22 2013 [ReM][D]: Req:9648 UID:0 AclInfo result SUCCESS, "<ACL_POOL><ACL><ID>0..."
Wed Feb  6 10:04:51 2013 [ReM][D]: Req:2592 UID:0 HostPoolInfo invoked
Wed Feb  6 10:04:51 2013 [ReM][D]: Req:2592 UID:0 HostPoolInfo result SUCCESS, "<HOST_POOL><HOST><ID..."
Wed Feb  6 10:04:51 2013 [ReM][D]: Req:6224 UID:0 VirtualMachinePoolInfo invoked, -2, -1, -1, -1
Wed Feb  6 10:04:51 2013 [ReM][D]: Req:6224 UID:0 VirtualMachinePoolInfo result SUCCESS, "<VM_POOL><VM><ID>1</..."
Wed Feb  6 10:04:52 2013 [ReM][D]: Req:6560 UID:0 AclInfo invoked
Wed Feb  6 10:04:52 2013 [ReM][D]: Req:6560 UID:0 AclInfo result SUCCESS, "<ACL_POOL><ACL><ID>0..."
Wed Feb  6 10:05:21 2013 [ReM][D]: Req:7936 UID:0 HostPoolInfo invoked
Wed Feb  6 10:05:21 2013 [ReM][D]: Req:7936 UID:0 HostPoolInfo result SUCCESS, "<HOST_POOL><HOST><ID..."
Wed Feb  6 10:05:21 2013 [ReM][D]: Req:8192 UID:0 VirtualMachinePoolInfo invoked, -2, -1, -1, -1
Wed Feb  6 10:05:21 2013 [ReM][D]: Req:8192 UID:0 VirtualMachinePoolInfo result SUCCESS, "<VM_POOL><VM><ID>1</..."
Wed Feb  6 10:05:22 2013 [ReM][D]: Req:4928 UID:0 AclInfo invoked
Wed Feb  6 10:05:22 2013 [ReM][D]: Req:4928 UID:0 AclInfo result SUCCESS, "<ACL_POOL><ACL><ID>0..."
Wed Feb  6 10:05:51 2013 [ReM][D]: Req:2720 UID:0 HostPoolInfo invoked
Wed Feb  6 10:05:51 2013 [ReM][D]: Req:2720 UID:0 HostPoolInfo result SUCCESS, "<HOST_POOL><HOST><ID..."
Wed Feb  6 10:05:51 2013 [ReM][D]: Req:1840 UID:0 VirtualMachinePoolInfo invoked, -2, -1, -1, -1
Wed Feb  6 10:05:51 2013 [ReM][D]: Req:1840 UID:0 VirtualMachinePoolInfo result SUCCESS, "<VM_POOL><VM><ID>1</..."
Wed Feb  6 10:05:52 2013 [ReM][D]: Req:5872 UID:0 AclInfo invoked
Wed Feb  6 10:05:52 2013 [ReM][D]: Req:5872 UID:0 AclInfo result SUCCESS, "<ACL_POOL><ACL><ID>0..."
Wed Feb  6 10:06:21 2013 [ReM][D]: Req:9152 UID:0 HostPoolInfo invoked
Wed Feb  6 10:06:21 2013 [ReM][D]: Req:9152 UID:0 HostPoolInfo result SUCCESS, "<HOST_POOL><HOST><ID..."
Wed Feb  6 10:06:21 2013 [ReM][D]: Req:6544 UID:0 VirtualMachinePoolInfo invoked, -2, -1, -1, -1
Wed Feb  6 10:06:21 2013 [ReM][D]: Req:6544 UID:0 VirtualMachinePoolInfo result SUCCESS, "<VM_POOL><VM><ID>1</..."
Wed Feb  6 10:06:22 2013 [ReM][D]: Req:1408 UID:0 AclInfo invoked
Wed Feb  6 10:06:22 2013 [ReM][D]: Req:1408 UID:0 AclInfo result SUCCESS, "<ACL_POOL><ACL><ID>0..."
Wed Feb  6 10:06:51 2013 [ReM][D]: Req:9744 UID:0 HostPoolInfo invoked
Wed Feb  6 10:06:51 2013 [ReM][D]: Req:9744 UID:0 HostPoolInfo result SUCCESS, "<HOST_POOL><HOST><ID..."
Wed Feb  6 10:06:51 2013 [ReM][D]: Req:8768 UID:0 VirtualMachinePoolInfo invoked, -2, -1, -1, -1
Wed Feb  6 10:06:51 2013 [ReM][D]: Req:8768 UID:0 VirtualMachinePoolInfo result SUCCESS, "<VM_POOL><VM><ID>1</..."
Wed Feb  6 10:06:52 2013 [ReM][D]: Req:2240 UID:0 AclInfo invoked
Wed Feb  6 10:06:52 2013 [ReM][D]: Req:2240 UID:0 AclInfo result SUCCESS, "<ACL_POOL><ACL><ID>0..."
Wed Feb  6 10:07:21 2013 [ReM][D]: Req:448 UID:0 HostPoolInfo invoked
Wed Feb  6 10:07:21 2013 [ReM][D]: Req:448 UID:0 HostPoolInfo result SUCCESS, "<HOST_POOL><HOST><ID..."
Wed Feb  6 10:07:21 2013 [ReM][D]: Req:1648 UID:0 VirtualMachinePoolInfo invoked, -2, -1, -1, -1
Wed Feb  6 10:07:21 2013 [ReM][D]: Req:1648 UID:0 VirtualMachinePoolInfo result SUCCESS, "<VM_POOL><VM><ID>1</..."
Wed Feb  6 10:07:21 2013 [ReM][D]: Req:3376 UID:0 AclInfo invoked
Wed Feb  6 10:07:21 2013 [ReM][D]: Req:3376 UID:0 AclInfo result SUCCESS, "<ACL_POOL><ACL><ID>0..."