vrruiz@vrruiz-MacBookPro:~/snap/snapd$ ~/Downloads/spread -v -reuse external:ubuntu-core-16-arm-64
2016/10/25 17:43:28 Found /home/vrruiz/snap/snapd/spread.yaml.
project file path:  .
project file content:  project: snapd

environment:
    GOPATH: /home/gopath
    REUSE_PROJECT: "$(HOST: echo $REUSE_PROJECT)"
    PROJECT_PATH: $GOPATH/src/github.com/snapcore/snapd
    PATH: $GOPATH/bin:/snap/bin:$PATH
    TESTSLIB: $PROJECT_PATH/tests/lib
    SNAP_REEXEC: 0
    SPREAD_STORE_USER: "$(HOST: echo $SPREAD_STORE_USER)"
    SPREAD_STORE_PASSWORD: "$(HOST: echo $SPREAD_STORE_PASSWORD)"
    LANG: "$(echo ${LANG:-C.UTF-8})"
    LANGUAGE: "$(echo ${LANGUAGE:-en})"
    # important to ensure adhoc and linode/qemu behave the same
    SUDO_USER: ""
    SUDO_UID: ""
    TRUST_TEST_KEYS: "true"
    MANAGED_DEVICE: "false"

backends:
    linode:
        key: "$(HOST: echo $SPREAD_LINODE_KEY)"
        halt-timeout: 2h
        systems:
            - ubuntu-16.04-64:
                kernel: GRUB 2
                workers: 2
            - ubuntu-16.04-32:
                kernel: GRUB 2
                workers: 2
            - ubuntu-core-16-64:
                kernel: Direct Disk
                image: ubuntu-16.04-64
            # FIXME restore of ubuntu-core does not properly reset
            # boot variables and key snaps to their pristine state.
            - ubuntu-core-16-64-fixme:
                kernel: Direct Disk
                image: ubuntu-16.04-64
    qemu:
        systems:
            - ubuntu-16.04-64:
                username: ubuntu
                password: ubuntu
            - ubuntu-core-16-64:
                image: ubuntu-16.04-64
                username: ubuntu
                password: ubuntu
            - ubuntu-16.10-64:
                username: ubuntu
                password: ubuntu
    autopkgtest:
        type: adhoc
        allocate: |
            echo "Allocating ad-hoc $SPREAD_SYSTEM"
            if [ -z "${ADT_ARTIFACTS}" ]; then
                FATAL "adhoc only works inside autopkgtest"
                exit 1
            fi
            echo 'ubuntu ALL=(ALL) NOPASSWD:ALL' > /etc/sudoers.d/99-spread-users
            ADDRESS localhost:22
        discard: |
            echo "Discarding ad-hoc $SPREAD_SYSTEM"
        systems:
            - ubuntu-16.04-64:
                username: ubuntu
                password: ubuntu

    external:
        type: adhoc
        environment:
            SPREAD_EXTERNAL_ADDRESS: "$(HOST: echo ${SPREAD_EXTERNAL_ADDRESS:-localhost:8022})"
            MANAGED_DEVICE: "true"
        allocate: |
            ADDRESS $SPREAD_EXTERNAL_ADDRESS
        systems:
            - ubuntu-core-16-64:
                environment:
                    TRUST_TEST_KEYS: "false"
                username: test
                password: ubuntu
            - ubuntu-core-16-arm-64:
                username: test
                password: ubuntu
            - ubuntu-core-16-arm-32:
                username: test
                password: ubuntu

path: /home/gopath/src/github.com/snapcore/snapd

exclude:
    - .git

prepare-each: |
    journalctl --rotate
    sleep .1
    journalctl --vacuum-time=1ms
    dmesg -c > /dev/null

debug-each: |
    journalctl -u snapd
    dmesg | grep DENIED

prepare: |
    # this indicates that the server got reused, nothing to setup
    [ "$REUSE_PROJECT" != 1 ] || exit 0

    # check that we are not updating
    . "$TESTSLIB/boot.sh"
    if [ $(bootenv snap_mode) = "try" ]; then
        echo "Ongoing reboot upgrade process, please try again when finished"
        exit 1
    fi

    # Disable burst limit so resetting the state quickly doesn't create problems.
    mkdir -p /etc/systemd/system/snapd.service.d
    cat <<EOF > /etc/systemd/system/snapd.service.d/local.conf
    [Unit]
    StartLimitInterval=0
    [Service]
    Environment=SNAPD_DEBUG_HTTP=7 SNAP_REEXEC=0
    EOF
    mkdir -p /etc/systemd/system/snapd.socket.d
    cat <<EOF > /etc/systemd/system/snapd.socket.d/local.conf
    [Unit]
    StartLimitInterval=0
    EOF

    if [ "$SPREAD_BACKEND" = external ]; then
        # build test binaries
        if [ ! -f $GOPATH/bin/snapbuild ]; then
            mkdir -p $GOPATH/bin
            snap install --devmode --edge classic
            classic "sudo apt update && apt install -y git golang-go build-essential"
            classic "GOPATH=$GOPATH go get ../..${PROJECT_PATH}/tests/lib/snapbuild"
            snap remove classic
        fi
        exit 0
    fi

    # apt update is hanging on security.ubuntu.com with IPv6.
    sysctl -w net.ipv6.conf.all.disable_ipv6=1
    trap "sysctl -w net.ipv6.conf.all.disable_ipv6=0" EXIT

    apt-get purge -y snapd || true
    apt-get update
    # utilities
    apt-get install -y devscripts expect gdebi-core jq rng-tools software-properties-common git

    # needed so that we have golang-gopkg-macaroon.v1 which is not (yet)
    # in trusty
    add-apt-repository --update ppa:snappy-dev/image
    # this should not be needed but apparently it is :/
    apt-get update
    # in 16.04: apt build-dep -y ./
    apt-get install -y $(gdebi --quiet --apt-line ./debian/control)

    # update vendoring
    go get -u github.com/kardianos/govendor
    govendor sync

    # FIXME: this can be removed once snap-confine 1.0.38-0ubuntu0.16.04.8
    #        hits xenial-updates
    apt-get install -y snap-confine

    # and remove the image PPA again
    add-apt-repository --remove ppa:snappy-dev/image

    # increment version so upgrade can work
    dch -i "testing build"

    if ! id test >& /dev/null; then
        # manually setting the UID and GID to 12345 because we need to
        # know the numbers match for when we set up the user inside
        # the all-snap, which has its own user & group database.
        # Nothing special about 12345 beyond it being high enough it's
        # unlikely to ever clash with anything, and easy to remember.
        addgroup --quiet --gid 12345 test
        adduser --quiet --uid 12345 --gid 12345 --disabled-password --gecos '' test
    fi

    owner=$( stat -c "%U:%G" /home/test )
    if [ "$owner" != "test:test" ]; then
        echo "expected /home/test to be test:test but it's $owner"
        exit 1
    fi
    unset owner

    echo 'test ALL=(ALL) NOPASSWD:ALL' >> /etc/sudoers
    chown test.test -R ..
    su -l -c "cd $PWD && DEB_BUILD_OPTIONS='nocheck testkeys' dpkg-buildpackage -tc -b -Zgzip" test

    # Build snapbuild.
    apt-get install -y git
    go get ./tests/lib/snapbuild

    # Build fakestore.
    go get ./tests/lib/fakestore/cmd/fakestore
    # Build fakedevicesvc.
    go get ./tests/lib/fakedevicesvc

