4 min delay in deploying a charm on LXC (and BTRFS)

[Jasper Aikema]

Hi all,

Is there someone who does not what happens when you deploy a juju charm?

I want to speed up the process of deploy a charm on a LXC container (by
cloning a BTRFS subvolume). This does work fine, but when I deploy a
charm a have a delay of 4 minutes, and I don't know why.

This is my debug-log when I did deploy owncloud:

<start of debug log>
2013-07-11 07:36:22,175 unit:wordpress/0: unit.hook.api INFO: Nothing to
configure, since nothing is installed
2013-07-11 07:36:22,205 unit:wordpress/0: hook.output DEBUG: hook
config-changed exited, exit code 0.
2013-07-11 07:36:22,268 unit:wordpress/0: hook.executor DEBUG: Hook
complete: /var/lib/juju/units/wordpress-0/charm/hooks/config-changed
2013-07-11 07:36:22,269 unit:wordpress/0: unit.lifecycle DEBUG:
configured unit
2013-07-11 07:36:22,387 unit:wordpress/0: statemachine DEBUG:
unitworkflowstate: transition complete configure (state started) {}
2013-07-11 07:36:22,414 unit:wordpress/0: juju.agents.unit INFO: No
upgrade flag set.
2013-07-11 08:04:00,843 Machine:0: juju.agents.machine DEBUG: Units
changed old:set(['wordpress/0']) new:set(['owncloud/0', 'wordpress/0'])
2013-07-11 08:04:00,844 Machine:0: juju.agents.machine DEBUG: Starting
service unit: owncloud/0 ...
2013-07-11 08:04:00,912 Machine:0: unit.deploy DEBUG: Downloading charm
cs:precise/owncloud-6 to /home/jasper/juju/jasper-lxc/charms
2013-07-11 08:04:00,958 Machine:0: unit.deploy DEBUG: Using
<juju.machine.unit.UnitContainerDeployment object at 0x1b20b50> for
owncloud/0 in /home/jasper/juju/jasper-lxc
2013-07-11 08:04:01,021 Machine:0: unit.deploy DEBUG: Starting service
unit owncloud/0...
2013-07-11 08:04:01,064 Machine:0: unit.deploy INFO: Creating container
owncloud-0...
2013-07-11 08:04:01,647 Machine:0: unit.deploy INFO: Created container
jasper-lxc-owncloud-0
2013-07-11 08:04:01,662 Machine:0: unit.deploy DEBUG: Charm extracted
into container
2013-07-11 08:04:01,662 Machine:0: unit.deploy DEBUG: Starting container...
2013-07-11 08:04:01,887 Machine:0: unit.deploy INFO: Started container
for owncloud/0
2013-07-11 08:04:01,888 Machine:0: unit.deploy INFO: Started service
unit owncloud/0
<here is the delay of the log>
2013-07-11 08:07:13,223 unit:owncloud/0: hook.executor DEBUG: started
2013-07-11 08:07:13,319 unit:owncloud/0: statemachine DEBUG:
unitworkflowstate: transition install (None -> installed) {}
2013-07-11 08:07:13,319 unit:owncloud/0: statemachine DEBUG:
unitworkflowstate:  execute action do_install
2013-07-11 08:07:13,426 unit:owncloud/0: hook.output DEBUG: Cached
relation hook contexts: []
2013-07-11 08:07:13,426 unit:owncloud/0: hook.executor DEBUG: Running
hook: /var/lib/juju/units/owncloud-0/charm/hooks/install
2013-07-11 08:07:13,610 unit:owncloud/0: unit.hook.api INFO: Installing
all components
2013-07-11 08:07:15,002 unit:owncloud/0: hook.output ERROR: gpg: keyring
`/tmp/tmpctTBex/secring.gpg' created
<end of debug log>

It seams the LXC container is created and started. But what does happen
then? The initial deployment of the container (like copying the files)
is already done. Maybe it is starting the container, but that should not
take more than 4 minutes.

Is there someone who has an idea?

Kind regards,

Jasper Aikema