pawel@mordor ~/gocode/src/github.com/snapcore/snapd (baa8096*) $ spread -v -debug -reuse -repeat 10 external:ubuntu-core-16-arm-32:tests/main/ubuntu-core-upgrade
2017-10-16 11:01:18 Found /home/pawel/gocode/src/github.com/snapcore/snapd/spread.yaml.
2017-10-16 11:01:20 Project content is packed for delivery (2.72MB).
2017-10-16 11:01:20 Reusing external:ubuntu-core-16-arm-32...
2017-10-16 11:01:22 Reusing project data on external:ubuntu-core-16-arm-32...
2017-10-16 11:01:22 Preparing project on external:ubuntu-core-16-arm-32...
2017-10-16 11:01:27 Preparing external:ubuntu-core-16-arm-32:tests/main/...
2017-10-16 11:01:32 Preparing external:ubuntu-core-16-arm-32:tests/main/ubuntu-core-upgrade...
2017-10-16 11:02:34 Executing external:ubuntu-core-16-arm-32:tests/main/ubuntu-core-upgrade (1/1)...
2017-10-16 11:02:35 Error executing external:ubuntu-core-16-arm-32:tests/main/ubuntu-core-upgrade : 
-----
+ . /home/gopath/src/github.com/snapcore/snapd/tests/lib/boot.sh
+ '[' 0 '!=' 0 ']'
+ snap list
+ awk '/^core / {print($3)}'
+ '[' -f curChg ']'
++ cat curChg
+ snap watch 11
error: cannot find change with id "11"
-----
.
2017-10-16 11:02:35 Debug output for external:ubuntu-core-16-arm-32:tests/main/ubuntu-core-upgrade : 
-----
+ snap list
Name        Version        Rev   Developer  Notes
core        16-2.28.5      3214  canonical  core
pi2-kernel  4.4.0.1075.75  43    canonical  kernel
pi3         16.04-0.5      6     canonical  gadget
+ . /home/gopath/src/github.com/snapcore/snapd/tests/lib/boot.sh
+ bootenv
+ '[' 0 -eq 0 ']'
+ command -v grub-editenv
+ fw_printenv
arch=arm
baudrate=115200
boot_a_script=load ${devtype} ${devnum}:${bootpart} ${scriptaddr} ${prefix}${script}; source ${scriptaddr}
boot_extlinux=sysboot ${devtype} ${devnum}:${bootpart} any ${scriptaddr} ${prefix}extlinux/extlinux.conf
boot_prefixes=/ /boot/
boot_script_dhcp=boot.scr.uimg
boot_scripts=boot.scr.uimg boot.scr
boot_targets=mmc0 usb0 pxe dhcp 
bootcmd=run distro_bootcmd
bootcmd_dhcp=usb start; if dhcp ${scriptaddr} ${boot_script_dhcp}; then source ${scriptaddr}; fi
bootcmd_mmc0=setenv devnum 0; run mmc_boot
bootcmd_pxe=usb start; dhcp; if pxe get; then pxe boot; fi
bootcmd_usb0=setenv devnum 0; run usb_boot
bootdelay=2
bootenv=uEnv.txt
cpu=armv7
devnum=0
devtype=mmc
dhcpuboot=usb start; dhcp u-boot.uimg; bootm
distro_bootcmd=for target in ${boot_targets}; do run bootcmd_${target}; done
fdt_addr_r=0x00000100
fdtaddr=0x02000000
fdtfile=bcm2709-rpi-2-b.dtb
filesize=1c5
importbootenv=echo Importing environment from mmc ...; env import -t -r $loadaddr $filesize
initrd_addr=0x02100000
initrd_file=initrd.img
kernel_addr_r=0x01000000
kernel_file=kernel.img
loadaddr=0x00200000
loadbootenv=echo LOADBOOTENV; load ${devtype} ${devnum} ${loadaddr} ${bootenv}
loadfdt=fdt addr 0x02000000; fdt get value args /chosen bootargs
loadfiles=run loadkernel; run loadinitrd; run loadfdt
loadinitrd=load mmc ${mmcdev}:${mmcpart} ${initrd_addr} ${snap_kernel}/${initrd_file}; setenv initrd_size ${filesize}
loadkernel=load mmc ${mmcdev}:${mmcpart} ${loadaddr} ${snap_kernel}/${kernel_file}
mmc_boot=run loadfdt; if mmc dev ${devnum}; then setenv devtype mmc; if run loadbootenv; then echo Loaded environment from ${bootenv}; run importbootenv; fi; if test -n "$uenvcmd"; then echo Running uenvcmd ...; run uenvcmd; fi; run scan_dev_for_boot_part; fi
mmcargs=setenv bootargs "${args} root=${mmcroot}"
mmcdev=0
mmcpart=1
mmcrootfstype=ext4
pxefile_addr_r=0x00100000
ramdisk_addr_r=0x02100000
scan_dev_for_boot=echo Scanning ${devtype} ${devnum}:${bootpart}...; for prefix in ${boot_prefixes}; do run scan_dev_for_extlinux; run scan_dev_for_scripts; done
scan_dev_for_boot_part=part list ${devtype} ${devnum} devplist; for bootpart in ${devplist}; do if fstype ${devtype} ${devnum}:${bootpart} bootfstype; then run scan_dev_for_boot; fi; done
scan_dev_for_extlinux=if test -e ${devtype} ${devnum}:${bootpart} ${prefix}extlinux/extlinux.conf; then echo Found ${prefix}extlinux/extlinux.conf; run boot_extlinux; echo SCRIPT FAILED: continuing...; fi
scan_dev_for_scripts=for script in ${boot_scripts}; do if test -e ${devtype} ${devnum}:${bootpart} ${prefix}${script}; then echo Found U-Boot script ${prefix}${script}; run boot_a_script; echo SCRIPT FAILED: continuing...; fi; done
scriptaddr=0x00000000
snap_core=core_3214.snap
snap_kernel=pi2-kernel_43.snap
snappy_boot=if test "${snap_mode}" = "try"; then setenv snap_mode "trying"; saveenv; if test "${snap_try_core}" != ""; then setenv snap_core "${snap_try_core}"; fi; if test "${snap_try_kernel}" != ""; then setenv snap_kernel "${snap_try_kernel}"; fi; elif test "${snap_mode}" = "trying"; then setenv snap_mode ""; saveenv; fi; run loadfiles; setenv mmcroot "/dev/disk/by-label/writable ${snappy_cmdline} snap_core=${snap_core} snap_kernel=${snap_kernel}"; run mmcargs; bootz ${loadaddr} ${initrd_addr}:${initrd_size} 0x02000000
snappy_cmdline=net.ifnames=0 init=/lib/systemd/systemd ro panic=-1 fixrtc
snappy_mode=
soc=bcm2835
stderr=serial,lcd
stdin=serial,usbkbd
stdout=serial,lcd
uenvcmd=echo ENVCMD; run snappy_boot
usb_boot=usb start; if usb dev ${devnum}; then setenv devtype usb; run scan_dev_for_boot_part; fi
vendor=raspberrypi
+ cat /proc/cmdline
8250.nr_uarts=1 dma.dmachans=0x7f35 bcm2708_fb.fbwidth=656 bcm2708_fb.fbheight=416 bcm2709.boardrev=0xa02082 bcm2709.serial=0x569fef42 smsc95xx.macaddr=B8:27:EB:9F:EF:42 bcm2708_fb.fbswap=1 bcm2709.uart_clock=48000000 vc_mem.mem_base=0x3dc00000 vc_mem.mem_size=0x3f000000  dwc_otg.lpm_enable=0 console=ttyS0,115200 console=tty0 elevator=deadline root=/dev/disk/by-label/writable net.ifnames=0 init=/lib/systemd/systemd ro panic=-1 fixrtc snap_core=core_3214.snap snap_kernel=pi2-kernel_43.snap
+ echo '# journal messages for snapd'
# journal messages for snapd
+ journalctl -u snapd
-- Logs begin at Mon 2017-10-16 09:01:34 UTC, end at Mon 2017-10-16 09:02:36 UTC. --
Oct 16 09:01:34 localhost.localdomain systemd[1]: Stopping Snappy daemon...
Oct 16 09:01:34 localhost.localdomain snapd[991]: 2017/10/16 09:01:34.377105 main.go:78: Exiting on terminated signal.
Oct 16 09:01:34 localhost.localdomain systemd[1]: Stopped Snappy daemon.
Oct 16 09:02:28 localhost.localdomain systemd[1]: Starting Snappy daemon...
Oct 16 09:02:28 localhost.localdomain snapd[2306]: 2017/10/16 09:02:28.847084 daemon.go:290: started snapd/2.28.5 (series 16) ubuntu-core/16 (armhf) linux/4.4.0-1075-raspi2.
Oct 16 09:02:28 localhost.localdomain systemd[1]: Started Snappy daemon.
+ echo '# apparmor denials '
# apparmor denials 
+ dmesg --ctime
+ grep DENIED
+ true
+ echo '# seccomp denials (kills) '
# seccomp denials (kills) 
+ dmesg --ctime
+ grep type=1326
+ true
+ echo '# snap interfaces'
# snap interfaces
+ snap interfaces
Slot                      Plug
:account-control          -
:alsa                     -
:autopilot-introspection  -
:bluetooth-control        -
:broadcom-asic-control    -
:browser-support          -
:camera                   -
:classic-support          -
:core-support             core:core-support-plug
:dcdbas-control           -
:docker-support           -
:firewall-control         -
:framebuffer              -
:fuse-support             -
:greengrass-support       -
:hardware-observe         -
:hardware-random-control  -
:hardware-random-observe  -
:home                     -
:io-ports-control         -
:joystick                 -
:kernel-module-control    -
:kubernetes-support       -
:kvm                      -
:log-observe              -
:lxd-support              -
:mount-observe            -
:netlink-audit            -
:netlink-connector        -
:network                  -
:network-bind             -
:network-control          -
:network-observe          -
:network-setup-control    -
:network-setup-observe    -
:opengl                   -
:openvswitch-support      -
:physical-memory-control  -
:physical-memory-observe  -
:ppp                      -
:process-control          -
:raw-usb                  -
:removable-media          -
:shutdown                 -
:snapd-control            -
:system-observe           -
:system-trace             -
:time-control             -
:timeserver-control       -
:timezone-control         -
:tpm                      -
:uhid                     -
-----
.
2017-10-16 11:02:35 Starting shell to debug...
external:ubuntu-core-16-arm-32 .../tests/main/ubuntu-core-upgrade#