suites:
    tests/main/:
        summary: Full-system tests for snapd
        systems: [-ubuntu-core-16-64-fixme]
        prepare: |
            . $TESTSLIB/prepare.sh
            if [[ "$SPREAD_SYSTEM" == ubuntu-core-16-* ]]; then
                prepare_all_snap
            else
                prepare_classic
            fi
        restore: |
            $TESTSLIB/reset.sh
            if [[ "$SPREAD_SYSTEM" != ubuntu-core-16-* ]]; then
                apt-get purge -y snapd || true
            fi
        restore-each: |
            $TESTSLIB/reset.sh --reuse-core

    tests/upgrade/:
        summary: Tests for snapd upgrade
        systems: [-ubuntu-core-16-64-fixme]
        restore:
            apt-get purge -y snapd || true
        restore-each: |
            $TESTSLIB/reset.sh

2016/10/25 17:43:28 Reusing external:ubuntu-core-16-arm-64...
2016/10/25 17:43:28 Project content is ready for delivery.
2016/10/25 17:43:30 Reusing project data on external:ubuntu-core-16-arm-64...
2016/10/25 17:43:30 Preparing project on external:external:ubuntu-core-16-arm-64...
2016/10/25 17:43:30 Preparing external:ubuntu-core-16-arm-64:tests/main/...
2016/10/25 17:43:31 Preparing external:ubuntu-core-16-arm-64:tests/main/known-remote...
2016/10/25 17:43:31 Executing external:ubuntu-core-16-arm-64:tests/main/known-remote...
2016/10/25 17:43:32 Restoring external:ubuntu-core-16-arm-64:tests/main/known-remote...
2016/10/25 17:43:54 Preparing external:ubuntu-core-16-arm-64:tests/main/searching...
2016/10/25 17:43:54 Executing external:ubuntu-core-16-arm-64:tests/main/searching...
2016/10/25 17:43:56 Restoring external:ubuntu-core-16-arm-64:tests/main/searching...
2016/10/25 17:44:11 Preparing external:ubuntu-core-16-arm-64:tests/main/interfaces-network-bind...
2016/10/25 17:44:26 Executing external:ubuntu-core-16-arm-64:tests/main/interfaces-network-bind...
2016/10/25 17:44:32 Restoring external:ubuntu-core-16-arm-64:tests/main/interfaces-network-bind...
2016/10/25 17:45:00 Preparing external:ubuntu-core-16-arm-64:tests/main/op-remove-retry...
2016/10/25 17:45:00 Executing external:ubuntu-core-16-arm-64:tests/main/op-remove-retry...
2016/10/25 17:45:19 Restoring external:ubuntu-core-16-arm-64:tests/main/op-remove-retry...
2016/10/25 17:45:40 Preparing external:ubuntu-core-16-arm-64:tests/main/interfaces-process-control...
2016/10/25 17:45:48 Executing external:ubuntu-core-16-arm-64:tests/main/interfaces-process-control...
2016/10/25 17:45:51 Restoring external:ubuntu-core-16-arm-64:tests/main/interfaces-process-control...
2016/10/25 17:46:14 Preparing external:ubuntu-core-16-arm-64:tests/main/try-snap-goes-away:network_bind_consumer...
2016/10/25 17:46:14 Executing external:ubuntu-core-16-arm-64:tests/main/try-snap-goes-away:network_bind_consumer...
2016/10/25 17:46:25 Restoring external:ubuntu-core-16-arm-64:tests/main/try-snap-goes-away:network_bind_consumer...
2016/10/25 17:46:45 Preparing external:ubuntu-core-16-arm-64:tests/main/try-snap-goes-away:test_snapd_tools...
2016/10/25 17:46:45 Executing external:ubuntu-core-16-arm-64:tests/main/try-snap-goes-away:test_snapd_tools...
2016/10/25 17:47:00 Restoring external:ubuntu-core-16-arm-64:tests/main/try-snap-goes-away:test_snapd_tools...
2016/10/25 17:47:22 Preparing external:ubuntu-core-16-arm-64:tests/main/writable-areas...
2016/10/25 17:47:22 Executing external:ubuntu-core-16-arm-64:tests/main/writable-areas...
2016/10/25 17:47:31 Restoring external:ubuntu-core-16-arm-64:tests/main/writable-areas...
2016/10/25 17:47:51 Preparing external:ubuntu-core-16-arm-64:tests/main/install-store...
2016/10/25 17:47:53 Executing external:ubuntu-core-16-arm-64:tests/main/install-store...
2016/10/25 17:49:11 Restoring external:ubuntu-core-16-arm-64:tests/main/install-store...
2016/10/25 17:49:37 Preparing external:ubuntu-core-16-arm-64:tests/main/interfaces-network-control...
2016/10/25 17:49:46 Executing external:ubuntu-core-16-arm-64:tests/main/interfaces-network-control...
2016/10/25 17:49:58 Restoring external:ubuntu-core-16-arm-64:tests/main/interfaces-network-control...
2016/10/25 17:50:25 Preparing external:ubuntu-core-16-arm-64:tests/main/snap-get...
2016/10/25 17:50:37 Executing external:ubuntu-core-16-arm-64:tests/main/snap-get...
2016/10/25 17:50:38 Restoring external:ubuntu-core-16-arm-64:tests/main/snap-get...
2016/10/25 17:51:00 Preparing external:ubuntu-core-16-arm-64:tests/main/security-device-cgroups:kmsg...
2016/10/25 17:51:02 Executing external:ubuntu-core-16-arm-64:tests/main/security-device-cgroups:kmsg...
2016/10/25 17:51:15 Restoring external:ubuntu-core-16-arm-64:tests/main/security-device-cgroups:kmsg...
2016/10/25 17:51:42 Preparing external:ubuntu-core-16-arm-64:tests/main/security-device-cgroups:uinput...
2016/10/25 17:51:42 Executing external:ubuntu-core-16-arm-64:tests/main/security-device-cgroups:uinput...
2016/10/25 17:51:54 Restoring external:ubuntu-core-16-arm-64:tests/main/security-device-cgroups:uinput...
2016/10/25 17:52:21 Preparing external:ubuntu-core-16-arm-64:tests/main/ubuntu-core-classic...
2016/10/25 17:52:22 Executing external:ubuntu-core-16-arm-64:tests/main/ubuntu-core-classic...
2016/10/25 17:53:11 Restoring external:ubuntu-core-16-arm-64:tests/main/ubuntu-core-classic...
2016/10/25 17:53:50 Preparing external:ubuntu-core-16-arm-64:tests/main/ubuntu-core-create-user...
2016/10/25 17:53:51 Executing external:ubuntu-core-16-arm-64:tests/main/ubuntu-core-create-user...
2016/10/25 17:53:51 Restoring external:ubuntu-core-16-arm-64:tests/main/ubuntu-core-create-user...
2016/10/25 17:54:15 Preparing external:ubuntu-core-16-arm-64:tests/main/server-snap:goServer...
2016/10/25 17:54:16 Error preparing external:ubuntu-core-16-arm-64:tests/main/server-snap:goServer : 
-----
+ journalctl --rotate
+ sleep .1
+ journalctl --vacuum-time=1ms
Deleted archived journal /run/log/journal/c644317453734faa87635fb7ba168b2f/system@4be7c078e65b47a4bd840d4c96848679-00000000000166d0-00053fb35a5b2217.journal (1012.0K).
Vacuuming done, freed 1012.0K of archived journals on disk.
+ dmesg -c
+ snap install go-example-webserver
error: cannot install "go-example-webserver": snap not found
-----
2016/10/25 17:54:17 Error debugging external:ubuntu-core-16-arm-64:tests/main/server-snap:goServer : 
-----
+ journalctl -u snapd
-- Logs begin at Tue 2016-10-25 16:54:15 UTC, end at Tue 2016-10-25 16:54:16 UTC. --
Oct 25 16:54:15 localhost.localdomain /usr/lib/snapd/snapd[13599]: api.go:829: Installing snap "go-example-webserver" revision unset
Oct 25 16:54:15 localhost.localdomain snapd[13599]: 2016/10/25 16:54:15.921442 api.go:829: Installing snap "go-example-webserver" revision unset
Oct 25 16:54:15 localhost.localdomain /usr/lib/snapd/snapd[13599]: logger.go:66: DEBUG: > "GET /api/v1/snaps/details/go-example-webserver?channel=stable&confinement=strict HTTP/1.1\r\nHost: search.apps.ubuntu.com\r\nUser-Agent: snapd/2.16+ppa65.57ba1019-1 (series 16) ubuntu/16.04 (arm64)\r\nAccept: application/hal+json\r\nX-Device-Authorization: Macaroon root=\"MDAyOWxvY2F0aW9uIG15YXBwcy5kZXZlbG9wZXIudWJ1bnR1LmNvbQowMDFlaWRlbnRpZmllciBEZXZpY2Ugc2Vzc2lvbgowMDRiY2lkIG15YXBwcy5kZXZlbG9wZXIudWJ1bnR1LmNvbXx2YWxpZF9zaW5jZXwyMDE2LTEwLTI1VDE1OjA2OjA5Ljc5NDA4NAowMGQyY2lkIG15YXBwcy5kZXZlbG9wZXIudWJ1bnR1LmNvbXxkZXZpY2V8eyJleHAiOiAxNDc5OTk5OTY5LCAiYnJhbmQiOiAiY2Fub25pY2FsIiwgInNlc3Npb24iOiAiNzNhZDRlZDgtYTVkNS00NzU2LWIyNjQtODFjZjRiNTcxN2ZiIiwgIm1vZGVsIjogImRyYWdvbmJvYXJkIiwgInNlcmlhbCI6ICJiNGFlYTU4Ni1lMTFjLTRiNTctODYxOS0xMTk3NjVhOGNlODkifQowMDJmc2lnbmF0dXJlIDWYKe38B4tOdrGK1K7tdRPwbx93Urx7eOShS4fpO6YOCg\"\r\nX-Ubuntu-Architecture: arm64\r\nX-Ubuntu-Series: 16\r\nX-Ubuntu-Wire-Protocol: 1\r\nAccept-Encoding: gzip\r\n\r\n"
Oct 25 16:54:16 localhost.localdomain /usr/lib/snapd/snapd[13599]: logger.go:73: DEBUG: < "HTTP/1.1 404 Not Found\r\nContent-Length: 128\r\nAccess-Control-Allow-Methods: GET\r\nAccess-Control-Allow-Origin: *\r\nAccess-Control-Expose-Headers: X-Suggested-Currency\r\nContent-Type: application/hal+json\r\nDate: Tue, 25 Oct 2016 16:54:16 GMT\r\nServer: gunicorn/19.3.0\r\nStrict-Transport-Security: max-age=2592000\r\nVary: Accept,Accept-Language,Authorization,X-Device-Authorization,X-GeoIP-Country-Code,X-Ubuntu-Architecture,X-Ubuntu-Confinement,X-Ubuntu-Device-Channel,X-Ubuntu-Frameworks,X-Ubuntu-Slots,X-Ubuntu-Release,X-Ubuntu-Series,X-Ubuntu-Store\r\nVia: 1.1 juju-prod-ols-cpi-machine-6 (squid/3.3.8)\r\nX-Bzr-Revision-Number: 529\r\nX-Cache: MISS from juju-prod-ols-cpi-machine-6\r\nX-Cache-Lookup: MISS from juju-prod-ols-cpi-machine-6:3128\r\n\r\n{\"error_list\": [{\"message\": \"No such package\", \"code\": \"resource-not-found\"}], \"errors\": [\"No such package\"], \"result\": \"error\"}"
Oct 25 16:54:16 localhost.localdomain /usr/lib/snapd/snapd[13599]: daemon.go:174: DEBUG: uid=0;@ POST /v2/snaps/go-example-webserver 932.635ms 400
+ dmesg
+ grep DENIED
-----
2016/10/25 17:54:17 Restoring external:ubuntu-core-16-arm-64:tests/main/server-snap:goServer...
2016/10/25 17:54:32 Preparing external:ubuntu-core-16-arm-64:tests/main/server-snap:pythonServer...
2016/10/25 17:54:49 Executing external:ubuntu-core-16-arm-64:tests/main/server-snap:pythonServer...
2016/10/25 17:54:49 Restoring external:ubuntu-core-16-arm-64:tests/main/server-snap:pythonServer...
2016/10/25 17:55:18 Preparing external:ubuntu-core-16-arm-64:tests/main/snap-connect...
2016/10/25 17:55:27 Executing external:ubuntu-core-16-arm-64:tests/main/snap-connect...
2016/10/25 17:55:32 Restoring external:ubuntu-core-16-arm-64:tests/main/snap-connect...
2016/10/25 17:55:52 Preparing external:ubuntu-core-16-arm-64:tests/main/ubuntu-core-device-reg...
2016/10/25 17:55:53 Executing external:ubuntu-core-16-arm-64:tests/main/ubuntu-core-device-reg...
2016/10/25 17:55:54 Error executing external:ubuntu-core-16-arm-64:tests/main/ubuntu-core-device-reg : 
-----
+ echo 'Wait for first boot to be done'
Wait for first boot to be done
+ snap changes
+ grep -q 'Done.*Initialize system state'
+ echo 'We have a model assertion'
We have a model assertion
+ snap known model
+ grep 'series: 16'
series: 16
+ snap known model
+ grep 'brand-id: canonical'
brand-id: canonical
+ echo 'Wait for device initialisation to be done'
Wait for device initialisation to be done
+ snap changes
+ grep -q 'Done.*Initialize device'
+ echo 'Check we have a serial'
Check we have a serial
+ snap known serial
+ grep 'authority-id: canonical'
authority-id: canonical
+ snap known serial
+ grep 'brand-id: canonical'
brand-id: canonical
+ snap known serial
+ grep 'model: pc'
-----
2016/10/25 17:55:54 Error debugging external:ubuntu-core-16-arm-64:tests/main/ubuntu-core-device-reg : 
-----
+ journalctl -u snapd
-- Logs begin at Tue 2016-10-25 16:55:53 UTC, end at Tue 2016-10-25 16:55:54 UTC. --
Oct 25 16:55:54 localhost.localdomain /usr/lib/snapd/snapd[14347]: daemon.go:174: DEBUG: uid=0;@ GET /v2/changes?select=all 6.002ms 200
Oct 25 16:55:54 localhost.localdomain /usr/lib/snapd/snapd[14347]: daemon.go:174: DEBUG: uid=0;@ GET /v2/assertions/model 2.256ms 200
Oct 25 16:55:54 localhost.localdomain /usr/lib/snapd/snapd[14347]: daemon.go:174: DEBUG: uid=0;@ GET /v2/assertions/model 2.075ms 200
Oct 25 16:55:54 localhost.localdomain /usr/lib/snapd/snapd[14347]: daemon.go:174: DEBUG: uid=0;@ GET /v2/changes?select=all 4.12ms 200
Oct 25 16:55:54 localhost.localdomain /usr/lib/snapd/snapd[14347]: daemon.go:174: DEBUG: uid=0;@ GET /v2/assertions/serial 2.972ms 200
Oct 25 16:55:54 localhost.localdomain /usr/lib/snapd/snapd[14347]: daemon.go:174: DEBUG: uid=0;@ GET /v2/assertions/serial 2.079ms 200
Oct 25 16:55:54 localhost.localdomain /usr/lib/snapd/snapd[14347]: daemon.go:174: DEBUG: uid=0;@ GET /v2/assertions/serial 2.676ms 200
+ dmesg
+ grep DENIED
-----
2016/10/25 17:55:54 Restoring external:ubuntu-core-16-arm-64:tests/main/ubuntu-core-device-reg...
2016/10/25 17:56:15 Preparing external:ubuntu-core-16-arm-64:tests/main/snap-set...
2016/10/25 17:56:27 Executing external:ubuntu-core-16-arm-64:tests/main/snap-set...
2016/10/25 17:56:36 Restoring external:ubuntu-core-16-arm-64:tests/main/snap-set...
2016/10/25 17:56:58 Preparing external:ubuntu-core-16-arm-64:tests/main/abort...
2016/10/25 17:57:00 Executing external:ubuntu-core-16-arm-64:tests/main/abort...
2016/10/25 17:57:21 Restoring external:ubuntu-core-16-arm-64:tests/main/abort...
2016/10/25 17:57:45 Preparing external:ubuntu-core-16-arm-64:tests/main/revert:fake...
2016/10/25 17:57:46 Executing external:ubuntu-core-16-arm-64:tests/main/revert:fake...
2016/10/25 17:57:46 Restoring external:ubuntu-core-16-arm-64:tests/main/revert:fake...
2016/10/25 17:58:06 Preparing external:ubuntu-core-16-arm-64:tests/main/revert:production...
2016/10/25 17:58:20 Executing external:ubuntu-core-16-arm-64:tests/main/revert:production...
2016/10/25 17:58:41 Restoring external:ubuntu-core-16-arm-64:tests/main/revert:production...
2016/10/25 17:59:04 Preparing external:ubuntu-core-16-arm-64:tests/main/interfaces-home...
2016/10/25 17:59:13 Executing external:ubuntu-core-16-arm-64:tests/main/interfaces-home...
2016/10/25 17:59:28 Restoring external:ubuntu-core-16-arm-64:tests/main/interfaces-home...
2016/10/25 17:59:54 Preparing external:ubuntu-core-16-arm-64:tests/main/snap-remove-not-mounted...
2016/10/25 17:59:54 Executing external:ubuntu-core-16-arm-64:tests/main/snap-remove-not-mounted...
2016/10/25 18:00:06 Restoring external:ubuntu-core-16-arm-64:tests/main/snap-remove-not-mounted...
2016/10/25 18:00:25 Preparing external:ubuntu-core-16-arm-64:tests/main/refresh-devmode:fake...
2016/10/25 18:00:26 Executing external:ubuntu-core-16-arm-64:tests/main/refresh-devmode:fake...
2016/10/25 18:00:26 Restoring external:ubuntu-core-16-arm-64:tests/main/refresh-devmode:fake...
2016/10/25 18:00:45 Preparing external:ubuntu-core-16-arm-64:tests/main/refresh-devmode:production...
2016/10/25 18:01:00 Executing external:ubuntu-core-16-arm-64:tests/main/refresh-devmode:production...
2016/10/25 18:01:11 Restoring external:ubuntu-core-16-arm-64:tests/main/refresh-devmode:production...
2016/10/25 18:01:42 Preparing external:ubuntu-core-16-arm-64:tests/main/interfaces-snapd-control...
2016/10/25 18:01:52 Executing external:ubuntu-core-16-arm-64:tests/main/interfaces-snapd-control...
2016/10/25 18:02:12 Restoring external:ubuntu-core-16-arm-64:tests/main/interfaces-snapd-control...
2016/10/25 18:02:36 Preparing external:ubuntu-core-16-arm-64:tests/main/interfaces-fuse_support...
2016/10/25 18:02:38 Error preparing external:ubuntu-core-16-arm-64:tests/main/interfaces-fuse_support : 
-----
+ journalctl --rotate
+ sleep .1
+ journalctl --vacuum-time=1ms
Deleted archived journal /run/log/journal/c644317453734faa87635fb7ba168b2f/system@4be7c078e65b47a4bd840d4c96848679-0000000000016b01-00053fb376764d07.journal (1012.0K).
Vacuuming done, freed 1012.0K of archived journals on disk.
+ dmesg -c
+ echo 'Given a snap declaring a fuse plug is installed'
Given a snap declaring a fuse plug is installed
+ snap install test-snapd-fuse-consumer
error: cannot install "test-snapd-fuse-consumer": snap not found
-----
2016/10/25 18:02:38 Error debugging external:ubuntu-core-16-arm-64:tests/main/interfaces-fuse_support : 
-----
+ journalctl -u snapd
-- Logs begin at Tue 2016-10-25 17:02:36 UTC, end at Tue 2016-10-25 17:02:38 UTC. --
Oct 25 17:02:36 localhost.localdomain /usr/lib/snapd/snapd[17947]: daemon.go:209: DEBUG: init done in 6.497ms
Oct 25 17:02:37 localhost.localdomain /usr/lib/snapd/snapd[17947]: api.go:829: Installing snap "test-snapd-fuse-consumer" revision unset
Oct 25 17:02:37 localhost.localdomain snapd[17947]: 2016/10/25 17:02:37.449213 api.go:829: Installing snap "test-snapd-fuse-consumer" revision unset
Oct 25 17:02:37 localhost.localdomain /usr/lib/snapd/snapd[17947]: logger.go:66: DEBUG: > "GET /api/v1/snaps/details/test-snapd-fuse-consumer?channel=stable&confinement=strict HTTP/1.1\r\nHost: search.apps.ubuntu.com\r\nUser-Agent: snapd/2.16+ppa65.57ba1019-1 (series 16) ubuntu/16.04 (arm64)\r\nAccept: application/hal+json\r\nX-Device-Authorization: Macaroon root=\"MDAyOWxvY2F0aW9uIG15YXBwcy5kZXZlbG9wZXIudWJ1bnR1LmNvbQowMDFlaWRlbnRpZmllciBEZXZpY2Ugc2Vzc2lvbgowMDRiY2lkIG15YXBwcy5kZXZlbG9wZXIudWJ1bnR1LmNvbXx2YWxpZF9zaW5jZXwyMDE2LTEwLTI1VDE1OjA2OjA5Ljc5NDA4NAowMGQyY2lkIG15YXBwcy5kZXZlbG9wZXIudWJ1bnR1LmNvbXxkZXZpY2V8eyJleHAiOiAxNDc5OTk5OTY5LCAiYnJhbmQiOiAiY2Fub25pY2FsIiwgInNlc3Npb24iOiAiNzNhZDRlZDgtYTVkNS00NzU2LWIyNjQtODFjZjRiNTcxN2ZiIiwgIm1vZGVsIjogImRyYWdvbmJvYXJkIiwgInNlcmlhbCI6ICJiNGFlYTU4Ni1lMTFjLTRiNTctODYxOS0xMTk3NjVhOGNlODkifQowMDJmc2lnbmF0dXJlIDWYKe38B4tOdrGK1K7tdRPwbx93Urx7eOShS4fpO6YOCg\"\r\nX-Ubuntu-Architecture: arm64\r\nX-Ubuntu-Series: 16\r\nX-Ubuntu-Wire-Protocol: 1\r\nAccept-Encoding: gzip\r\n\r\n"
Oct 25 17:02:38 localhost.localdomain /usr/lib/snapd/snapd[17947]: logger.go:73: DEBUG: < "HTTP/1.1 404 Not Found\r\nContent-Length: 128\r\nAccess-Control-Allow-Methods: GET\r\nAccess-Control-Allow-Origin: *\r\nAccess-Control-Expose-Headers: X-Suggested-Currency\r\nContent-Type: application/hal+json\r\nDate: Tue, 25 Oct 2016 17:02:38 GMT\r\nServer: gunicorn/19.3.0\r\nStrict-Transport-Security: max-age=2592000\r\nVary: Accept,Accept-Language,Authorization,X-Device-Authorization,X-GeoIP-Country-Code,X-Ubuntu-Architecture,X-Ubuntu-Confinement,X-Ubuntu-Device-Channel,X-Ubuntu-Frameworks,X-Ubuntu-Slots,X-Ubuntu-Release,X-Ubuntu-Series,X-Ubuntu-Store\r\nVia: 1.1 juju-prod-ols-cpi-machine-6 (squid/3.3.8)\r\nX-Bzr-Revision-Number: 529\r\nX-Cache: MISS from juju-prod-ols-cpi-machine-6\r\nX-Cache-Lookup: MISS from juju-prod-ols-cpi-machine-6:3128\r\n\r\n{\"error_list\": [{\"message\": \"No such package\", \"code\": \"resource-not-found\"}], \"errors\": [\"No such package\"], \"result\": \"error\"}"
Oct 25 17:02:38 localhost.localdomain /usr/lib/snapd/snapd[17947]: daemon.go:174: DEBUG: uid=0;@ POST /v2/snaps/test-snapd-fuse-consumer 1.220285s 400
+ dmesg
+ grep DENIED
-----
2016/10/25 18:02:38 Restoring external:ubuntu-core-16-arm-64:tests/main/interfaces-fuse_support...
2016/10/25 18:03:01 Preparing external:ubuntu-core-16-arm-64:tests/main/snapctl...
2016/10/25 18:03:10 Executing external:ubuntu-core-16-arm-64:tests/main/snapctl...
2016/10/25 18:03:11 Restoring external:ubuntu-core-16-arm-64:tests/main/snapctl...
2016/10/25 18:03:38 Preparing external:ubuntu-core-16-arm-64:tests/main/install-remove-multi...
2016/10/25 18:03:38 Executing external:ubuntu-core-16-arm-64:tests/main/install-remove-multi...
2016/10/25 18:04:02 Restoring external:ubuntu-core-16-arm-64:tests/main/install-remove-multi...
2016/10/25 18:04:20 Preparing external:ubuntu-core-16-arm-64:tests/main/prepare-image-uboot...
2016/10/25 18:04:21 Executing external:ubuntu-core-16-arm-64:tests/main/prepare-image-uboot...
2016/10/25 18:05:46 Restoring external:ubuntu-core-16-arm-64:tests/main/prepare-image-uboot...
2016/10/25 18:06:13 Preparing external:ubuntu-core-16-arm-64:tests/main/snap-run-hook...
2016/10/25 18:06:22 Executing external:ubuntu-core-16-arm-64:tests/main/snap-run-hook...
2016/10/25 18:06:22 Restoring external:ubuntu-core-16-arm-64:tests/main/snap-run-hook...
2016/10/25 18:06:50 Preparing external:ubuntu-core-16-arm-64:tests/main/security-apparmor...
2016/10/25 18:07:02 Executing external:ubuntu-core-16-arm-64:tests/main/security-apparmor...
2016/10/25 18:07:02 Restoring external:ubuntu-core-16-arm-64:tests/main/security-apparmor...
2016/10/25 18:07:24 Preparing external:ubuntu-core-16-arm-64:tests/main/install-sideload...
2016/10/25 18:07:26 Executing external:ubuntu-core-16-arm-64:tests/main/install-sideload...
2016/10/25 18:08:13 Restoring external:ubuntu-core-16-arm-64:tests/main/install-sideload...
2016/10/25 18:08:53 Preparing external:ubuntu-core-16-arm-64:tests/main/ubuntu-core-reboot...
2016/10/25 18:09:11 Executing external:ubuntu-core-16-arm-64:tests/main/ubuntu-core-reboot...
2016/10/25 18:09:12 Rebooting external:ubuntu-core-16-arm-64 as requested...
2016/10/25 18:10:13 Error executing external:ubuntu-core-16-arm-64:tests/main/ubuntu-core-reboot : 
-----
mesg: ttyname failed: Inappropriate ioctl for device
+ echo 'Ensure snaps are (still) there.'
Ensure snaps are (still) there.
+ snap list
+ grep test-snapd-tools
test-snapd-tools       1.0           x1              -
+ snap list
+ grep network-bind-consumer
network-bind-consumer  1.0           x1              -
+ echo 'Ensure the service is (still) running.'
Ensure the service is (still) running.
+ retries=10
+ systemctl is-active snap.network-bind-consumer.network-consumer.service
active
+ echo 'Ensure apparmor profiles are (still) loaded.'
Ensure apparmor profiles are (still) loaded.
+ for app in success fail echo head env block cat
+ grep 'snap.test-snapd-tools.success (enforce)' /sys/kernel/security/apparmor/profiles
snap.test-snapd-tools.success (enforce)
+ for app in success fail echo head env block cat
+ grep 'snap.test-snapd-tools.fail (enforce)' /sys/kernel/security/apparmor/profiles
snap.test-snapd-tools.fail (enforce)
+ for app in success fail echo head env block cat
+ grep 'snap.test-snapd-tools.echo (enforce)' /sys/kernel/security/apparmor/profiles
snap.test-snapd-tools.echo (enforce)
+ for app in success fail echo head env block cat
+ grep 'snap.test-snapd-tools.head (enforce)' /sys/kernel/security/apparmor/profiles
snap.test-snapd-tools.head (enforce)
+ for app in success fail echo head env block cat
+ grep 'snap.test-snapd-tools.env (enforce)' /sys/kernel/security/apparmor/profiles
snap.test-snapd-tools.env (enforce)
+ for app in success fail echo head env block cat
+ grep 'snap.test-snapd-tools.block (enforce)' /sys/kernel/security/apparmor/profiles
snap.test-snapd-tools.block (enforce)
+ for app in success fail echo head env block cat
+ grep 'snap.test-snapd-tools.cat (enforce)' /sys/kernel/security/apparmor/profiles
snap.test-snapd-tools.cat (enforce)
+ '[' 0 = 0 ']'
+ REBOOT
<REBOOT>

