[one-users] new VM's never leaving pending state

[Jurgen Weber]

Hi

My System:
# uname -a
Linux chaos 3.2.0-3-amd64 #1 SMP Mon Jul 23 02:45:17 UTC 2012 x86_64 
GNU/Linux


ii  opennebula                         3.4.1-3.1 amd64        controller 
which executes the OpenNebula cluster services
ii  opennebula-common                  3.4.1-3.1 all          empty 
package to create OpenNebula users and directories
ii  opennebula-sunstone                3.4.1-3.1 all          web 
interface to which executes the OpenNebula cluster services
ii  opennebula-tools                   3.4.1-3.1 all          
Command-line tools for OpenNebula Cloud
ii  ruby-opennebula                    3.4.1-3.1 all          Ruby 
bindings for OpenNebula Cloud API (OCA)

I ran an apt-get update/upgrade on this machine and it updated without 
issue... I am not sure if that is related to this issue thou it just 
happened aroudn the same time.

I am now attempting to create a new VM and it does not start in sunstone 
it displays as pending forever. In /var/log/one/sched.log You see the 
following every minute:

Tue Sep 11 10:22:36 2012 [HOST][D]: Discovered Hosts (enabled): 2
Tue Sep 11 10:22:36 2012 [VM][D]: Pending virtual machines : 101
Tue Sep 11 10:22:36 2012 [SCHED][I]: Select hosts
     PRI    HID
     -------------------
Virtual Machine: 101

Lastly when I restarted opennebula I found the following in the oned.log

Tue Sep 11 09:18:20 2012 [ONE][I]: Log level:3 
[0=ERROR,1=WARNING,2=INFO,3=DEBUG]
Tue Sep 11 09:18:20 2012 [ONE][I]: Checking database version.
Tue Sep 11 09:18:20 2012 [VMM][I]: Starting Virtual Machine Manager...
Tue Sep 11 09:18:20 2012 [LCM][I]: Starting Life-cycle Manager...
Tue Sep 11 09:18:20 2012 [VMM][I]: Virtual Machine Manager started.
Tue Sep 11 09:18:20 2012 [LCM][I]: Life-cycle Manager started.
Tue Sep 11 09:18:20 2012 [InM][I]: Starting Information Manager...
Tue Sep 11 09:18:20 2012 [InM][I]: Information Manager started.
Tue Sep 11 09:18:20 2012 [TrM][I]: Starting Transfer Manager...
Tue Sep 11 09:18:20 2012 [DiM][I]: Starting Dispatch Manager...
Tue Sep 11 09:18:20 2012 [TrM][I]: Transfer Manager started.
Tue Sep 11 09:18:20 2012 [DiM][I]: Dispatch Manager started.
Tue Sep 11 09:18:20 2012 [HKM][I]: Starting Hook Manager...
Tue Sep 11 09:18:20 2012 [AuM][I]: Starting Auth Manager...
Tue Sep 11 09:18:20 2012 [HKM][I]: Hook Manager started.
Tue Sep 11 09:18:20 2012 [AuM][I]: Authorization Manager started.
Tue Sep 11 09:18:20 2012 [ImM][I]: Starting Image Manager...
Tue Sep 11 09:18:20 2012 [ImM][I]: Image Manager started.
Tue Sep 11 09:18:20 2012 [ReM][I]: Starting Request Manager...
Tue Sep 11 09:18:20 2012 [ReM][I]: Starting XML-RPC server, port 2633 ...
Tue Sep 11 09:18:20 2012 [ReM][I]: Request Manager started.
Tue Sep 11 09:18:21 2012 [ReM][D]: ClusterPoolInfo method invoked
Tue Sep 11 09:18:21 2012 [AuM][E]: Auth Error: Could not find 
Authorization driver
Tue Sep 11 09:18:21 2012 [ReM][E]: [ClusterPoolInfo] User couldn't be 
authenticated, aborting call.
Tue Sep 11 09:18:21 2012 [ReM][D]: DatastorePoolInfo method invoked
Tue Sep 11 09:18:21 2012 [AuM][E]: Auth Error: Could not find 
Authorization driver
Tue Sep 11 09:18:21 2012 [ReM][E]: [DatastorePoolInfo] User couldn't be 
authenticated, aborting call.
Tue Sep 11 09:18:22 2012 [VMM][I]: Loading Virtual Machine Manager drivers.

is that Auth error an issue? How do I resolve it? I read something about 
ssh, etc but that is all functioning fine.

Thanks

-- 
Jurgen Weber

Systems Engineer
IT Infrastructure Team Leader

THE ICONIC | E jurgen.weber at theiconic.com.au | www.theiconic.com.au