[one-users] Missing log when no error happens

[Daniel Dehennin]

Hello,

I made some search without success, I'm asking here before creating any
redmine issue.

Using OpenNebula 4.2, I just see that there is no TM log on successful
VMs setup:

    oneadmin at yggdrasil:~$ cut -d' ' -f6- /var/log/one/16.log 
    [DiM][I]: New VM state is ACTIVE.
    [LCM][I]: New VM state is PROLOG.
    [LCM][I]: New VM state is BOOT
    [VMM][I]: Generating deployment file: /var/lib/one/vms/16/deployment.0
    [VMM][I]: ExitCode: 0
    [VMM][I]: Successfully execute network driver operation: pre.
    [VMM][I]: ExitCode: 0
    [VMM][I]: Successfully execute virtualization driver operation: deploy.
    [VMM][I]: post: Executed "sudo /usr/bin/ovs-vsctl set Port vnet0 tag=20".
    [VMM][I]: post: Executed "sudo /usr/bin/ovs-ofctl add-flow baby-gnu in_port=5,dl_src=02:00:0a:02:00:00,priority=40000,actions=normal".
    [VMM][I]: post: Executed "sudo /usr/bin/ovs-ofctl add-flow baby-gnu in_port=5,priority=39000,actions=drop".
    [VMM][I]: ExitCode: 0
    [VMM][I]: Successfully execute network driver operation: post.
    [LCM][I]: New VM state is RUNNING

I generate an error to see what's comming in the logs:

    oneadmin at yggdrasil:~$ cut -d' ' -f6- /var/log/one/15.log 
    [DiM][I]: New VM state is ACTIVE.
    [LCM][I]: New VM state is PROLOG.
    [TM][I]: Command execution fail: /var/lib/one/remotes/tm/qcow2/clone yggdrasil:/var/lib/one/datastores/1/e72972d1dc14203d83520f9ae6395d94 yggdrasil:/var/lib/one//datastores/0/15/disk.1 15 1
    [TM][I]: clone: Cloning /var/lib/one/datastores/1/e72972d1dc14203d83520f9ae6395d94 in yggdrasil:/var/lib/one//datastores/0/15/disk.1
    [TM][E]: clone: Command "cd /var/lib/one/datastores/0/15; qemu-img create -b /var/lib/one/datastores/1/e72972d1dc14203d83520f9ae6395d94 -f qcow2 /var/lib/one/datastores/0/15/disk.1" failed: qemu-img: Could not open '/var/lib/one/datastores/1/e72972d1dc14203d83520f9ae6395d94'
    [TM][E]: Error copying yggdrasil:/var/lib/one/datastores/1/e72972d1dc14203d83520f9ae6395d94 to yggdrasil:/var/lib/one//datastores/0/15/disk.1
    [TM][I]: ExitCode: 1
    [TM][E]: Error executing image transfer script: Error copying yggdrasil:/var/lib/one/datastores/1/e72972d1dc14203d83520f9ae6395d94 to yggdrasil:/var/lib/one//datastores/0/15/disk.1
    [DiM][I]: New VM state is FAILED
    [DiM][I]: New VM state is DONE.
    [TM][W]: Ignored: TRANSFER SUCCESS 15 -

My log configuration is:

    LOG = [
      system      = "file",
      debug_level = 3
    ]
 
I think it's a problem, each step should be clearly identified with the
command it runs.

It can be hard to reproduce an error, specially when the user delete the
VM in “FAILED” state and try 2 or 3 times before asking the ONE admin.

For the logs above, I don't know why the clone log[1] are not reported.

Regards.

Footnotes: 
[1]  http://dev.opennebula.org/projects/opennebula/repository/revisions/release-4.2/entry/src/tm_mad/qcow2/clone#L69

-- 
Daniel Dehennin
Récupérer ma clef GPG:
gpg --keyserver pgp.mit.edu --recv-keys 0x7A6FE2DF
-------------- next part --------------
A non-text attachment was scrubbed...
Name: not available
Type: application/pgp-signature
Size: 229 bytes
Desc: not available
URL: <http://lists.opennebula.org/pipermail/users-opennebula.org/attachments/20131030/a17fa111/attachment.pgp>