+ echo 'Ensure snaps are (still) there.'
Ensure snaps are (still) there.
+ snap list
+ grep test-snapd-tools
test-snapd-tools       1.0           x1              -
+ snap list
+ grep network-bind-consumer
network-bind-consumer  1.0           x1              -
+ echo 'Ensure the service is (still) running.'
Ensure the service is (still) running.
+ retries=10
+ systemctl is-active snap.network-bind-consumer.network-consumer.service
inactive
+ '[' 10 -eq 0 ']'
+ retries=9
+ sleep 1
+ systemctl is-active snap.network-bind-consumer.network-consumer.service
inactive
+ '[' 9 -eq 0 ']'
+ retries=8
+ sleep 1
+ systemctl is-active snap.network-bind-consumer.network-consumer.service
inactive
+ '[' 8 -eq 0 ']'
+ retries=7
+ sleep 1
+ systemctl is-active snap.network-bind-consumer.network-consumer.service
inactive
+ '[' 7 -eq 0 ']'
+ retries=6
+ sleep 1
+ systemctl is-active snap.network-bind-consumer.network-consumer.service
inactive
+ '[' 6 -eq 0 ']'
+ retries=5
+ sleep 1
+ systemctl is-active snap.network-bind-consumer.network-consumer.service
inactive
+ '[' 5 -eq 0 ']'
+ retries=4
+ sleep 1
+ systemctl is-active snap.network-bind-consumer.network-consumer.service
inactive
+ '[' 4 -eq 0 ']'
+ retries=3
+ sleep 1
+ systemctl is-active snap.network-bind-consumer.network-consumer.service
inactive
+ '[' 3 -eq 0 ']'
+ retries=2
+ sleep 1
+ systemctl is-active snap.network-bind-consumer.network-consumer.service
inactive
+ '[' 2 -eq 0 ']'
+ retries=1
+ sleep 1
+ systemctl is-active snap.network-bind-consumer.network-consumer.service
inactive
+ '[' 1 -eq 0 ']'
+ retries=0
+ sleep 1
+ systemctl is-active snap.network-bind-consumer.network-consumer.service
inactive
+ '[' 0 -eq 0 ']'
+ echo 'Service did not activate.'
Service did not activate.
+ exit 1
-----
2016/10/25 18:10:13 Error debugging external:ubuntu-core-16-arm-64:tests/main/ubuntu-core-reboot : 
-----
+ journalctl -u snapd
-- Logs begin at Tue 2016-10-25 14:39:14 UTC, end at Tue 2016-10-25 14:39:33 UTC. --
Oct 25 14:39:20 localhost.localdomain systemd[1]: Started Snappy daemon.
Oct 25 14:39:21 localhost.localdomain /usr/lib/snapd/snapd[1425]: daemon.go:209: DEBUG: init done in 3.625ms
Oct 25 14:39:22 localhost.localdomain /usr/lib/snapd/snapd[1425]: daemon.go:174: DEBUG: uid=0;@ GET /v2/snaps 5.421ms 200
Oct 25 14:39:22 localhost.localdomain /usr/lib/snapd/snapd[1425]: daemon.go:174: DEBUG: uid=0;@ GET /v2/snaps 4.935ms 200
+ dmesg
+ grep DENIED
-----
2016/10/25 18:10:13 Restoring external:ubuntu-core-16-arm-64:tests/main/ubuntu-core-reboot...
2016/10/25 18:10:45 Preparing external:ubuntu-core-16-arm-64:tests/main/interfaces-network...
2016/10/25 18:10:55 Executing external:ubuntu-core-16-arm-64:tests/main/interfaces-network...
2016/10/25 18:11:02 Restoring external:ubuntu-core-16-arm-64:tests/main/interfaces-network...
2016/10/25 18:11:38 Preparing external:ubuntu-core-16-arm-64:tests/main/snap-disconnect...
2016/10/25 18:11:44 Executing external:ubuntu-core-16-arm-64:tests/main/snap-disconnect...
2016/10/25 18:11:55 Restoring external:ubuntu-core-16-arm-64:tests/main/snap-disconnect...
2016/10/25 18:12:15 Preparing external:ubuntu-core-16-arm-64:tests/main/op-remove...
2016/10/25 18:12:17 Executing external:ubuntu-core-16-arm-64:tests/main/op-remove...
2016/10/25 18:12:34 Restoring external:ubuntu-core-16-arm-64:tests/main/op-remove...
2016/10/25 18:12:50 Preparing external:ubuntu-core-16-arm-64:tests/main/ubuntu-core-apt...
2016/10/25 18:12:50 Executing external:ubuntu-core-16-arm-64:tests/main/ubuntu-core-apt...
2016/10/25 18:12:51 Restoring external:ubuntu-core-16-arm-64:tests/main/ubuntu-core-apt...
2016/10/25 18:13:16 Preparing external:ubuntu-core-16-arm-64:tests/main/interfaces-cli...
2016/10/25 18:13:25 Executing external:ubuntu-core-16-arm-64:tests/main/interfaces-cli...
2016/10/25 18:13:26 Restoring external:ubuntu-core-16-arm-64:tests/main/interfaces-cli...
2016/10/25 18:13:43 Preparing external:ubuntu-core-16-arm-64:tests/main/cmdline...
2016/10/25 18:13:44 Executing external:ubuntu-core-16-arm-64:tests/main/cmdline...
2016/10/25 18:13:45 Restoring external:ubuntu-core-16-arm-64:tests/main/cmdline...
2016/10/25 18:14:07 Preparing external:ubuntu-core-16-arm-64:tests/main/interfaces-mount-observe...
2016/10/25 18:14:16 Executing external:ubuntu-core-16-arm-64:tests/main/interfaces-mount-observe...
2016/10/25 18:14:30 Restoring external:ubuntu-core-16-arm-64:tests/main/interfaces-mount-observe...
2016/10/25 18:15:00 Preparing external:ubuntu-core-16-arm-64:tests/main/refresh:fake...
2016/10/25 18:15:01 Executing external:ubuntu-core-16-arm-64:tests/main/refresh:fake...
2016/10/25 18:15:01 Restoring external:ubuntu-core-16-arm-64:tests/main/refresh:fake...
2016/10/25 18:15:24 Preparing external:ubuntu-core-16-arm-64:tests/main/refresh:production...
2016/10/25 18:15:39 Executing external:ubuntu-core-16-arm-64:tests/main/refresh:production...
2016/10/25 18:15:52 Restoring external:ubuntu-core-16-arm-64:tests/main/refresh:production...
2016/10/25 18:16:16 Preparing external:ubuntu-core-16-arm-64:tests/main/revert-sideload...
2016/10/25 18:16:18 Executing external:ubuntu-core-16-arm-64:tests/main/revert-sideload...
2016/10/25 18:16:31 Restoring external:ubuntu-core-16-arm-64:tests/main/revert-sideload...
2016/10/25 18:17:00 Preparing external:ubuntu-core-16-arm-64:tests/main/find-private...
2016/10/25 18:17:00 Executing external:ubuntu-core-16-arm-64:tests/main/find-private...
2016/10/25 18:17:02 Restoring external:ubuntu-core-16-arm-64:tests/main/find-private...
2016/10/25 18:17:20 Preparing external:ubuntu-core-16-arm-64:tests/main/interfaces-firewall-control...
2016/10/25 18:17:31 Executing external:ubuntu-core-16-arm-64:tests/main/interfaces-firewall-control...
2016/10/25 18:17:38 Restoring external:ubuntu-core-16-arm-64:tests/main/interfaces-firewall-control...
2016/10/25 18:18:03 Preparing external:ubuntu-core-16-arm-64:tests/main/ubuntu-core-writablepaths...
2016/10/25 18:18:03 Executing external:ubuntu-core-16-arm-64:tests/main/ubuntu-core-writablepaths...
2016/10/25 18:18:06 Restoring external:ubuntu-core-16-arm-64:tests/main/ubuntu-core-writablepaths...
2016/10/25 18:18:29 Preparing external:ubuntu-core-16-arm-64:tests/main/remove-errors...
2016/10/25 18:18:29 Executing external:ubuntu-core-16-arm-64:tests/main/remove-errors...
2016/10/25 18:18:41 Restoring external:ubuntu-core-16-arm-64:tests/main/remove-errors...
2016/10/25 18:19:04 Preparing external:ubuntu-core-16-arm-64:tests/main/regression-home-snap-root-owned...
2016/10/25 18:19:16 Executing external:ubuntu-core-16-arm-64:tests/main/regression-home-snap-root-owned...
2016/10/25 18:19:17 Restoring external:ubuntu-core-16-arm-64:tests/main/regression-home-snap-root-owned...
2016/10/25 18:19:39 Preparing external:ubuntu-core-16-arm-64:tests/main/interfaces-system-observe...
2016/10/25 18:19:46 Executing external:ubuntu-core-16-arm-64:tests/main/interfaces-system-observe...
2016/10/25 18:19:50 Restoring external:ubuntu-core-16-arm-64:tests/main/interfaces-system-observe...
2016/10/25 18:20:17 Preparing external:ubuntu-core-16-arm-64:tests/main/help:interfaces...
2016/10/25 18:20:18 Executing external:ubuntu-core-16-arm-64:tests/main/help:interfaces...
2016/10/25 18:20:18 Restoring external:ubuntu-core-16-arm-64:tests/main/help:interfaces...
2016/10/25 18:20:32 Preparing external:ubuntu-core-16-arm-64:tests/main/help:remove...
2016/10/25 18:20:33 Executing external:ubuntu-core-16-arm-64:tests/main/help:remove...
2016/10/25 18:20:33 Restoring external:ubuntu-core-16-arm-64:tests/main/help:remove...
2016/10/25 18:20:52 Preparing external:ubuntu-core-16-arm-64:tests/main/help:abort...
2016/10/25 18:20:53 Executing external:ubuntu-core-16-arm-64:tests/main/help:abort...
2016/10/25 18:20:53 Restoring external:ubuntu-core-16-arm-64:tests/main/help:abort...
2016/10/25 18:21:14 Preparing external:ubuntu-core-16-arm-64:tests/main/help:changes...
2016/10/25 18:21:14 Executing external:ubuntu-core-16-arm-64:tests/main/help:changes...
2016/10/25 18:21:14 Restoring external:ubuntu-core-16-arm-64:tests/main/help:changes...
2016/10/25 18:21:31 Preparing external:ubuntu-core-16-arm-64:tests/main/help:find...
2016/10/25 18:21:33 Executing external:ubuntu-core-16-arm-64:tests/main/help:find...
2016/10/25 18:21:33 Restoring external:ubuntu-core-16-arm-64:tests/main/help:find...
2016/10/25 18:21:54 Preparing external:ubuntu-core-16-arm-64:tests/main/help:install...
2016/10/25 18:21:54 Executing external:ubuntu-core-16-arm-64:tests/main/help:install...
2016/10/25 18:21:55 Restoring external:ubuntu-core-16-arm-64:tests/main/help:install...
2016/10/25 18:22:10 Preparing external:ubuntu-core-16-arm-64:tests/main/ubuntu-core-upgrade...
2016/10/25 18:22:12 Executing external:ubuntu-core-16-arm-64:tests/main/ubuntu-core-upgrade...
2016/10/25 18:22:29 Rebooting external:ubuntu-core-16-arm-64 as requested...
2016/10/25 18:23:22 Rebooting external:ubuntu-core-16-arm-64 as requested...
2016/10/25 18:24:20 Rebooting external:ubuntu-core-16-arm-64 as requested...
2016/10/25 18:25:13 Rebooting external:ubuntu-core-16-arm-64 as requested...
2016/10/25 18:26:05 Restoring external:ubuntu-core-16-arm-64:tests/main/ubuntu-core-upgrade...
2016/10/25 18:26:34 Preparing external:ubuntu-core-16-arm-64:tests/main/change-errors...
2016/10/25 18:26:35 Executing external:ubuntu-core-16-arm-64:tests/main/change-errors...
2016/10/25 18:26:35 Restoring external:ubuntu-core-16-arm-64:tests/main/change-errors...
2016/10/25 18:26:59 Preparing external:ubuntu-core-16-arm-64:tests/main/interfaces-hardware-observe...
2016/10/25 18:27:07 Executing external:ubuntu-core-16-arm-64:tests/main/interfaces-hardware-observe...
2016/10/25 18:27:10 Restoring external:ubuntu-core-16-arm-64:tests/main/interfaces-hardware-observe...
2016/10/25 18:27:28 Preparing external:ubuntu-core-16-arm-64:tests/main/security-profiles...
2016/10/25 18:27:31 Executing external:ubuntu-core-16-arm-64:tests/main/security-profiles...
2016/10/25 18:27:46 Restoring external:ubuntu-core-16-arm-64:tests/main/security-profiles...
2016/10/25 18:28:17 Preparing external:ubuntu-core-16-arm-64:tests/main/snap-run-symlink-error...
2016/10/25 18:28:18 Executing external:ubuntu-core-16-arm-64:tests/main/snap-run-symlink-error...
2016/10/25 18:28:18 Restoring external:ubuntu-core-16-arm-64:tests/main/snap-run-symlink-error...
2016/10/25 18:28:35 Preparing external:ubuntu-core-16-arm-64:tests/main/interfaces-udev...
2016/10/25 18:28:45 Executing external:ubuntu-core-16-arm-64:tests/main/interfaces-udev...
2016/10/25 18:28:49 Restoring external:ubuntu-core-16-arm-64:tests/main/interfaces-udev...
2016/10/25 18:29:12 Preparing external:ubuntu-core-16-arm-64:tests/main/install-store-laaaarge...
2016/10/25 18:29:13 Executing external:ubuntu-core-16-arm-64:tests/main/install-store-laaaarge...
2016/10/25 18:29:26 Restoring external:ubuntu-core-16-arm-64:tests/main/install-store-laaaarge...
2016/10/25 18:29:48 Preparing external:ubuntu-core-16-arm-64:tests/main/ubuntu-core-uboot:initrdimg...
2016/10/25 18:29:49 Executing external:ubuntu-core-16-arm-64:tests/main/ubuntu-core-uboot:initrdimg...
2016/10/25 18:29:49 Restoring external:ubuntu-core-16-arm-64:tests/main/ubuntu-core-uboot:initrdimg...
2016/10/25 18:30:08 Preparing external:ubuntu-core-16-arm-64:tests/main/ubuntu-core-uboot:kernelimg...
2016/10/25 18:30:09 Executing external:ubuntu-core-16-arm-64:tests/main/ubuntu-core-uboot:kernelimg...
2016/10/25 18:30:09 Restoring external:ubuntu-core-16-arm-64:tests/main/ubuntu-core-uboot:kernelimg...
2016/10/25 18:30:28 Preparing external:ubuntu-core-16-arm-64:tests/main/interfaces-network-observe...
2016/10/25 18:30:39 Executing external:ubuntu-core-16-arm-64:tests/main/interfaces-network-observe...
2016/10/25 18:30:44 Restoring external:ubuntu-core-16-arm-64:tests/main/interfaces-network-observe...
2016/10/25 18:31:12 Preparing external:ubuntu-core-16-arm-64:tests/main/snap-download...
2016/10/25 18:31:12 Executing external:ubuntu-core-16-arm-64:tests/main/snap-download...
2016/10/25 18:31:26 Restoring external:ubuntu-core-16-arm-64:tests/main/snap-download...
2016/10/25 18:31:42 Preparing external:ubuntu-core-16-arm-64:tests/main/ubuntu-core-fan...
2016/10/25 18:31:46 Executing external:ubuntu-core-16-arm-64:tests/main/ubuntu-core-fan...
2016/10/25 18:31:46 Restoring external:ubuntu-core-16-arm-64:tests/main/ubuntu-core-fan...
2016/10/25 18:32:09 Preparing external:ubuntu-core-16-arm-64:tests/main/interfaces-content...
2016/10/25 18:32:26 Executing external:ubuntu-core-16-arm-64:tests/main/interfaces-content...
2016/10/25 18:32:29 Restoring external:ubuntu-core-16-arm-64:tests/main/interfaces-content...
2016/10/25 18:32:52 Preparing external:ubuntu-core-16-arm-64:tests/main/revert-devmode:fake...
2016/10/25 18:32:54 Executing external:ubuntu-core-16-arm-64:tests/main/revert-devmode:fake...
2016/10/25 18:32:54 Restoring external:ubuntu-core-16-arm-64:tests/main/revert-devmode:fake...
2016/10/25 18:33:11 Preparing external:ubuntu-core-16-arm-64:tests/main/revert-devmode:production...
2016/10/25 18:33:23 Executing external:ubuntu-core-16-arm-64:tests/main/revert-devmode:production...
2016/10/25 18:34:01 Restoring external:ubuntu-core-16-arm-64:tests/main/revert-devmode:production...
2016/10/25 18:34:28 Preparing external:ubuntu-core-16-arm-64:tests/main/interfaces-log-observe...
2016/10/25 18:34:36 Executing external:ubuntu-core-16-arm-64:tests/main/interfaces-log-observe...
2016/10/25 18:34:44 Restoring external:ubuntu-core-16-arm-64:tests/main/interfaces-log-observe...
2016/10/25 18:35:04 Preparing external:ubuntu-core-16-arm-64:tests/main/enable-disable...
2016/10/25 18:35:05 Executing external:ubuntu-core-16-arm-64:tests/main/enable-disable...
2016/10/25 18:35:19 Restoring external:ubuntu-core-16-arm-64:tests/main/enable-disable...
2016/10/25 18:35:44 Preparing external:ubuntu-core-16-arm-64:tests/main/systemd-service...
2016/10/25 18:35:45 Executing external:ubuntu-core-16-arm-64:tests/main/systemd-service...
2016/10/25 18:37:54 Restoring external:ubuntu-core-16-arm-64:tests/main/systemd-service...
2016/10/25 18:38:22 Preparing external:ubuntu-core-16-arm-64:tests/main/interfaces-bluez...
2016/10/25 18:38:22 Executing external:ubuntu-core-16-arm-64:tests/main/interfaces-bluez...
2016/10/25 18:38:38 Restoring external:ubuntu-core-16-arm-64:tests/main/interfaces-bluez...
2016/10/25 18:38:58 Preparing external:ubuntu-core-16-arm-64:tests/main/known...
2016/10/25 18:39:00 Executing external:ubuntu-core-16-arm-64:tests/main/known...
2016/10/25 18:39:00 Restoring external:ubuntu-core-16-arm-64:tests/main/known...
2016/10/25 18:39:19 Preparing external:ubuntu-core-16-arm-64:tests/main/listing...
2016/10/25 18:39:32 Executing external:ubuntu-core-16-arm-64:tests/main/listing...
2016/10/25 18:39:33 Restoring external:ubuntu-core-16-arm-64:tests/main/listing...
2016/10/25 18:39:58 Restoring external:ubuntu-core-16-arm-64:tests/main/...
2016/10/25 18:40:13 Successful tasks: 75
2016/10/25 18:40:13 Aborted tasks: 2
2016/10/25 18:40:13 Failed tasks: 2
    - external:ubuntu-core-16-arm-64:tests/main/ubuntu-core-device-reg
    - external:ubuntu-core-16-arm-64:tests/main/ubuntu-core-reboot
2016/10/25 18:40:13 Failed task prepare: 2
    - external:ubuntu-core-16-arm-64:tests/main/interfaces-fuse_support
    - external:ubuntu-core-16-arm-64:tests/main/server-snap:goServer
error: unsuccessful run