balena-os / balena-engine

Moby-based Container Engine for Embedded, IoT, and Edge uses
https://www.balena.io
Apache License 2.0
691 stars 66 forks source link

SIGSEGV: segmentation violation code / Segfault from boltdb/bolt.(*Bucket).openBucket() => runtime.newobject() => runtime.mallocgc() on an Intel Edison board with long uptime #223

Closed trinitronx closed 3 years ago

trinitronx commented 4 years ago

Description

Balena Engine / (moby) seems to log many messages about bytes of blob data. Otherwise, things seem to work fine on this board (Intel Edison with BalenaOS v2.31.5+rev1.

After leaving BalenaOS running for a long time balena.service stuck in SystemD crashloop with segfaults during startup.

Steps to reproduce the issue:

  1. Boot BalenaOS (it works and balena.service is functional)
  2. Leave it running for a while (usually a day or two)
  3. Come back and check on it... balena.service is in SystemD crashloop logging SIGSEGV segfaults

Describe the results you received:

balena.service crashed with SIGSEGV, SystemD tries to restart but keeps failing. It then just gets stuck in this crashloop indefinitely until a reboot.

Describe the results you expected:

balena.service should start up normally and stay running on a system with long uptime

Additional information you deem important (e.g. issue happens only occasionally):

Note: The issue manifests as SIGSEGV errors in balena-engine after a board is left running for a long time. After a reboot, everything functions normally again. I've been able to reproduce this by leaving the board up and running for a couple days.

Since this seems related to memory allocation, I tried looking at the Intel Edison board's memory info while balena.service was experiencing the SIGSEGV crashloop:

# free 
             total       used       free     shared    buffers     cached
Mem:        979212     794424     184788      10156      36752      91988
-/+ buffers/cache:     665684     313528
Swap:            0          0          0

# cat /proc/meminfo 
MemTotal:         979212 kB
MemFree:          184404 kB
MemAvailable:     289316 kB
Buffers:           36752 kB
Cached:            91988 kB
SwapCached:            0 kB
Active:           129708 kB
Inactive:          30356 kB
Active(anon):      31652 kB
Inactive(anon):     9828 kB
Active(file):      98056 kB
Inactive(file):    20528 kB
Unevictable:           0 kB
Mlocked:               0 kB
HighTotal:        132104 kB
HighFree:          10736 kB
LowTotal:         847108 kB
LowFree:          173668 kB
SwapTotal:             0 kB
SwapFree:              0 kB
Dirty:                 4 kB
Writeback:             0 kB
AnonPages:         31380 kB
Mapped:            38264 kB
Shmem:             10156 kB
Slab:             626672 kB
SReclaimable:       9996 kB
SUnreclaim:       616676 kB
KernelStack:         832 kB
PageTables:          744 kB
NFS_Unstable:          0 kB
Bounce:                0 kB
WritebackTmp:          0 kB
CommitLimit:      489604 kB
Committed_AS:     388252 kB
VmallocTotal:     122880 kB
VmallocUsed:           0 kB
VmallocChunk:          0 kB
HugePages_Total:       0
HugePages_Free:        0
HugePages_Rsvd:        0
HugePages_Surp:        0
Hugepagesize:       4096 kB
Hugetlb:               0 kB
DirectMap4k:       77816 kB
DirectMap4M:      794624 kB

Output from top:

Mem: 791496K used, 187716K free, 10152K shrd, 36752K buff, 91984K cached
CPU:   2% usr   3% sys   0% nic  93% idle   0% io   0% irq   0% sirq
Load average: 0.19 0.17 0.12 3/97 13782
  PID  PPID USER     STAT   VSZ %VSZ %CPU COMMAND
13761     1 root     S     843m  88%   4% /usr/bin/balenad --experimental --log-driver=journald -s aufs -H fd:// -H unix:///var/run/balena.sock -H unix:///var/run/balena-engine.sock --dns 10.114.102.1 --bip 10.1
  762     1 avahi    S     5836   1%   2% avahi-daemon: running [d816afd-3.local]
    8     2 root     IW       0   0%   0% [rcu_sched]
13781  6595 root     R     3788   0%   0% top
13779     2 root     IW       0   0%   0% [kworker/u4:2]
    1     0 root     S    26988   3%   0% {systemd} /sbin/init
  672     1 root     S     6016   1%   0% /lib/systemd/systemd-logind
  686     1 messageb S     5544   1%   0% /usr/bin/dbus-daemon --system --address=systemd: --nofork --nopidfile --systemd-activation --syslog-only
 6594     1 root     S     3148   0%   0% /usr/sbin/dropbear -i -r /etc/dropbear/dropbear_rsa_host_key -s
  248     2 root     SW       0   0%   0% [irq/38-mmc2]
  631     2 root     SW       0   0%   0% [brcmf_wdog/mmc2]
12339     2 root     IW       0   0%   0% [kworker/0:2]
13767 13763 root     S     834m  87%   0% balena info
  747     1 root     S    72680   7%   0% /usr/sbin/NetworkManager --no-daemon
  702     1 root     S    51468   5%   0% /usr/sbin/ModemManager --log-journal
  477     1 root     S    34020   3%   0% /lib/systemd/systemd-journald
  714     1 root     S    13044   1%   0% /usr/sbin/chronyd -s -d
  560     1 root     S    12412   1%   0% /lib/systemd/systemd-udevd
  817     1 root     S    11428   1%   0% /usr/sbin/wpa_supplicant -u
  863     1 root     S     7212   1%   0% /usr/libexec/bluetooth/bluetoothd --experimental
24694     1 openvpn  S     6568   1%   0% /usr/sbin/openvpn --writepid /var/run/openvpn/openvpn.pid --cd /etc/openvpn/ --config /etc/openvpn/openvpn.conf --connect-retry 5 120
13762 13761 root     S     5480   1%   0% /proc/self/exe --healthcheck /usr/lib/balena/balena-healthcheck --pid 13761
  767   762 avahi    S     5320   1%   0% avahi-daemon: chroot helper
  661     1 root     S     5168   1%   0% @sbin/plymouthd --tty=tty1 --mode=boot --pid-file=/run/plymouth/pid --attach-to-session --kernel-command-line=plymouth.ignore-serial-consoles splash
  704     1 root     S     3976   0%   0% /usr/sbin/bluetooth_rfkill_event
 6595  6594 root     S     3860   0%   0% -sh
13763 13762 root     S     3760   0%   0% {balena-healthch} /bin/sh /usr/lib/balena/balena-healthcheck
  770     1 nobody   S     2708   0%   0% /usr/bin/dnsmasq -x /run/dnsmasq.pid -a 127.0.0.2,10.114.102.1 -7 /etc/dnsmasq.d/ -r /etc/resolv.dnsmasq -z --servers-file=/run/dnsmasq.servers -k --log-facility=-
  670     1 root     S     2376   0%   0% /usr/bin/btattach -B /dev/ttyS0 -P bcm -S 3000000
   14     2 root     SW       0   0%   0% [ksoftirqd/1]
   34     2 root     SW       0   0%   0% [kswapd0]
    7     2 root     SW       0   0%   0% [ksoftirqd/0]
  781     2 root     SW       0   0%   0% [jbd2/mmcblk0p11]
26768     2 root     IW<      0   0%   0% [kworker/1:1H]
22369     2 root     IW<      0   0%   0% [kworker/0:2H]
   13     2 root     SW       0   0%   0% [migration/1]
   10     2 root     SW       0   0%   0% [migration/0]
12726     2 root     IW       0   0%   0% [kworker/1:0]
    2     0 root     SW       0   0%   0% [kthreadd]
12875     2 root     IW       0   0%   0% [kworker/u4:0]
  425     2 root     SW       0   0%   0% [jbd2/mmcblk0p10]
13332     2 root     IW       0   0%   0% [kworker/u4:1]
   23     2 root     SW       0   0%   0% [kcompactd0]
  692     2 root     IW<      0   0%   0% [kworker/u5:2]
   17     2 root     SW       0   0%   0% [kdevtmpfs]
   31     2 root     IW<      0   0%   0% [kworker/u5:0]
    6     2 root     IW<      0   0%   0% [mm_percpu_wq]
    9     2 root     IW       0   0%   0% [rcu_bh]
   11     2 root     SW       0   0%   0% [cpuhp/0]
   12     2 root     SW       0   0%   0% [cpuhp/1]
   18     2 root     IW<      0   0%   0% [netns]

After a reboot, while balena.service is up and working:

# free
             total       used       free     shared    buffers     cached
Mem:        979208     675708     303500      10280      17440     330372
-/+ buffers/cache:     327896     651312
Swap:            0          0          0

# cat /proc/meminfo 
MemTotal:         979208 kB
MemFree:          303252 kB
MemAvailable:     636736 kB
Buffers:           17456 kB
Cached:           330364 kB
SwapCached:            0 kB
Active:           218416 kB
Inactive:         257264 kB
Active(anon):     134424 kB
Inactive(anon):     3712 kB
Active(file):      83992 kB
Inactive(file):   253552 kB
Unevictable:      147844 kB
Mlocked:          147844 kB
HighTotal:        132104 kB
HighFree:            224 kB
LowTotal:         847104 kB
LowFree:          303028 kB
SwapTotal:             0 kB
SwapFree:              0 kB
Dirty:                 4 kB
Writeback:             4 kB
AnonPages:        275700 kB
Mapped:            98592 kB
Shmem:             10280 kB
Slab:              42312 kB
SReclaimable:      19608 kB
SUnreclaim:        22704 kB
KernelStack:        1496 kB
PageTables:         2044 kB
NFS_Unstable:          0 kB
Bounce:                0 kB
WritebackTmp:          0 kB
CommitLimit:      489604 kB
Committed_AS:    1140728 kB
VmallocTotal:     122880 kB
VmallocUsed:           0 kB
VmallocChunk:          0 kB
HugePages_Total:       0
HugePages_Free:        0
HugePages_Rsvd:        0
HugePages_Surp:        0
Hugepagesize:       4096 kB
Hugetlb:               0 kB
DirectMap4k:       65528 kB
DirectMap4M:      806912 kB

After a reboot, here is top output while balena.service is working:

Mem: 676492K used, 302716K free, 10280K shrd, 17504K buff, 330364K cached
CPU:   4% usr   8% sys   0% nic  87% idle   0% io   0% irq   0% sirq
Load average: 0.18 0.46 0.39 2/188 5495
  PID  PPID USER     STAT   VSZ %VSZ %CPU COMMAND
 5494  3140 root     R     3748   0%   8% top
  777     1 root     S     917m  96%   0% /usr/bin/balenad --experimental --log-driver=journald -s aufs -H fd:// -H unix:///var/run/balena.sock -H unix:///var/run/balena-engine.sock --dns 10.114.102.1 --bip 10.1
  804   777 root     S     867m  91%   0% balena-engine-containerd --config /var/run/balena-engine/containerd/containerd.toml
 1052   804 root     S     859m  90%   0% balena-engine-containerd-shim -namespace moby -workdir /var/lib/docker/containerd/daemon/io.containerd.runtime.v1.linux/moby/a9632da37c7a73bd1525eba38f7270d3a5ec0749bbc2
 1531   804 root     S     858m  90%   0% balena-engine-containerd-shim -namespace moby -workdir /var/lib/docker/containerd/daemon/io.containerd.runtime.v1.linux/moby/404fbc488f413f253bb33210d5bee40a64250c505fc0
 1450  1239 root     S     851m  89%   0% balena start --attach resin_supervisor
 1671  1660 root     S     186m  19%   0% fluidsynth -s -a alsa -g 3 /usr/share/sounds/sf2/FluidR3_GM.sf2 /usr/share/sounds/sf2/FluidR3_GS.sf2
 1566  1531 root     S     138m  14%   0% node /usr/src/app/dist/app.js
 1819  1662 root     S     128m  13%   0% npm
 1659  1656 root     S    80644   8%   0% node cylon-processes/cylon-led.js
 1912  1911 root     S    79492   8%   0% node grove-lcd-main.js
  752     1 root     S    56064   6%   0% /usr/sbin/NetworkManager --no-daemon
  673     1 root     S    51472   5%   0% /usr/sbin/ModemManager --log-journal
 1769  1666 root     S    49756   5%   0% /usr/bin/python /usr/local/bin/touchosc2midi -v --midi-in 0 --midi-out 0 --ip 0.0.0.0
  479     1 root     S    33016   3%   0% /lib/systemd/systemd-journald
    1     0 root     S    25880   3%   0% {systemd} /sbin/init
  709     1 root     S    13044   1%   0% /usr/sbin/chronyd -s -d
  560     1 root     S    12412   1%   0% /lib/systemd/systemd-udevd
  827     1 root     S    11304   1%   0% /usr/sbin/wpa_supplicant -u
 1395  1066 root     S    11280   1%   0% /lib/systemd/systemd-udevd --daemon
  914     1 root     S     7212   1%   0% /usr/libexec/bluetooth/bluetoothd --experimental
  771     1 openvpn  S     6496   1%   0% /usr/sbin/openvpn --writepid /var/run/openvpn/openvpn.pid --cd /etc/openvpn/ --config /etc/openvpn/openvpn.conf --connect-retry 5 120
  672     1 root     S     6016   1%   0% /lib/systemd/systemd-logind
  767     1 avahi    S     5584   1%   0% avahi-daemon: registering [d816afd-67.local]
  780   777 root     S     5480   1%   0% /proc/self/exe --healthcheck /usr/lib/balena/balena-healthcheck --pid 777
 1254  1239 root     S     5480   1%   0% /proc/self/exe --healthcheck /usr/lib/resin-supervisor/resin-supervisor-healthcheck --pid 1239
  656     1 messageb S     5412   1%   0% /usr/bin/dbus-daemon --system --address=systemd: --nofork --nopidfile --systemd-activation --syslog-only
  774   767 avahi    S     5320   1%   0% avahi-daemon: chroot helper
  649     1 root     S     5168   1%   0% @sbin/plymouthd --tty=tty1 --mode=boot --pid-file=/run/plymouth/pid --attach-to-session --kernel-command-line=plymouth.ignore-serial-consoles splash
  670     1 root     S     3976   0%   0% /usr/sbin/bluetooth_rfkill_event
 3140  3128 root     S     3860   0%   0% -sh
 1239     1 root     S     3760   0%   0% {start-resin-sup} /bin/sh /usr/bin/start-resin-supervisor
 1624  1566 redsocks S     3484   0%   0% avahi-daemon: running [d816afd-2.local]
 1625  1624 redsocks S     3228   0%   0% avahi-daemon: chroot helper
 3128     1 root     S     3148   0%   0% /usr/sbin/dropbear -i -r /etc/dropbear/dropbear_rsa_host_key -s
 1618  1566 messageb S     2944   0%   0% /usr/bin/dbus-daemon --system
  775     1 nobody   S     2708   0%   0% /usr/bin/dnsmasq -x /run/dnsmasq.pid -a 127.0.0.2,10.114.102.1 -7 /etc/dnsmasq.d/ -r /etc/resolv.dnsmasq -z --servers-file=/run/dnsmasq.servers -k --log-facility=-
 1911  1819 root     S     2376   0%   0% sh -c node grove-lcd-main.js
  676     1 root     S     2376   0%   0% /usr/bin/btattach -B /dev/ttyS0 -P bcm -S 3000000
 1662  1655 root     S     2372   0%   0% {run_main.sh} /bin/sh /usr/src/app/run_main.sh
 1660  1653 root     S     2372   0%   0% /bin/sh ./run
 1666  1657 root     S     2372   0%   0% /bin/sh ./run
 1120  1119 daemon   S      492   0%   0% s6-fdholderd -1 -i rules
 1654  1066 root     S      208   0%   0% s6-supervise gizmophone
 1119  1066 root     S      208   0%   0% s6-supervise s6-fdholderd
 1653  1066 root     S      208   0%   0% s6-supervise fluidsynth-server
 1655  1066 root     S      208   0%   0% s6-supervise grove-lcd
 1656  1066 root     S      208   0%   0% s6-supervise cylon-led
 1657  1066 root     S      208   0%   0% s6-supervise touchosc2midi-server
 1066  1052 root     S      204   0%   0% s6-svscan -t0 /var/run/s6/services
    8     2 root     IW       0   0%   0% [rcu_sched]

Output of balena-engine version:

While balena.service was in crashloop:

# balena-engine version
Client:
 Version:       17.12.0-dev
 API version:   1.35
 Go version:    go1.9.7
 Git commit:    fe78e2c9a69313007c53c83fff4b5525fbc2ba45
 Built: Fri Mar  1 17:29:39 2019
 OS/Arch:       linux/386
 Experimental:  false
 Orchestrator:  swarm
Cannot connect to the balenaEngine daemon at unix:///var/run/balena-engine.sock. Is the balenaEngine daemon running?

After a reboot:

# balena-engine version 
Client:
 Version:       17.12.0-dev
 API version:   1.35
 Go version:    go1.9.7
 Git commit:    fe78e2c9a69313007c53c83fff4b5525fbc2ba45
 Built: Fri Mar  1 17:29:39 2019
 OS/Arch:       linux/386
 Experimental:  false
 Orchestrator:  swarm

Server:
 Engine:
  Version:      17.12.0-dev
  API version:  1.35 (minimum version 1.12)
  Go version:   go1.9.7
  Git commit:   fe78e2c9a69313007c53c83fff4b5525fbc2ba45
  Built:        Fri Mar  1 17:29:39 2019
  OS/Arch:      linux/386
  Experimental: true

Output of balena-engine info:

While balena.service was in crashloop:

# balena-engine info 
Cannot connect to the balenaEngine daemon at unix:///var/run/balena-engine.sock. Is the balenaEngine daemon running?

After a reboot:

# balena-engine info
Containers: 2
 Running: 2
 Paused: 0
 Stopped: 0
Images: 2
Server Version: 17.12.0-dev
Storage Driver: aufs
 Root Dir: /var/lib/docker/aufs
 Backing Filesystem: extfs
 Dirs: 28
 Dirperm1 Supported: true
Logging Driver: journald
Cgroup Driver: cgroupfs
Plugins:
 Volume: local
 Network: bridge host ipvlan null
 Log: journald json-file
Swarm: 
 NodeID: 
 Is Manager: false
 Node Address: 
Runtimes: bare runc
Default Runtime: runc
Init Binary: balena-engine-init
containerd version: 
runc version: 13e66eedaddfbfeda2a73d23701000e4e63b5471
init version: 949e6fa-dirty (expected: 949e6facb7738)
Kernel Version: 4.16.0-edison-standard
Operating System: balenaOS 2.31.5+rev1
OSType: linux
Architecture: i686
CPUs: 2
Total Memory: 956.3MiB
Name: d816afd
ID: OCWY:CXRY:36LH:5IXS:SQK2:OXW5:FHH5:3PUO:X6CL:JUWM:7CZE:II6Y
Docker Root Dir: /var/lib/docker
Debug Mode (client): false
Debug Mode (server): false
Registry: https://index.docker.io/v1/
Labels:
Experimental: true
Insecure Registries:
 127.0.0.0/8
Live Restore Enabled: false

Additional environment details (device type, OS, etc.):

JournalD logs for balena.service

balena-engine is in crashloop, failing with a GoLang stacktrace & SIGSEGV / segmentation fault. Looks like it's something during startup related to some library called "boltdb". The calls from boltdb/bolt(*Bucket).openBucket() try to allocate memory via runtime.mallocgc() which calls runtime.heapBitsSetType(). No idea why it's crashing

Jul 05 22:41:31 d816afd systemd[1]: balena.service: Failed with result 'exit-code'.
Jul 05 22:41:31 d816afd systemd[1]: Failed to start Balena Application Container Engine.
Jul 05 22:41:31 d816afd systemd[1]: balena.service: Service hold-off time over, scheduling restart.
Jul 05 22:41:31 d816afd systemd[1]: balena.service: Scheduled restart job, restart counter is at 32590.
Jul 05 22:41:31 d816afd systemd[1]: Stopped Balena Application Container Engine.
Jul 05 22:41:31 d816afd systemd[1]: Starting Balena Application Container Engine...
Jul 05 22:41:31 d816afd balenad[9694]: time="2020-07-05T22:41:31Z" level=warning msg="Running experimental build"
Jul 05 22:41:31 d816afd balenad[9694]: time="2020-07-05T22:41:31.942123497Z" level=info msg="libcontainerd: started new balena-engine-containerd process" pid=9706
Jul 05 22:41:32 d816afd balenad[9694]: time="2020-07-05T22:41:32Z" level=info msg="starting containerd" module=containerd revision= version=1.0.0+unknown
Jul 05 22:41:32 d816afd balenad[9694]: time="2020-07-05T22:41:32Z" level=info msg="setting subreaper..." module=containerd
Jul 05 22:41:32 d816afd balenad[9694]: time="2020-07-05T22:41:32Z" level=info msg="changing OOM score to -500" module=containerd
Jul 05 22:41:32 d816afd balenad[9694]: time="2020-07-05T22:41:32Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." module=containerd type=io.containerd.content.v1
Jul 05 22:41:32 d816afd balenad[9694]: time="2020-07-05T22:41:32Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." module=containerd type=io.containerd.snapshotter.v1
Jul 05 22:41:32 d816afd balenad[9694]: time="2020-07-05T22:41:32Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." module=containerd type=io.containerd.metadata.v1
Jul 05 22:41:32 d816afd balenad[9694]: fatal error: unexpected signal during runtime execution
Jul 05 22:41:32 d816afd balenad[9694]: [signal SIGSEGV: segmentation violation code=0x1 addr=0x9913c2bb pc=0x805c703]
Jul 05 22:41:32 d816afd balenad[9694]: goroutine 1 [running]:
Jul 05 22:41:32 d816afd balenad[9694]: runtime.throw(0x9022512, 0x2a)
Jul 05 22:41:32 d816afd balenad[9694]:         /usr/lib/go/src/runtime/panic.go:605 +0x82 fp=0x1aac76fc sp=0x1aac76f0 pc=0x8073852
Jul 05 22:41:32 d816afd balenad[9694]: runtime.sigpanic()
Jul 05 22:41:32 d816afd balenad[9694]:         /usr/lib/go/src/runtime/signal_unix.go:351 +0x25b fp=0x1aac7720 sp=0x1aac76fc pc=0x8087e7b
Jul 05 22:41:32 d816afd balenad[9694]: runtime.heapBitsSetType(0x1ac7e000, 0x200a000, 0x2008020, 0x8f4d880)
Jul 05 22:41:32 d816afd balenad[9694]:         /usr/lib/go/src/runtime/mbitmap.go:1070 +0x173 fp=0x1aac7794 sp=0x1aac7720 pc=0x805c703
Jul 05 22:41:32 d816afd balenad[9694]: runtime.mallocgc(0x200a000, 0x8f4d880, 0x1aa13501, 0x0)
Jul 05 22:41:32 d816afd balenad[9694]:         /usr/lib/go/src/runtime/malloc.go:741 +0x493 fp=0x1aac77e8 sp=0x1aac7794 pc=0x805a1f3
Jul 05 22:41:32 d816afd balenad[9694]: runtime.newobject(0x8f4d880, 0xb63a7000)
Jul 05 22:41:32 d816afd balenad[9694]:         /usr/lib/go/src/runtime/malloc.go:840 +0x2c fp=0x1aac77fc sp=0x1aac77e8 pc=0x805a61c
Jul 05 22:41:32 d816afd balenad[9694]: github.com/docker/docker/vendor/github.com/boltdb/bolt.(*Bucket).openBucket(0x1a98868c, 0xb63a7022, 0x10, 0x10, 0x2)
Jul 05 22:41:32 d816afd balenad[9694]:         /yocto/resin-board/build/tmp/work/core2-32-poky-linux/balena/17.12.0-dev+gitfe78e2c9a69313007c53c83fff4b5525fbc2ba45-r0/git/src/import/.gopath/src/github.com/docke>
Jul 05 22:41:32 d816afd balenad[9694]: github.com/docker/docker/vendor/github.com/boltdb/bolt.(*Bucket).Bucket(0x1a98868c, 0x1aac7938, 0x2, 0x20, 0x1aac7938)
Jul 05 22:41:32 d816afd balenad[9694]:         /yocto/resin-board/build/tmp/work/core2-32-poky-linux/balena/17.12.0-dev+gitfe78e2c9a69313007c53c83fff4b5525fbc2ba45-r0/git/src/import/.gopath/src/github.com/docke>
Jul 05 22:41:32 d816afd balenad[9694]: github.com/docker/docker/vendor/github.com/boltdb/bolt.(*Tx).Bucket(0x1a988680, 0x1aac7938, 0x2, 0x20, 0x2)
Jul 05 22:41:32 d816afd balenad[9694]:         /yocto/resin-board/build/tmp/work/core2-32-poky-linux/balena/17.12.0-dev+gitfe78e2c9a69313007c53c83fff4b5525fbc2ba45-r0/git/src/import/.gopath/src/github.com/docke>
Jul 05 22:41:32 d816afd balenad[9694]: github.com/docker/docker/vendor/github.com/containerd/containerd/metadata.(*DB).Init.func1(0x1a988680, 0x90630d0, 0x1a988680)
Jul 05 22:41:32 d816afd balenad[9694]:         /yocto/resin-board/build/tmp/work/core2-32-poky-linux/balena/17.12.0-dev+gitfe78e2c9a69313007c53c83fff4b5525fbc2ba45-r0/git/src/import/.gopath/src/github.com/docke>
Jul 05 22:41:32 d816afd balenad[9694]: github.com/docker/docker/vendor/github.com/boltdb/bolt.(*DB).Update(0x1a961680, 0x1aac7a2c, 0x0, 0x0)
Jul 05 22:41:32 d816afd balenad[9694]:         /yocto/resin-board/build/tmp/work/core2-32-poky-linux/balena/17.12.0-dev+gitfe78e2c9a69313007c53c83fff4b5525fbc2ba45-r0/git/src/import/.gopath/src/github.com/docke>
Jul 05 22:41:32 d816afd balenad[9694]: github.com/docker/docker/vendor/github.com/containerd/containerd/metadata.(*DB).Init(0x1aa31ac0, 0x98ccee0, 0x1ac78020, 0x1aac7abc, 0x1aa31ac0)
Jul 05 22:41:32 d816afd balenad[9694]:         /yocto/resin-board/build/tmp/work/core2-32-poky-linux/balena/17.12.0-dev+gitfe78e2c9a69313007c53c83fff4b5525fbc2ba45-r0/git/src/import/.gopath/src/github.com/docke>
Jul 05 22:41:32 d816afd balenad[9694]: github.com/docker/docker/vendor/github.com/containerd/containerd/server.loadPlugins.func2(0x1aa31a80, 0x98ccee0, 0x1a962b40, 0x21, 0x1ac70240)
Jul 05 22:41:32 d816afd balenad[9694]:         /yocto/resin-board/build/tmp/work/core2-32-poky-linux/balena/17.12.0-dev+gitfe78e2c9a69313007c53c83fff4b5525fbc2ba45-r0/git/src/import/.gopath/src/github.com/docke>
Jul 05 22:41:32 d816afd balenad[9694]: github.com/docker/docker/vendor/github.com/containerd/containerd/plugin.(*Registration).Init(0x1a96b320, 0x1aa31a80, 0x1a96b320)
Jul 05 22:41:32 d816afd balenad[9694]:         /yocto/resin-board/build/tmp/work/core2-32-poky-linux/balena/17.12.0-dev+gitfe78e2c9a69313007c53c83fff4b5525fbc2ba45-r0/git/src/import/.gopath/src/github.com/docke>
Jul 05 22:41:32 d816afd balenad[9694]: github.com/docker/docker/vendor/github.com/containerd/containerd/server.New(0xb65c7270, 0x1ac586c0, 0x1aa4f100, 0xb65c7270, 0x1ac586c0, 0x1aa3fb40)
Jul 05 22:41:32 d816afd balenad[9694]:         /yocto/resin-board/build/tmp/work/core2-32-poky-linux/balena/17.12.0-dev+gitfe78e2c9a69313007c53c83fff4b5525fbc2ba45-r0/git/src/import/.gopath/src/github.com/docke>
Jul 05 22:41:32 d816afd balenad[9694]: github.com/docker/docker/vendor/github.com/containerd/containerd/cmd/containerd.Main.func1(0x1aa7a9a0, 0x1aa7a9a0, 0x1aac7d73)
Jul 05 22:41:32 d816afd balenad[9694]:         /yocto/resin-board/build/tmp/work/core2-32-poky-linux/balena/17.12.0-dev+gitfe78e2c9a69313007c53c83fff4b5525fbc2ba45-r0/git/src/import/.gopath/src/github.com/docke>
Jul 05 22:41:32 d816afd balenad[9694]: github.com/docker/docker/vendor/github.com/urfave/cli.HandleAction(0x8dfc7c0, 0x9063270, 0x1aa7a9a0, 0x1ac11440, 0x0)
Jul 05 22:41:32 d816afd balenad[9694]:         /yocto/resin-board/build/tmp/work/core2-32-poky-linux/balena/17.12.0-dev+gitfe78e2c9a69313007c53c83fff4b5525fbc2ba45-r0/git/src/import/.gopath/src/github.com/docke>
Jul 05 22:41:32 d816afd balenad[9694]: github.com/docker/docker/vendor/github.com/urfave/cli.(*App).Run(0x1a901dc0, 0x1a95e060, 0x3, 0x4, 0x0, 0x0)
Jul 05 22:41:32 d816afd balenad[9694]:         /yocto/resin-board/build/tmp/work/core2-32-poky-linux/balena/17.12.0-dev+gitfe78e2c9a69313007c53c83fff4b5525fbc2ba45-r0/git/src/import/.gopath/src/github.com/docke>
Jul 05 22:41:32 d816afd balenad[9694]: github.com/docker/docker/vendor/github.com/containerd/containerd/cmd/containerd.Main()
Jul 05 22:41:32 d816afd balenad[9694]:         /yocto/resin-board/build/tmp/work/core2-32-poky-linux/balena/17.12.0-dev+gitfe78e2c9a69313007c53c83fff4b5525fbc2ba45-r0/git/src/import/.gopath/src/github.com/docke>
Jul 05 22:41:32 d816afd balenad[9694]: main.main()
Jul 05 22:41:32 d816afd balenad[9694]:         /yocto/resin-board/build/tmp/work/core2-32-poky-linux/balena/17.12.0-dev+gitfe78e2c9a69313007c53c83fff4b5525fbc2ba45-r0/git/src/import/.gopath/src/github.com/docke>
Jul 05 22:41:32 d816afd balenad[9694]: runtime.main()
Jul 05 22:41:32 d816afd balenad[9694]:         /usr/lib/go/src/runtime/proc.go:195 +0x21d fp=0x1aac7ff0 sp=0x1aac7fc4 pc=0x8074d1d
Jul 05 22:41:32 d816afd balenad[9694]: runtime.goexit()
Jul 05 22:41:32 d816afd balenad[9694]:         /usr/lib/go/src/runtime/asm_386.s:1635 +0x1 fp=0x1aac7ff4 sp=0x1aac7ff0 pc=0x809e0b1
Jul 05 22:41:32 d816afd balenad[9694]: goroutine 9 [syscall]:
Jul 05 22:41:32 d816afd balenad[9694]: os/signal.signal_recv(0x9064bd8)
Jul 05 22:41:32 d816afd balenad[9694]:         /usr/lib/go/src/runtime/sigqueue.go:131 +0x12f
Jul 05 22:41:32 d816afd balenad[9694]: os/signal.loop()
Jul 05 22:41:32 d816afd balenad[9694]:         /usr/lib/go/src/os/signal/signal_unix.go:22 +0x1a
Jul 05 22:41:32 d816afd balenad[9694]: created by os/signal.init.0
Jul 05 22:41:32 d816afd balenad[9694]:         /usr/lib/go/src/os/signal/signal_unix.go:28 +0x37
Jul 05 22:41:32 d816afd balenad[9694]: goroutine 15 [select]:
Jul 05 22:41:32 d816afd balenad[9694]: github.com/docker/docker/vendor/github.com/containerd/containerd/cmd/containerd.handleSignals.func1(0x1aae5d40, 0x1ac52d40, 0x98ccee0, 0x1ac586c0, 0x1aae5d80)
Jul 05 22:41:32 d816afd balenad[9694]:         /yocto/resin-board/build/tmp/work/core2-32-poky-linux/balena/17.12.0-dev+gitfe78e2c9a69313007c53c83fff4b5525fbc2ba45-r0/git/src/import/.gopath/src/github.com/docke>
Jul 05 22:41:32 d816afd balenad[9694]: created by github.com/docker/docker/vendor/github.com/containerd/containerd/cmd/containerd.handleSignals
Jul 05 22:41:32 d816afd balenad[9694]:         /yocto/resin-board/build/tmp/work/core2-32-poky-linux/balena/17.12.0-dev+gitfe78e2c9a69313007c53c83fff4b5525fbc2ba45-r0/git/src/import/.gopath/src/github.com/docke>
Jul 05 22:41:32 d816afd balenad[9694]: goroutine 16 [select, locked to thread]:
Jul 05 22:41:32 d816afd balenad[9694]: runtime.gopark(0x9064c98, 0x0, 0x8fdebeb, 0x6, 0x18, 0x1)
Jul 05 22:41:32 d816afd balenad[9694]:         /usr/lib/go/src/runtime/proc.go:287 +0x114
Jul 05 22:41:32 d816afd balenad[9694]: runtime.selectgo(0x1a923fa0, 0x1aae5e00)
Jul 05 22:41:32 d816afd balenad[9694]:         /usr/lib/go/src/runtime/select.go:395 +0xf4a
Jul 05 22:41:32 d816afd balenad[9694]: runtime.ensureSigM.func1()
Jul 05 22:41:32 d816afd balenad[9694]:         /usr/lib/go/src/runtime/signal_unix.go:511 +0x1e9
Jul 05 22:41:32 d816afd balenad[9694]: runtime.goexit()
Jul 05 22:41:32 d816afd balenad[9694]:         /usr/lib/go/src/runtime/asm_386.s:1635 +0x1
Jul 05 22:41:32 d816afd balenad[9694]: goroutine 25 [select]:
Jul 05 22:41:32 d816afd balenad[9694]: github.com/docker/docker/vendor/github.com/docker/go-events.(*Broadcaster).run(0x1a96b380)
Jul 05 22:41:32 d816afd balenad[9694]:         /yocto/resin-board/build/tmp/work/core2-32-poky-linux/balena/17.12.0-dev+gitfe78e2c9a69313007c53c83fff4b5525fbc2ba45-r0/git/src/import/.gopath/src/github.com/docke>
Jul 05 22:41:32 d816afd balenad[9694]: created by github.com/docker/docker/vendor/github.com/docker/go-events.NewBroadcaster
Jul 05 22:41:32 d816afd balenad[9694]:         /yocto/resin-board/build/tmp/work/core2-32-poky-linux/balena/17.12.0-dev+gitfe78e2c9a69313007c53c83fff4b5525fbc2ba45-r0/git/src/import/.gopath/src/github.com/docke>
Jul 05 22:41:32 d816afd balenad[9694]: time="2020-07-05T22:41:32.121758471Z" level=error msg="containerd did not exit successfully" error="exit status 2" module=libcontainerd
Jul 05 22:41:51 d816afd balenad[9694]: Failed to connect to containerd: failed to dial "/var/run/balena-engine/containerd/balena-engine-containerd.sock": dial unix:///var/run/balena-engine/containerd/balena-eng>
Jul 05 22:41:51 d816afd systemd[1]: balena.service: Main process exited, code=exited, status=1/FAILURE
Jul 05 22:41:51 d816afd systemd[1]: balena.service: Failed with result 'exit-code'.
Jul 05 22:41:51 d816afd systemd[1]: Failed to start Balena Application Container Engine.
Jul 05 22:41:52 d816afd systemd[1]: balena.service: Service hold-off time over, scheduling restart.
Jul 05 22:41:52 d816afd systemd[1]: balena.service: Scheduled restart job, restart counter is at 32591.
Jul 05 22:41:52 d816afd systemd[1]: Stopped Balena Application Container Engine.
Jul 05 22:41:52 d816afd systemd[1]: Starting Balena Application Container Engine...
Jul 05 22:41:52 d816afd balenad[9724]: time="2020-07-05T22:41:52Z" level=warning msg="Running experimental build"
Jul 05 22:41:52 d816afd balenad[9724]: time="2020-07-05T22:41:52.401718531Z" level=info msg="libcontainerd: started new balena-engine-containerd process" pid=9736
Jul 05 22:41:52 d816afd balenad[9724]: time="2020-07-05T22:41:52Z" level=info msg="starting containerd" module=containerd revision= version=1.0.0+unknown
Jul 05 22:41:52 d816afd balenad[9724]: time="2020-07-05T22:41:52Z" level=info msg="setting subreaper..." module=containerd
Jul 05 22:41:52 d816afd balenad[9724]: time="2020-07-05T22:41:52Z" level=info msg="changing OOM score to -500" module=containerd
Jul 05 22:41:52 d816afd balenad[9724]: time="2020-07-05T22:41:52Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." module=containerd type=io.containerd.content.v1
Jul 05 22:41:52 d816afd balenad[9724]: time="2020-07-05T22:41:52Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." module=containerd type=io.containerd.snapshotter.v1
Jul 05 22:41:52 d816afd balenad[9724]: time="2020-07-05T22:41:52Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." module=containerd type=io.containerd.metadata.v1
Jul 05 22:41:52 d816afd balenad[9724]: fatal error: unexpected signal during runtime execution
Jul 05 22:41:52 d816afd balenad[9724]: [signal SIGSEGV: segmentation violation code=0x1 addr=0x9913c2bb pc=0x805c703]
Jul 05 22:41:52 d816afd balenad[9724]: goroutine 1 [running]:
Jul 05 22:41:52 d816afd balenad[9724]: runtime.throw(0x9022512, 0x2a)
Jul 05 22:41:52 d816afd balenad[9724]:         /usr/lib/go/src/runtime/panic.go:605 +0x82 fp=0x1ba9f6fc sp=0x1ba9f6f0 pc=0x8073852
Jul 05 22:41:52 d816afd balenad[9724]: runtime.sigpanic()
Jul 05 22:41:52 d816afd balenad[9724]:         /usr/lib/go/src/runtime/signal_unix.go:351 +0x25b fp=0x1ba9f720 sp=0x1ba9f6fc pc=0x8087e7b
Jul 05 22:41:52 d816afd balenad[9724]: runtime.heapBitsSetType(0x1bc3a000, 0x200a000, 0x2008020, 0x8f4d880)
Jul 05 22:41:52 d816afd balenad[9724]:         /usr/lib/go/src/runtime/mbitmap.go:1070 +0x173 fp=0x1ba9f794 sp=0x1ba9f720 pc=0x805c703
Jul 05 22:41:52 d816afd balenad[9724]: runtime.mallocgc(0x200a000, 0x8f4d880, 0x1bc28701, 0x0)
Jul 05 22:41:52 d816afd balenad[9724]:         /usr/lib/go/src/runtime/malloc.go:741 +0x493 fp=0x1ba9f7e8 sp=0x1ba9f794 pc=0x805a1f3
Jul 05 22:41:52 d816afd balenad[9724]: runtime.newobject(0x8f4d880, 0xb63e7000)
Jul 05 22:41:52 d816afd balenad[9724]:         /usr/lib/go/src/runtime/malloc.go:840 +0x2c fp=0x1ba9f7fc sp=0x1ba9f7e8 pc=0x805a61c
Jul 05 22:41:52 d816afd balenad[9724]: github.com/docker/docker/vendor/github.com/boltdb/bolt.(*Bucket).openBucket(0x1b98768c, 0xb63e7022, 0x10, 0x10, 0x2)
Jul 05 22:41:52 d816afd balenad[9724]:         /yocto/resin-board/build/tmp/work/core2-32-poky-linux/balena/17.12.0-dev+gitfe78e2c9a69313007c53c83fff4b5525fbc2ba45-r0/git/src/import/.gopath/src/github.com/docke>
Jul 05 22:41:52 d816afd balenad[9724]: github.com/docker/docker/vendor/github.com/boltdb/bolt.(*Bucket).Bucket(0x1b98768c, 0x1ba9f938, 0x2, 0x20, 0x1ba9f938)
Jul 05 22:41:52 d816afd balenad[9724]:         /yocto/resin-board/build/tmp/work/core2-32-poky-linux/balena/17.12.0-dev+gitfe78e2c9a69313007c53c83fff4b5525fbc2ba45-r0/git/src/import/.gopath/src/github.com/docke>
Jul 05 22:41:52 d816afd balenad[9724]: github.com/docker/docker/vendor/github.com/boltdb/bolt.(*Tx).Bucket(0x1b987680, 0x1ba9f938, 0x2, 0x20, 0x2)
Jul 05 22:41:52 d816afd balenad[9724]:         /yocto/resin-board/build/tmp/work/core2-32-poky-linux/balena/17.12.0-dev+gitfe78e2c9a69313007c53c83fff4b5525fbc2ba45-r0/git/src/import/.gopath/src/github.com/docke>
Jul 05 22:41:52 d816afd balenad[9724]: github.com/docker/docker/vendor/github.com/containerd/containerd/metadata.(*DB).Init.func1(0x1b987680, 0x90630d0, 0x1b987680)
Jul 05 22:41:52 d816afd balenad[9724]:         /yocto/resin-board/build/tmp/work/core2-32-poky-linux/balena/17.12.0-dev+gitfe78e2c9a69313007c53c83fff4b5525fbc2ba45-r0/git/src/import/.gopath/src/github.com/docke>
Jul 05 22:41:52 d816afd balenad[9724]: github.com/docker/docker/vendor/github.com/boltdb/bolt.(*DB).Update(0x1bc107e0, 0x1ba9fa2c, 0x0, 0x0)
Jul 05 22:41:52 d816afd balenad[9724]:         /yocto/resin-board/build/tmp/work/core2-32-poky-linux/balena/17.12.0-dev+gitfe78e2c9a69313007c53c83fff4b5525fbc2ba45-r0/git/src/import/.gopath/src/github.com/docke>
Jul 05 22:41:52 d816afd balenad[9724]: github.com/docker/docker/vendor/github.com/containerd/containerd/metadata.(*DB).Init(0x1bc20780, 0x98ccee0, 0x1bc1dec0, 0x1ba9fabc, 0x1bc20780)
Jul 05 22:41:52 d816afd balenad[9724]:         /yocto/resin-board/build/tmp/work/core2-32-poky-linux/balena/17.12.0-dev+gitfe78e2c9a69313007c53c83fff4b5525fbc2ba45-r0/git/src/import/.gopath/src/github.com/docke>
Jul 05 22:41:52 d816afd balenad[9724]: github.com/docker/docker/vendor/github.com/containerd/containerd/server.loadPlugins.func2(0x1bc20740, 0x98ccee0, 0x1bac52f0, 0x21, 0x1bc15440)
Jul 05 22:41:52 d816afd balenad[9724]:         /yocto/resin-board/build/tmp/work/core2-32-poky-linux/balena/17.12.0-dev+gitfe78e2c9a69313007c53c83fff4b5525fbc2ba45-r0/git/src/import/.gopath/src/github.com/docke>
Jul 05 22:41:52 d816afd balenad[9724]: github.com/docker/docker/vendor/github.com/containerd/containerd/plugin.(*Registration).Init(0x1bc264e0, 0x1bc20740, 0x1bc264e0)
Jul 05 22:41:52 d816afd balenad[9724]:         /yocto/resin-board/build/tmp/work/core2-32-poky-linux/balena/17.12.0-dev+gitfe78e2c9a69313007c53c83fff4b5525fbc2ba45-r0/git/src/import/.gopath/src/github.com/docke>
Jul 05 22:41:52 d816afd balenad[9724]: github.com/docker/docker/vendor/github.com/containerd/containerd/server.New(0xb65cb620, 0x1bc1cfe0, 0x1b987300, 0xb65cb620, 0x1bc1cfe0, 0x1bc1d9e0)
Jul 05 22:41:52 d816afd balenad[9724]:         /yocto/resin-board/build/tmp/work/core2-32-poky-linux/balena/17.12.0-dev+gitfe78e2c9a69313007c53c83fff4b5525fbc2ba45-r0/git/src/import/.gopath/src/github.com/docke>
Jul 05 22:41:52 d816afd balenad[9724]: github.com/docker/docker/vendor/github.com/containerd/containerd/cmd/containerd.Main.func1(0x1b988f20, 0x1b988f20, 0x1ba9fd73)
Jul 05 22:41:52 d816afd balenad[9724]:         /yocto/resin-board/build/tmp/work/core2-32-poky-linux/balena/17.12.0-dev+gitfe78e2c9a69313007c53c83fff4b5525fbc2ba45-r0/git/src/import/.gopath/src/github.com/docke>
Jul 05 22:41:52 d816afd balenad[9724]: github.com/docker/docker/vendor/github.com/urfave/cli.HandleAction(0x8dfc7c0, 0x9063270, 0x1b988f20, 0x1bc26270, 0x0)
Jul 05 22:41:52 d816afd balenad[9724]:         /yocto/resin-board/build/tmp/work/core2-32-poky-linux/balena/17.12.0-dev+gitfe78e2c9a69313007c53c83fff4b5525fbc2ba45-r0/git/src/import/.gopath/src/github.com/docke>
Jul 05 22:41:52 d816afd balenad[9724]: github.com/docker/docker/vendor/github.com/urfave/cli.(*App).Run(0x1b95db20, 0x1b958080, 0x3, 0x4, 0x0, 0x0)
Jul 05 22:41:52 d816afd balenad[9724]:         /yocto/resin-board/build/tmp/work/core2-32-poky-linux/balena/17.12.0-dev+gitfe78e2c9a69313007c53c83fff4b5525fbc2ba45-r0/git/src/import/.gopath/src/github.com/docke>
Jul 05 22:41:52 d816afd balenad[9724]: github.com/docker/docker/vendor/github.com/containerd/containerd/cmd/containerd.Main()
Jul 05 22:41:52 d816afd balenad[9724]:         /yocto/resin-board/build/tmp/work/core2-32-poky-linux/balena/17.12.0-dev+gitfe78e2c9a69313007c53c83fff4b5525fbc2ba45-r0/git/src/import/.gopath/src/github.com/docke>
Jul 05 22:41:52 d816afd balenad[9724]: main.main()
Jul 05 22:41:52 d816afd balenad[9724]:         /yocto/resin-board/build/tmp/work/core2-32-poky-linux/balena/17.12.0-dev+gitfe78e2c9a69313007c53c83fff4b5525fbc2ba45-r0/git/src/import/.gopath/src/github.com/docke>
Jul 05 22:41:52 d816afd balenad[9724]: runtime.main()
Jul 05 22:41:52 d816afd balenad[9724]:         /usr/lib/go/src/runtime/proc.go:195 +0x21d fp=0x1ba9fff0 sp=0x1ba9ffc4 pc=0x8074d1d
Jul 05 22:41:52 d816afd balenad[9724]: runtime.goexit()
Jul 05 22:41:52 d816afd balenad[9724]:         /usr/lib/go/src/runtime/asm_386.s:1635 +0x1 fp=0x1ba9fff4 sp=0x1ba9fff0 pc=0x809e0b1
Jul 05 22:41:52 d816afd balenad[9724]: goroutine 31 [syscall]:
Jul 05 22:41:52 d816afd balenad[9724]: os/signal.signal_recv(0x9064bd8)
Jul 05 22:41:52 d816afd balenad[9724]:         /usr/lib/go/src/runtime/sigqueue.go:131 +0x12f
Jul 05 22:41:52 d816afd balenad[9724]: os/signal.loop()
Jul 05 22:41:52 d816afd balenad[9724]:         /usr/lib/go/src/os/signal/signal_unix.go:22 +0x1a
Jul 05 22:41:52 d816afd balenad[9724]: created by os/signal.init.0
Jul 05 22:41:52 d816afd balenad[9724]:         /usr/lib/go/src/os/signal/signal_unix.go:28 +0x37
Jul 05 22:41:52 d816afd balenad[9724]: goroutine 37 [select]:
Jul 05 22:41:52 d816afd balenad[9724]: github.com/docker/docker/vendor/github.com/containerd/containerd/cmd/containerd.handleSignals.func1(0x1bbdaa40, 0x1bc20400, 0x98ccee0, 0x1bc1cfe0, 0x1bbdaa80)
Jul 05 22:41:52 d816afd balenad[9724]:         /yocto/resin-board/build/tmp/work/core2-32-poky-linux/balena/17.12.0-dev+gitfe78e2c9a69313007c53c83fff4b5525fbc2ba45-r0/git/src/import/.gopath/src/github.com/docke>
Jul 05 22:41:52 d816afd balenad[9724]: created by github.com/docker/docker/vendor/github.com/containerd/containerd/cmd/containerd.handleSignals
Jul 05 22:41:52 d816afd balenad[9724]:         /yocto/resin-board/build/tmp/work/core2-32-poky-linux/balena/17.12.0-dev+gitfe78e2c9a69313007c53c83fff4b5525fbc2ba45-r0/git/src/import/.gopath/src/github.com/docke>
Jul 05 22:41:52 d816afd balenad[9724]: goroutine 38 [select, locked to thread]:
Jul 05 22:41:52 d816afd balenad[9724]: runtime.gopark(0x9064c98, 0x0, 0x8fdebeb, 0x6, 0x18, 0x1)
Jul 05 22:41:52 d816afd balenad[9724]:         /usr/lib/go/src/runtime/proc.go:287 +0x114
Jul 05 22:41:52 d816afd balenad[9724]: runtime.selectgo(0x1b9217a0, 0x1bbdab00)
Jul 05 22:41:52 d816afd balenad[9724]:         /usr/lib/go/src/runtime/select.go:395 +0xf4a
Jul 05 22:41:52 d816afd balenad[9724]: runtime.ensureSigM.func1()
Jul 05 22:41:52 d816afd balenad[9724]:         /usr/lib/go/src/runtime/signal_unix.go:511 +0x1e9
Jul 05 22:41:52 d816afd balenad[9724]: runtime.goexit()
Jul 05 22:41:52 d816afd balenad[9724]:         /usr/lib/go/src/runtime/asm_386.s:1635 +0x1
Jul 05 22:41:52 d816afd balenad[9724]: goroutine 39 [select]:
Jul 05 22:41:52 d816afd balenad[9724]: github.com/docker/docker/vendor/github.com/docker/go-events.(*Broadcaster).run(0x1bc26540)
Jul 05 22:41:52 d816afd balenad[9724]:         /yocto/resin-board/build/tmp/work/core2-32-poky-linux/balena/17.12.0-dev+gitfe78e2c9a69313007c53c83fff4b5525fbc2ba45-r0/git/src/import/.gopath/src/github.com/docke>
Jul 05 22:41:52 d816afd balenad[9724]: created by github.com/docker/docker/vendor/github.com/docker/go-events.NewBroadcaster
Jul 05 22:41:52 d816afd balenad[9724]:         /yocto/resin-board/build/tmp/work/core2-32-poky-linux/balena/17.12.0-dev+gitfe78e2c9a69313007c53c83fff4b5525fbc2ba45-r0/git/src/import/.gopath/src/github.com/docke>
Jul 05 22:41:52 d816afd balenad[9724]: time="2020-07-05T22:41:52.580602425Z" level=error msg="containerd did not exit successfully" error="exit status 2" module=libcontainerd

After a reboot, blob data messages are repeatedly logged

Jul 05 23:55:29 d816afd balenad[777]: [59B blob data]
Jul 05 23:55:29 d816afd balenad[777]: [59B blob data]
Jul 05 23:55:29 d816afd balenad[777]: [59B blob data]
Jul 05 23:55:29 d816afd balenad[777]: [59B blob data]
Jul 05 23:55:29 d816afd balenad[777]: [59B blob data]
Jul 05 23:55:29 d816afd balenad[777]: [59B blob data]
Jul 05 23:55:29 d816afd balenad[777]: [59B blob data]
Jul 05 23:55:29 d816afd balenad[777]: [59B blob data]
Jul 05 23:55:29 d816afd balenad[777]: [69B blob data]
Jul 05 23:55:29 d816afd balenad[777]: [69B blob data]
Jul 05 23:55:34 d816afd systemd-journald[479]: Suppressed 148 messages from balena.service
Jul 05 23:55:34 d816afd balenad[777]: [69B blob data]
Jul 05 23:55:34 d816afd balenad[777]: [69B blob data]
Jul 05 23:55:34 d816afd balenad[777]: [69B blob data]
Jul 05 23:55:34 d816afd balenad[777]: [59B blob data]
Jul 05 23:55:34 d816afd balenad[777]: [59B blob data]
Jul 05 23:55:34 d816afd balenad[777]: [59B blob data]
Jul 05 23:55:34 d816afd balenad[777]: [59B blob data]
Jul 05 23:55:34 d816afd balenad[777]: [59B blob data]
Jul 05 23:55:34 d816afd balenad[777]: [59B blob data]
Jul 05 23:55:34 d816afd balenad[777]: [59B blob data]
Jul 05 23:55:34 d816afd balenad[777]: [59B blob data]

The only other logs, with the blob data messages filtered were:

# journalctl -xen100000000 -u balena --no-pager | grep -v 'blob data' 
-- Logs begin at Sun 2020-07-05 23:55:35 UTC, end at Mon 2020-07-06 00:00:08 UTC. --
Jul 05 23:56:04 d816afd systemd-journald[479]: Suppressed 176 messages from balena.service
Jul 05 23:56:34 d816afd systemd-journald[479]: Suppressed 154 messages from balena.service
Jul 05 23:57:04 d816afd systemd-journald[479]: Suppressed 171 messages from balena.service
Jul 05 23:57:13 d816afd balenad[777]: time="2020-07-05T23:57:13.382382207Z" level=warning msg="unknown container" container=404fbc488f413f253bb33210d5bee40a64250c505fc00cc7e0e2506e17ceeee5 module=libcontainerd namespace=plugins.moby
Jul 05 23:57:13 d816afd balenad[777]: Supervisor API: GET /v1/healthy 200 - 34.806 ms
Jul 05 23:57:13 d816afd balenad[777]: time="2020-07-05T23:57:13.754735828Z" level=warning msg="unknown container" container=404fbc488f413f253bb33210d5bee40a64250c505fc00cc7e0e2506e17ceeee5 module=libcontainerd namespace=plugins.moby
Jul 05 23:57:34 d816afd systemd-journald[479]: Suppressed 160 messages from balena.service
Jul 05 23:58:05 d816afd systemd-journald[479]: Suppressed 162 messages from balena.service
Jul 05 23:58:35 d816afd systemd-journald[479]: Suppressed 165 messages from balena.service
Jul 05 23:59:05 d816afd systemd-journald[479]: Suppressed 155 messages from balena.service
Jul 05 23:59:35 d816afd systemd-journald[479]: Suppressed 175 messages from balena.service
Jul 06 00:00:05 d816afd systemd-journald[479]: Suppressed 149 messages from balena.service
trinitronx commented 4 years ago

Just realizing that I probably should've checked JournalD log space usage and tmpfs size while it was in crashloop last time... 🤔🤦 although I do have SystemMaxUse=8M set in journald.conf to limit disk usage.

# cat /etc/systemd/journald.conf 
#  This file is part of systemd.
#
#  systemd is free software; you can redistribute it and/or modify it
#  under the terms of the GNU Lesser General Public License as published by
#  the Free Software Foundation; either version 2.1 of the License, or
#  (at your option) any later version.
#
# Entries in this file show the compile time defaults.
# You can change settings by editing this file.
# Defaults can be restored by simply deleting this file.
#
# See journald.conf(5) for details.

[Journal]
Storage=auto
#Compress=yes
#Seal=yes
#SplitMode=uid
#SyncIntervalSec=5m
#RateLimitIntervalSec=30s
#RateLimitBurst=1000
SystemMaxUse=8M
#SystemKeepFree=
#SystemMaxFileSize=
#SystemMaxFiles=100
RuntimeMaxUse=8M
#RuntimeKeepFree=
#RuntimeMaxFileSize=
#RuntimeMaxFiles=100
#MaxRetentionSec=
#MaxFileSec=1month
#ForwardToSyslog=yes
#ForwardToKMsg=no
#ForwardToConsole=no
#ForwardToWall=yes
#TTYPath=/dev/console
#MaxLevelStore=debug
#MaxLevelSyslog=debug
#MaxLevelKMsg=notice
#MaxLevelConsole=info
#MaxLevelWall=emerg
#LineMax=48K

After this last reboot... disk usage looks ok and especially tmpfs ramdisk mounts are not full:

# df -h 
Filesystem                      Size  Used Avail Use% Mounted on
devtmpfs                        473M     0  473M   0% /dev
/dev/mmcblk0p8                  300M  208M   72M  75% /mnt/sysroot/active
/dev/disk/by-label/resin-state   19M  223K   17M   2% /mnt/state
none                            300M  208M   72M  75% /
tmpfs                           479M  128K  479M   1% /dev/shm
tmpfs                           479M  9.9M  469M   3% /run
tmpfs                           479M     0  479M   0% /sys/fs/cgroup
tmpfs                           479M     0  479M   0% /tmp
/dev/mmcblk0p7                   40M   18M   22M  46% /mnt/boot
tmpfs                           479M   20K  479M   1% /var/volatile
/dev/mmcblk0p9                  295M  2.1M  273M   1% /mnt/sysroot/inactive
/dev/mmcblk0p11                 2.9G  936M  1.8G  35% /mnt/data
robertgzr commented 4 years ago

about those * blob data logs: https://github.com/balena-os/balena-engine/issues/179

You're also running a pretty outdated version of the engine. Can you try with the latest balenaOS release? We should be at least on a v18.09.x release.

trinitronx commented 4 years ago

@robertgzr Sure, as long as it still supports Intel Edison :wink: (I've been worried about this support going away ever since Intel made the unfortunate choice to discontinue this little postage stamp CPU 😱)

robertgzr commented 3 years ago

Please reopen if this persists with recent engine versions