[one-users] State "UNKNOWN"

Fri May 14 00:54:19 PDT 2010

Hi all:

When i attempt to deploy a VM, his final state is "unknown":

This is the vm.log file:

Fri May 14 09:38:39 2010 [DiM][I]: New VM state is ACTIVE.
Fri May 14 09:38:39 2010 [LCM][I]: New VM state is PROLOG.
Fri May 14 09:38:39 2010 [VM][I]: Virtual Machine has no context
Fri May 14 09:38:42 2010 [TM][I]: tm_clone.sh: 
cesganuba-1c:/srv/cloud/images/pru2-centos-5-4.img 
84.21.169.153:/srv/cloud/one/var//19/images/disk.0
Fri May 14 09:38:42 2010 [TM][I]: tm_clone.sh: DST: 
/srv/cloud/one/var//19/images/disk.0
Fri May 14 09:38:42 2010 [TM][I]: tm_clone.sh: Creating directory 
/srv/cloud/one/var//19/images
Fri May 14 09:38:42 2010 [TM][I]: tm_clone.sh: Executed "mkdir -p 
/srv/cloud/one/var//19/images".
Fri May 14 09:38:42 2010 [TM][I]: tm_clone.sh: Executed "chmod a+w 
/srv/cloud/one/var//19/images".
Fri May 14 09:38:42 2010 [TM][I]: tm_clone.sh: Cloning 
/srv/cloud/images/pru2-centos-5-4.img
Fri May 14 09:38:42 2010 [TM][I]: tm_clone.sh: Executed "cp 
/srv/cloud/images/pru2-centos-5-4.img /srv/cloud/one/var//19/images/disk.0".
Fri May 14 09:38:42 2010 [TM][I]: tm_clone.sh: Executed "chmod a+w 
/srv/cloud/one/var//19/images/disk.0".
Fri May 14 09:43:53 2010 [LCM][I]: New VM state is BOOT
Fri May 14 09:43:53 2010 [VMM][I]: Generating deployment file: 
/srv/cloud/one/var/19/deployment.0
Fri May 14 09:43:56 2010 [LCM][I]: New VM state is RUNNING
Fri May 14 09:44:26 2010 [VMM][I]: VM running but it was not found. 
Restart and delete actions available or try to recover it manually
Fri May 14 09:44:26 2010 [LCM][I]: New VM state is UNKNOWN

And this the oned.log file:

[....]
Fri May 14 09:38:39 2010 [ReM][D]: VirtualMachineDeploy invoked
Fri May 14 09:38:39 2010 [DiM][D]: Deploying VM 19
Fri May 14 09:38:42 2010 [TM][D]: Message received: LOG - 19 
tm_clone.sh: cesganuba-1c:/srv/cloud/images/pru2-centos-5-4.img 
84.21.169.153:/srv/cloud/one/var//19/images/disk.0

Fri May 14 09:38:42 2010 [TM][D]: Message received: LOG - 19 
tm_clone.sh: DST: /srv/cloud/one/var//19/images/disk.0

Fri May 14 09:38:42 2010 [TM][D]: Message received: LOG - 19 
tm_clone.sh: Creating directory /srv/cloud/one/var//19/images

Fri May 14 09:38:42 2010 [TM][D]: Message received: LOG - 19 
tm_clone.sh: Executed "mkdir -p /srv/cloud/one/var//19/images".

Fri May 14 09:38:42 2010 [TM][D]: Message received: LOG - 19 
tm_clone.sh: Executed "chmod a+w /srv/cloud/one/var//19/images".

Fri May 14 09:38:42 2010 [TM][D]: Message received: LOG - 19 
tm_clone.sh: Cloning /srv/cloud/images/pru2-centos-5-4.img

Fri May 14 09:38:42 2010 [TM][D]: Message received: LOG - 19 
tm_clone.sh: Executed "cp /srv/cloud/images/pru2-centos-5-4.img 
/srv/cloud/one/var//19/images/disk.0".

Fri May 14 09:38:42 2010 [TM][D]: Message received: LOG - 19 
tm_clone.sh: Executed "chmod a+w /srv/cloud/one/var//19/images/disk.0".

Fri May 14 09:38:42 2010 [TM][D]: Message received: TRANSFER SUCCESS 19 -

Fri May 14 09:38:43 2010 [InM][D]: Host 1 successfully monitored.
Fri May 14 09:39:20 2010 [ReM][D]: VirtualMachineDeploy invoked
Fri May 14 09:40:03 2010 [ReM][D]: HostPoolInfo method invoked
Fri May 14 09:42:23 2010 [ReM][D]: HostPoolInfo method invoked
Fri May 14 09:43:54 2010 [InM][I]: Monitoring host 84.21.169.153 (1)
Fri May 14 09:43:54 2010 [InM][I]: Monitoring host 84.21.169.69 (3)
Fri May 14 09:43:55 2010 [VMM][D]: Message received: DEPLOY SUCCESS 19 
one-19

/tmp/one-im//one_im-0ed949a4bd6f9d3a8e845559ac44e11f; 
/tmp/one-im//one_im-0ed949a4bd6f9d3a8e845559ac44e11f'
Fri May 14 09:43:56 2010 [InM][I]: STDERR follows.
Fri May 14 09:43:56 2010 [InM][I]: /usr/bin/env: ruby: No such file or 
directory
Fri May 14 09:43:56 2010 [InM][I]: ExitCode: 127
Fri May 14 09:43:56 2010 [InM][D]: Host 3 successfully monitored.
Fri May 14 09:43:59 2010 [InM][D]: Host 1 successfully monitored.
Fri May 14 09:44:23 2010 [VMM][I]: Monitoring VM 19.
Fri May 14 09:44:26 2010 [VMM][D]: Message received: POLL SUCCESS 19 STATE=d

