Public bug reported:
I'm trying to [MAAS 2.1.2] deploy and am hitting intermittent problems,
any help would be appreciated. The system below commissioned fine and
was chosen to bootstrap Juju (2.0.2) but is now failing. I can't login
or ssh in, so the network interface isn't getting provisioned properly
even though it claims it is. Oddly, MAAS thinks the node should have a
different IP [" ... 192.168.1.2 (Auto assign)"].
...
[ OK ] Reached target Network.
[ 58.581413] cloud-init[3350]: Cloud-init v. 0.7.8 running 'init' at Wed, 14
Dec 2016 16:20:18 +0000. Up 55.14 seconds.
[ 58.596442] cloud-init[3350]: ci-info:
+++++++++++++++++++++++++++++++++++++++++Net device
info++++++++++++++++++++++++++++++++++++++++++
[ 58.612404] cloud-init[3350]: ci-info:
+---------------+------+-----------------------------+---------------+-------+-------------------+
[ 58.628393] cloud-init[3350]: ci-info: | Device | Up |
Address | Mask | Scope | Hw-Address |
[ 58.644389] cloud-init[3350]: ci-info:
+---------------+------+-----------------------------+---------------+-------+-------------------+
[ 58.660400] cloud-init[3350]: ci-info: | br-enP2p1s0f1 | True |
192.168.1.7 | 255.255.255.0 | . | 1c:1b:0d:01:d8:00 |
[ 58.676396] cloud-init[3350]: ci-info: | lo | True |
127.0.0.1 | 255.0.0.0 | . | . |
[ 58.692389] cloud-init[3350]: ci-info: | lo | True |
::1/128 | . | host | . |
[ 58.708390] cloud-init[3350]: ci-info: | enP2p1s0f2 | True |
. | . | . | 1c:1b:0d:01:d8:01 |
[ 58.724391] cloud-init[3350]: ci-info: | enP2p1s0f2 | True |
fe80::1e1b:dff:fe01:d801/64 | . | link | 1c:1b:0d:01:d8:01 |
[ 58.740392] cloud-init[3350]: ci-info: | enP2p1s0f1 | True |
. | . | . | 1c:1b:0d:01:d8:00 |
[ 58.756396] cloud-init[3350]: ci-info:
+---------------+------+-----------------------------+---------------+-------+-------------------+
[ 58.772389] cloud-init[3350]: ci-info: +++++++++++++++++++++++++++++++Route
IPv4 info+++++++++++++++++++++++++++++++
[ 58.788390] cloud-init[3350]: ci-info:
+-------+-------------+-------------+---------------+---------------+-------+
[ 58.804393] cloud-init[3350]: ci-info: | Route | Destination | Gateway |
Genmask | Interface | Flags |
[ 58.820398] cloud-init[3350]: ci-info:
+-------+-------------+-------------+---------------+---------------+-------+
[ 58.836392] cloud-init[3350]: ci-info: | 0 | 0.0.0.0 | 192.168.1.1 |
0.0.0.0 | br-enP2p1s0f1 | UG |
[ 58.852391] cloud-init[3350]: ci-info: | 1 | 192.168.1.0 | 0.0.0.0 |
255.255.255.0 | br-enP2p1s0f1 | U |
[ 58.868391] cloud-init[3350]: ci-info:
+-------+-------------+-------------+---------------+---------------+-------+
[ 58.884390] cloud-init[3350]: 2016-12-14 16:20:22,403 -
handlers.py[WARNING]: failed posting event: start: init-network/check-cache:
attempting to read from cache [trust]
...
...
[ 265.585458] cloud-init[3913]: 2016-12-14 16:23:49,407 -
handlers.py[WARNING]: failed posting event: finish:
modules-final/config-package-update-upgrade-install: SUCCESS:
config-package-update-upgrade-install previously ran
[ 268.585382] cloud-init[3913]: 2016-12-14 16:23:52,407 -
handlers.py[WARNING]: failed posting event: start: modules-final/config-fan:
running config-fan with frequency once-per-instance
[ 271.585995] cloud-init[3913]: 2016-12-14 16:23:55,408 -
handlers.py[WARNING]: failed posting event: finish: modules-final/config-fan:
SUCCESS: config-fan previously ran
[ 274.585181] cloud-init[3913]: 2016-12-14 16:23:58,407 -
handlers.py[WARNING]: failed posting event: start:
modules-final/config-landscape: running config-landscape with frequency
once-per-instance
[ 277.585243] cloud-init[3913]: 2016-12-14 16:24:01,407 -
handlers.py[WARNING]: failed posting event: finish:
modules-final/config-landscape: SUCCESS: config-landscape previously ran
[ 280.586888] cloud-init[3913]: 2016-12-14 16:24:04,408 -
handlers.py[WARNING]: failed posting event: start: modules-final/config-lxd:
running config-lxd with frequency once-per-instance
[ 283.586615] cloud-init[3913]: 2016-12-14 16:24:07,408 -
handlers.py[WARNING]: failed posting event: finish: modules-final/config-lxd:
SUCCESS: config-lxd previously ran
[ 286.585808] cloud-init[3913]: 2016-12-14 16:24:10,407 -
handlers.py[WARNING]: failed posting event: start: modules-final/config-puppet:
running config-puppet with frequency once-per-instance
[ 289.585863] cloud-init[3913]: 2016-12-14 16:24:13,407 -
handlers.py[WARNING]: failed posting event: finish:
modules-final/config-puppet: SUCCESS: config-puppet previously ran
[ 292.585137] cloud-init[3913]: 2016-12-14 16:24:16,407 -
handlers.py[WARNING]: failed posting event: start: modules-final/config-chef:
running config-chef with frequency once-per-instance
[ 295.585322] cloud-init[3913]: 2016-12-14 16:24:19,407 -
handlers.py[WARNING]: failed posting event: finish: modules-final/config-chef:
SUCCESS: config-chef previously ran
[ 298.586097] cloud-init[3913]: 2016-12-14 16:24:22,408 -
handlers.py[WARNING]: failed posting event: start:
modules-final/config-salt-minion: running config-salt-minion with frequency
once-per-instance
[ 301.586092] cloud-init[3913]: 2016-12-14 16:24:25,408 -
handlers.py[WARNING]: failed posting event: finish:
modules-final/config-salt-minion: SUCCESS: config-salt-minion previously ran
[ 304.585439] cloud-init[3913]: 2016-12-14 16:24:28,407 -
handlers.py[WARNING]: failed posting event: start:
modules-final/config-mcollective: running config-mcollective with frequency
once-per-instance
[ 307.585243] cloud-init[3913]: 2016-12-14 16:24:31,407 -
handlers.py[WARNING]: failed posting event: finish:
modules-final/config-mcollective: SUCCESS: config-mcollective previously ran
[ 310.586398] cloud-init[3913]: 2016-12-14 16:24:34,408 -
handlers.py[WARNING]: failed posting event: start:
modules-final/config-rightscale_userdata: running config-rightscale_userdata
with frequency once-per-instance
[ 313.585653] cloud-init[3913]: 2016-12-14 16:24:37,407 -
handlers.py[WARNING]: failed posting event: finish:
modules-final/config-rightscale_userdata: SUCCESS: config-rightscale_userdata
previously ran
[ 316.586447] cloud-init[3913]: 2016-12-14 16:24:40,408 -
handlers.py[WARNING]: failed posting event: start:
modules-final/config-scripts-vendor: running config-scripts-vendor with
frequency once-per-instance
[ 319.586427] cloud-init[3913]: 2016-12-14 16:24:43,408 -
handlers.py[WARNING]: failed posting event: finish:
modules-final/config-scripts-vendor: SUCCESS: config-scripts-vendor previously
ran
[ 322.585346] cloud-init[3913]: 2016-12-14 16:24:46,407 -
handlers.py[WARNING]: failed posting event: start:
modules-final/config-scripts-per-once: running config-scripts-per-once with
frequency once
...
** Affects: cloud-init
Importance: Undecided
Status: New
** Affects: maas
Importance: Undecided
Status: Won't Fix
** Attachment added: "maas-failed-posting.tgz"
https://bugs.launchpad.net/bugs/1649951/+attachment/4791645/+files/maas-failed-posting.tgz
** Also affects: maas
Importance: Undecided
Status: New
** No longer affects: cloud-init
--
You received this bug notification because you are a member of Yahoo!
Engineering Team, which is subscribed to cloud-init.
https://bugs.launchpad.net/bugs/1649951
Title:
handlers.py WARNING failed posting event finish SUCCESS
Status in cloud-init:
New
Status in MAAS:
Won't Fix
Bug description:
I'm trying to [MAAS 2.1.2] deploy and am hitting intermittent
problems, any help would be appreciated. The system below
commissioned fine and was chosen to bootstrap Juju (2.0.2) but is now
failing. I can't login or ssh in, so the network interface isn't
getting provisioned properly even though it claims it is. Oddly, MAAS
thinks the node should have a different IP [" ... 192.168.1.2 (Auto
assign)"].
...
[ OK ] Reached target Network.
[ 58.581413] cloud-init[3350]: Cloud-init v. 0.7.8 running 'init' at Wed,
14 Dec 2016 16:20:18 +0000. Up 55.14 seconds.
[ 58.596442] cloud-init[3350]: ci-info:
+++++++++++++++++++++++++++++++++++++++++Net device
info++++++++++++++++++++++++++++++++++++++++++
[ 58.612404] cloud-init[3350]: ci-info:
+---------------+------+-----------------------------+---------------+-------+-------------------+
[ 58.628393] cloud-init[3350]: ci-info: | Device | Up |
Address | Mask | Scope | Hw-Address |
[ 58.644389] cloud-init[3350]: ci-info:
+---------------+------+-----------------------------+---------------+-------+-------------------+
[ 58.660400] cloud-init[3350]: ci-info: | br-enP2p1s0f1 | True |
192.168.1.7 | 255.255.255.0 | . | 1c:1b:0d:01:d8:00 |
[ 58.676396] cloud-init[3350]: ci-info: | lo | True |
127.0.0.1 | 255.0.0.0 | . | . |
[ 58.692389] cloud-init[3350]: ci-info: | lo | True |
::1/128 | . | host | . |
[ 58.708390] cloud-init[3350]: ci-info: | enP2p1s0f2 | True |
. | . | . | 1c:1b:0d:01:d8:01 |
[ 58.724391] cloud-init[3350]: ci-info: | enP2p1s0f2 | True |
fe80::1e1b:dff:fe01:d801/64 | . | link | 1c:1b:0d:01:d8:01 |
[ 58.740392] cloud-init[3350]: ci-info: | enP2p1s0f1 | True |
. | . | . | 1c:1b:0d:01:d8:00 |
[ 58.756396] cloud-init[3350]: ci-info:
+---------------+------+-----------------------------+---------------+-------+-------------------+
[ 58.772389] cloud-init[3350]: ci-info:
+++++++++++++++++++++++++++++++Route IPv4 info+++++++++++++++++++++++++++++++
[ 58.788390] cloud-init[3350]: ci-info:
+-------+-------------+-------------+---------------+---------------+-------+
[ 58.804393] cloud-init[3350]: ci-info: | Route | Destination | Gateway
| Genmask | Interface | Flags |
[ 58.820398] cloud-init[3350]: ci-info:
+-------+-------------+-------------+---------------+---------------+-------+
[ 58.836392] cloud-init[3350]: ci-info: | 0 | 0.0.0.0 | 192.168.1.1
| 0.0.0.0 | br-enP2p1s0f1 | UG |
[ 58.852391] cloud-init[3350]: ci-info: | 1 | 192.168.1.0 | 0.0.0.0
| 255.255.255.0 | br-enP2p1s0f1 | U |
[ 58.868391] cloud-init[3350]: ci-info:
+-------+-------------+-------------+---------------+---------------+-------+
[ 58.884390] cloud-init[3350]: 2016-12-14 16:20:22,403 -
handlers.py[WARNING]: failed posting event: start: init-network/check-cache:
attempting to read from cache [trust]
...
...
[ 265.585458] cloud-init[3913]: 2016-12-14 16:23:49,407 -
handlers.py[WARNING]: failed posting event: finish:
modules-final/config-package-update-upgrade-install: SUCCESS:
config-package-update-upgrade-install previously ran
[ 268.585382] cloud-init[3913]: 2016-12-14 16:23:52,407 -
handlers.py[WARNING]: failed posting event: start: modules-final/config-fan:
running config-fan with frequency once-per-instance
[ 271.585995] cloud-init[3913]: 2016-12-14 16:23:55,408 -
handlers.py[WARNING]: failed posting event: finish: modules-final/config-fan:
SUCCESS: config-fan previously ran
[ 274.585181] cloud-init[3913]: 2016-12-14 16:23:58,407 -
handlers.py[WARNING]: failed posting event: start:
modules-final/config-landscape: running config-landscape with frequency
once-per-instance
[ 277.585243] cloud-init[3913]: 2016-12-14 16:24:01,407 -
handlers.py[WARNING]: failed posting event: finish:
modules-final/config-landscape: SUCCESS: config-landscape previously ran
[ 280.586888] cloud-init[3913]: 2016-12-14 16:24:04,408 -
handlers.py[WARNING]: failed posting event: start: modules-final/config-lxd:
running config-lxd with frequency once-per-instance
[ 283.586615] cloud-init[3913]: 2016-12-14 16:24:07,408 -
handlers.py[WARNING]: failed posting event: finish: modules-final/config-lxd:
SUCCESS: config-lxd previously ran
[ 286.585808] cloud-init[3913]: 2016-12-14 16:24:10,407 -
handlers.py[WARNING]: failed posting event: start: modules-final/config-puppet:
running config-puppet with frequency once-per-instance
[ 289.585863] cloud-init[3913]: 2016-12-14 16:24:13,407 -
handlers.py[WARNING]: failed posting event: finish:
modules-final/config-puppet: SUCCESS: config-puppet previously ran
[ 292.585137] cloud-init[3913]: 2016-12-14 16:24:16,407 -
handlers.py[WARNING]: failed posting event: start: modules-final/config-chef:
running config-chef with frequency once-per-instance
[ 295.585322] cloud-init[3913]: 2016-12-14 16:24:19,407 -
handlers.py[WARNING]: failed posting event: finish: modules-final/config-chef:
SUCCESS: config-chef previously ran
[ 298.586097] cloud-init[3913]: 2016-12-14 16:24:22,408 -
handlers.py[WARNING]: failed posting event: start:
modules-final/config-salt-minion: running config-salt-minion with frequency
once-per-instance
[ 301.586092] cloud-init[3913]: 2016-12-14 16:24:25,408 -
handlers.py[WARNING]: failed posting event: finish:
modules-final/config-salt-minion: SUCCESS: config-salt-minion previously ran
[ 304.585439] cloud-init[3913]: 2016-12-14 16:24:28,407 -
handlers.py[WARNING]: failed posting event: start:
modules-final/config-mcollective: running config-mcollective with frequency
once-per-instance
[ 307.585243] cloud-init[3913]: 2016-12-14 16:24:31,407 -
handlers.py[WARNING]: failed posting event: finish:
modules-final/config-mcollective: SUCCESS: config-mcollective previously ran
[ 310.586398] cloud-init[3913]: 2016-12-14 16:24:34,408 -
handlers.py[WARNING]: failed posting event: start:
modules-final/config-rightscale_userdata: running config-rightscale_userdata
with frequency once-per-instance
[ 313.585653] cloud-init[3913]: 2016-12-14 16:24:37,407 -
handlers.py[WARNING]: failed posting event: finish:
modules-final/config-rightscale_userdata: SUCCESS: config-rightscale_userdata
previously ran
[ 316.586447] cloud-init[3913]: 2016-12-14 16:24:40,408 -
handlers.py[WARNING]: failed posting event: start:
modules-final/config-scripts-vendor: running config-scripts-vendor with
frequency once-per-instance
[ 319.586427] cloud-init[3913]: 2016-12-14 16:24:43,408 -
handlers.py[WARNING]: failed posting event: finish:
modules-final/config-scripts-vendor: SUCCESS: config-scripts-vendor previously
ran
[ 322.585346] cloud-init[3913]: 2016-12-14 16:24:46,407 -
handlers.py[WARNING]: failed posting event: start:
modules-final/config-scripts-per-once: running config-scripts-per-once with
frequency once
...
To manage notifications about this bug go to:
https://bugs.launchpad.net/cloud-init/+bug/1649951/+subscriptions
--
Mailing list: https://launchpad.net/~yahoo-eng-team
Post to : yahoo-eng-team@lists.launchpad.net
Unsubscribe : https://launchpad.net/~yahoo-eng-team
More help : https://help.launchpad.net/ListHelp