Fri May 14 09:44:45 2010 [ReM][D]: HostPoolInfo method invoked
Fri May 14 09:44:54 2010 [ReM][D]: VirtualMachinePoolInfo method invoked
Fri May 14 09:45:23 2010 [VMM][I]: Monitoring VM 19.
Fri May 14 09:45:25 2010 [InM][I]: Monitoring host 84.21.169.153 (1)
Fri May 14 09:45:25 2010 [InM][I]: Monitoring host 84.21.169.69 (3)
Fri May 14 09:45:26 2010 [VMM][D]: Message received: POLL SUCCESS 19 STATE=d

/tmp/one-im//one_im-0ed949a4bd6f9d3a8e845559ac44e11f; 
/tmp/one-im//one_im-0ed949a4bd6f9d3a8e845559ac44e11f'
Fri May 14 09:45:27 2010 [InM][I]: STDERR follows.
Fri May 14 09:45:27 2010 [InM][I]: /usr/bin/env: ruby: No such file or 
directory
Fri May 14 09:45:27 2010 [InM][I]: ExitCode: 127
Fri May 14 09:45:27 2010 [InM][D]: Host 3 successfully monitored.
Fri May 14 09:45:30 2010 [InM][D]: Host 1 successfully monitored.
Fri May 14 09:46:23 2010 [VMM][I]: Monitoring VM 19.
Fri May 14 09:46:26 2010 [VMM][D]: Message received: POLL SUCCESS 19 STATE=d

Fri May 14 09:46:55 2010 [InM][I]: Monitoring host 84.21.169.153 (1)
Fri May 14 09:46:55 2010 [InM][I]: Monitoring host 84.21.169.69 (3)
/tmp/one-im//one_im-0ed949a4bd6f9d3a8e845559ac44e11f; 
/tmp/one-im//one_im-0ed949a4bd6f9d3a8e845559ac44e11f'
Fri May 14 09:46:57 2010 [InM][I]: STDERR follows.
Fri May 14 09:46:57 2010 [InM][I]: /usr/bin/env: ruby: No such file or 
directory
Fri May 14 09:46:57 2010 [InM][I]: ExitCode: 127
Fri May 14 09:46:57 2010 [InM][D]: Host 3 successfully monitored.
Fri May 14 09:46:59 2010 [InM][D]: Host 1 successfully monitored.
Fri May 14 09:47:23 2010 [VMM][I]: Monitoring VM 19.
Fri May 14 09:47:26 2010 [VMM][D]: Message received: POLL SUCCESS 19 STATE=d

Fri May 14 09:47:53 2010 [VMM][I]: --Mark--
Fri May 14 09:47:55 2010 [InM][I]: --Mark--
Fri May 14 09:48:23 2010 [VMM][I]: Monitoring VM 19.
Fri May 14 09:48:25 2010 [InM][I]: Monitoring host 84.21.169.153 (1)
Fri May 14 09:48:25 2010 [InM][I]: Monitoring host 84.21.169.69 (3)
Fri May 14 09:48:26 2010 [VMM][D]: Message received: POLL SUCCESS 19 STATE=d

/tmp/one-im//one_im-0ed949a4bd6f9d3a8e845559ac44e11f; 
/tmp/one-im//one_im-0ed949a4bd6f9d3a8e845559ac44e11f'
Fri May 14 09:48:27 2010 [InM][I]: STDERR follows.
Fri May 14 09:48:27 2010 [InM][I]: /usr/bin/env: ruby: No such file or 
directory
Fri May 14 09:48:27 2010 [InM][I]: ExitCode: 127
Fri May 14 09:48:27 2010 [InM][D]: Host 3 successfully monitored.
Fri May 14 09:48:29 2010 [InM][D]: Host 1 successfully monitored.
Fri May 14 09:49:23 2010 [VMM][I]: Monitoring VM 19.
Fri May 14 09:49:26 2010 [VMM][D]: Message received: POLL SUCCESS 19 STATE=d

Fri May 14 09:49:55 2010 [InM][I]: Monitoring host 84.21.169.153 (1)
Fri May 14 09:49:55 2010 [InM][I]: Monitoring host 84.21.169.69 (3)
/tmp/one-im//one_im-0ed949a4bd6f9d3a8e845559ac44e11f; 
/tmp/one-im//one_im-0ed949a4bd6f9d3a8e845559ac44e11f'
Fri May 14 09:49:57 2010 [InM][I]: STDERR follows.
Fri May 14 09:49:57 2010 [InM][I]: /usr/bin/env: ruby: No such file or 
directory
Fri May 14 09:49:57 2010 [InM][I]: ExitCode: 127
Fri May 14 09:49:57 2010 [InM][D]: Host 3 successfully monitored.
Fri May 14 09:49:59 2010 [InM][D]: Host 1 successfully monitored.
Fri May 14 09:50:23 2010 [VMM][I]: Monitoring VM 19.
Fri May 14 09:50:26 2010 [VMM][D]: Message received: POLL SUCCESS 19 STATE=d

By the Way, the command "usr/bin/env" exits....

Any idea about this issue???

Regards