-- Logs begin at Sun 2020-09-20 10:44:04 UTC, end at Sun 2020-09-20 10:47:24 UTC. --
Sep 20 10:44:04 localhost kernel: Booting Linux on physical CPU 0x0
Sep 20 10:44:04 localhost kernel: Linux version 5.10.31 (oe-user@oe-host) (arm-poky-linux-gnueabi-gcc (GCC) 9.3.0, GNU ld (GNU Binutils) 2.34.0.20200220) #1 Fri Apr 23 15:16:49 UTC 2021
Sep 20 10:44:04 localhost kernel: CPU: ARMv6-compatible processor [410fb767] revision 7 (ARMv7), cr=00c5387d
Sep 20 10:44:04 localhost kernel: CPU: PIPT / VIPT nonaliasing data cache, VIPT nonaliasing instruction cache
Sep 20 10:44:04 localhost kernel: OF: fdt: Machine model: Raspberry Pi Zero W Rev 1.1
Sep 20 10:44:04 localhost kernel: Memory policy: Data cache writeback
Sep 20 10:44:04 localhost kernel: Reserved memory: created CMA memory pool at 0x1ac00000, size 64 MiB
Sep 20 10:44:04 localhost kernel: OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool
Sep 20 10:44:04 localhost kernel: Zone ranges:
Sep 20 10:44:04 localhost kernel:   Normal   [mem 0x0000000000000000-0x000000001effffff]
Sep 20 10:44:04 localhost kernel: Movable zone start for each node
Sep 20 10:44:04 localhost kernel: Early memory node ranges
Sep 20 10:44:04 localhost kernel:   node   0: [mem 0x0000000000000000-0x000000001effffff]
Sep 20 10:44:04 localhost kernel: Initmem setup node 0 [mem 0x0000000000000000-0x000000001effffff]
Sep 20 10:44:04 localhost kernel: On node 0 totalpages: 126976
Sep 20 10:44:04 localhost kernel:   Normal zone: 1116 pages used for memmap
Sep 20 10:44:04 localhost kernel:   Normal zone: 0 pages reserved
Sep 20 10:44:04 localhost kernel:   Normal zone: 126976 pages, LIFO batch:31
Sep 20 10:44:04 localhost kernel: pcpu-alloc: s0 r0 d32768 u32768 alloc=1*32768
Sep 20 10:44:04 localhost kernel: pcpu-alloc: [0] 0 
Sep 20 10:44:04 localhost kernel: Built 1 zonelists, mobility grouping on.  Total pages: 125860
Sep 20 10:44:04 localhost kernel: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_compat_alsa=0 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=1824 bcm2708_fb.fbheight=984 bcm2708_fb.fbdepth=16 bcm2708_fb.fbswap=1 smsc95xx.macaddr=B8:27:EB:40:4A:B9 vc_mem.mem_base=0x1fa00000 vc_mem.mem_size=0x20000000  dwc_otg.lpm_enable=0 console=null rootwait vt.global_cursor_default=0 cgroup_enable=memory root=UUID=ba1eadef-9391-435b-a219-76225d4a99b6 rootwait
Sep 20 10:44:04 localhost kernel: Dentry cache hash table entries: 65536 (order: 6, 262144 bytes, linear)
Sep 20 10:44:04 localhost kernel: Inode-cache hash table entries: 32768 (order: 5, 131072 bytes, linear)
Sep 20 10:44:04 localhost kernel: mem auto-init: stack:off, heap alloc:off, heap free:off
Sep 20 10:44:04 localhost kernel: Memory: 410992K/507904K available (10443K kernel code, 1370K rwdata, 3228K rodata, 10304K init, 865K bss, 31376K reserved, 65536K cma-reserved)
Sep 20 10:44:04 localhost kernel: SLUB: HWalign=32, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
Sep 20 10:44:04 localhost kernel: ftrace: allocating 35629 entries in 70 pages
Sep 20 10:44:04 localhost kernel: ftrace: allocated 70 pages with 3 groups
Sep 20 10:44:04 localhost kernel: NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16
Sep 20 10:44:04 localhost kernel: random: get_random_bytes called from start_kernel+0x328/0x574 with crng_init=0
Sep 20 10:44:04 localhost kernel: sched_clock: 32 bits at 1000kHz, resolution 1000ns, wraps every 2147483647500ns
Sep 20 10:44:04 localhost kernel: clocksource: timer: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275 ns
Sep 20 10:44:04 localhost kernel: bcm2835: system timer (irq = 27)
Sep 20 10:44:04 localhost kernel: Console: colour dummy device 80x30
Sep 20 10:44:04 localhost kernel: Calibrating delay loop... 697.95 BogoMIPS (lpj=3489792)
Sep 20 10:44:04 localhost kernel: pid_max: default: 32768 minimum: 301
Sep 20 10:44:04 localhost kernel: LSM: Security Framework initializing
Sep 20 10:44:04 localhost kernel: Mount-cache hash table entries: 1024 (order: 0, 4096 bytes, linear)
Sep 20 10:44:04 localhost kernel: Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes, linear)
Sep 20 10:44:04 localhost kernel: CPU: Testing write buffer coherency: ok
Sep 20 10:44:04 localhost kernel: Setting up static identity map for 0x8200 - 0x825c
Sep 20 10:44:04 localhost kernel: devtmpfs: initialized
Sep 20 10:44:04 localhost kernel: VFP support v0.3: implementor 41 architecture 1 part 20 variant b rev 5
Sep 20 10:44:04 localhost kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
Sep 20 10:44:04 localhost kernel: futex hash table entries: 256 (order: -1, 3072 bytes, linear)
Sep 20 10:44:04 localhost kernel: pinctrl core: initialized pinctrl subsystem
Sep 20 10:44:04 localhost kernel: NET: Registered protocol family 16
Sep 20 10:44:04 localhost kernel: DMA: preallocated 1024 KiB pool for atomic coherent allocations
Sep 20 10:44:04 localhost kernel: audit: initializing netlink subsys (disabled)
Sep 20 10:44:04 localhost kernel: thermal_sys: Registered thermal governor 'step_wise'
Sep 20 10:44:04 localhost kernel: hw-breakpoint: found 6 breakpoint and 1 watchpoint registers.
Sep 20 10:44:04 localhost kernel: hw-breakpoint: maximum watchpoint size is 4 bytes.
Sep 20 10:44:04 localhost kernel: Serial: AMBA PL011 UART driver
Sep 20 10:44:04 localhost kernel: audit: type=2000 audit(0.100:1): state=initialized audit_enabled=0 res=1
Sep 20 10:44:04 localhost kernel: bcm2835-mbox 2000b880.mailbox: mailbox enabled
Sep 20 10:44:04 localhost kernel: raspberrypi-firmware soc:firmware: Attached to firmware from 2021-04-21T15:49:53, variant start_cd
Sep 20 10:44:04 localhost kernel: raspberrypi-firmware soc:firmware: Firmware hash is a48d332c35ee1c1c1ab433228e23317f62dcc5fb
Sep 20 10:44:04 localhost kernel: Kprobes globally optimized
Sep 20 10:44:04 localhost kernel: bcm2835-dma 20007000.dma: DMA legacy API manager, dmachans=0x1
Sep 20 10:44:04 localhost kernel: SCSI subsystem initialized
Sep 20 10:44:04 localhost kernel: usbcore: registered new interface driver usbfs
Sep 20 10:44:04 localhost kernel: usbcore: registered new interface driver hub
Sep 20 10:44:04 localhost kernel: usbcore: registered new device driver usb
Sep 20 10:44:04 localhost kernel: clocksource: Switched to clocksource timer
Sep 20 10:44:04 localhost kernel: VFS: Disk quotas dquot_6.6.0
Sep 20 10:44:04 localhost kernel: VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
Sep 20 10:44:04 localhost kernel: FS-Cache: Loaded
Sep 20 10:44:04 localhost kernel: CacheFiles: Loaded
Sep 20 10:44:04 localhost kernel: NET: Registered protocol family 2
Sep 20 10:44:04 localhost kernel: tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 4096 bytes, linear)
Sep 20 10:44:04 localhost kernel: TCP established hash table entries: 4096 (order: 2, 16384 bytes, linear)
Sep 20 10:44:04 localhost kernel: TCP bind hash table entries: 4096 (order: 2, 16384 bytes, linear)
Sep 20 10:44:04 localhost kernel: TCP: Hash tables configured (established 4096 bind 4096)
Sep 20 10:44:04 localhost kernel: UDP hash table entries: 256 (order: 0, 4096 bytes, linear)
Sep 20 10:44:04 localhost kernel: UDP-Lite hash table entries: 256 (order: 0, 4096 bytes, linear)
Sep 20 10:44:04 localhost kernel: NET: Registered protocol family 1
Sep 20 10:44:04 localhost kernel: RPC: Registered named UNIX socket transport module.
Sep 20 10:44:04 localhost kernel: RPC: Registered udp transport module.
Sep 20 10:44:04 localhost kernel: RPC: Registered tcp transport module.
Sep 20 10:44:04 localhost kernel: RPC: Registered tcp NFSv4.1 backchannel transport module.
Sep 20 10:44:04 localhost kernel: hw perfevents: no irqs for PMU, sampling events not supported
Sep 20 10:44:04 localhost kernel: hw perfevents: enabled with armv6_1176 PMU driver, 3 counters available
Sep 20 10:44:04 localhost kernel: Initialise system trusted keyrings
Sep 20 10:44:04 localhost kernel: workingset: timestamp_bits=14 max_order=17 bucket_order=3
Sep 20 10:44:04 localhost kernel: zbud: loaded
Sep 20 10:44:04 localhost kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher
Sep 20 10:44:04 localhost kernel: FS-Cache: Netfs 'nfs' registered for caching
Sep 20 10:44:04 localhost kernel: NFS: Registering the id_resolver key type
Sep 20 10:44:04 localhost kernel: Key type id_resolver registered
Sep 20 10:44:04 localhost kernel: Key type id_legacy registered
Sep 20 10:44:04 localhost kernel: nfs4filelayout_init: NFSv4 File Layout Driver Registering...
Sep 20 10:44:04 localhost kernel: nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering...
Sep 20 10:44:04 localhost kernel: aufs 5.10-20210111
Sep 20 10:44:04 localhost kernel: Key type asymmetric registered
Sep 20 10:44:04 localhost kernel: Asymmetric key parser 'x509' registered
Sep 20 10:44:04 localhost kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249)
Sep 20 10:44:04 localhost kernel: io scheduler mq-deadline registered
Sep 20 10:44:04 localhost kernel: io scheduler kyber registered
Sep 20 10:44:04 localhost kernel: bcm2708_fb soc:fb: FB found 1 display(s)
Sep 20 10:44:04 localhost kernel: Console: switching to colour frame buffer device 228x61
Sep 20 10:44:04 localhost kernel: random: fast init done
Sep 20 10:44:04 localhost kernel: bcm2708_fb soc:fb: Registered framebuffer for display 0, size 1824x984
Sep 20 10:44:04 localhost kernel: bcm2835-rng 20104000.rng: hwrng registered
Sep 20 10:44:04 localhost kernel: vc-mem: phys_addr:0x00000000 mem_base=0x1fa00000 mem_size:0x20000000(512 MiB)
Sep 20 10:44:04 localhost kernel: gpiomem-bcm2835 20200000.gpiomem: Initialised: Registers at 0x20200000
Sep 20 10:44:04 localhost kernel: brd: module loaded
Sep 20 10:44:04 localhost kernel: loop: module loaded
Sep 20 10:44:04 localhost kernel: zram: Added device: zram0
Sep 20 10:44:04 localhost kernel: Loading iSCSI transport class v2.0-870.
Sep 20 10:44:04 localhost kernel: libphy: Fixed MDIO Bus: probed
Sep 20 10:44:04 localhost kernel: tun: Universal TUN/TAP device driver, 1.6
Sep 20 10:44:04 localhost kernel: dwc_otg: version 3.00a 10-AUG-2012 (platform bus)
Sep 20 10:44:04 localhost kernel: Core Release: 2.80a
Sep 20 10:44:04 localhost kernel: Setting default values for core params
Sep 20 10:44:04 localhost kernel: Finished setting default values for core params
Sep 20 10:44:04 localhost kernel: Using Buffer DMA mode
Sep 20 10:44:04 localhost kernel: Periodic Transfer Interrupt Enhancement - disabled
Sep 20 10:44:04 localhost kernel: Multiprocessor Interrupt Enhancement - disabled
Sep 20 10:44:04 localhost kernel: OTG VER PARAM: 0, OTG VER FLAG: 0
Sep 20 10:44:04 localhost kernel: Dedicated Tx FIFOs mode
Sep 20 10:44:04 localhost kernel: 
Sep 20 10:44:04 localhost kernel: WARN::dwc_otg_hcd_init:1072: FIQ DMA bounce buffers: virt = dad14000 dma = 0x9ad14000 len=9024
Sep 20 10:44:04 localhost kernel: FIQ FSM acceleration enabled for :
                                  Non-periodic Split Transactions
                                  Periodic Split Transactions
                                  High-Speed Isochronous Endpoints
                                  Interrupt/Control Split Transaction hack enabled
Sep 20 10:44:04 localhost kernel: dwc_otg: Microframe scheduler enabled
Sep 20 10:44:04 localhost kernel: 
Sep 20 10:44:04 localhost kernel: WARN::hcd_init_fiq:457: FIQ on core 0
Sep 20 10:44:04 localhost kernel: 
Sep 20 10:44:04 localhost kernel: WARN::hcd_init_fiq:458: FIQ ASM at c0741d78 length 36
Sep 20 10:44:04 localhost kernel: 
Sep 20 10:44:04 localhost kernel: WARN::hcd_init_fiq:496: MPHI regs_base at df810000
Sep 20 10:44:04 localhost kernel: dwc_otg 20980000.usb: DWC OTG Controller
Sep 20 10:44:04 localhost kernel: dwc_otg 20980000.usb: new USB bus registered, assigned bus number 1
Sep 20 10:44:04 localhost kernel: dwc_otg 20980000.usb: irq 56, io mem 0x00000000
Sep 20 10:44:04 localhost kernel: Init: Port Power? op_state=1
Sep 20 10:44:04 localhost kernel: Init: Power Port (0)
Sep 20 10:44:04 localhost kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.10
Sep 20 10:44:04 localhost kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
Sep 20 10:44:04 localhost kernel: usb usb1: Product: DWC OTG Controller
Sep 20 10:44:04 localhost kernel: usb usb1: Manufacturer: Linux 5.10.31 dwc_otg_hcd
Sep 20 10:44:04 localhost kernel: usb usb1: SerialNumber: 20980000.usb
Sep 20 10:44:04 localhost kernel: hub 1-0:1.0: USB hub found
Sep 20 10:44:04 localhost kernel: hub 1-0:1.0: 1 port detected
Sep 20 10:44:04 localhost kernel: dwc_otg: FIQ enabled
Sep 20 10:44:04 localhost kernel: dwc_otg: NAK holdoff enabled
Sep 20 10:44:04 localhost kernel: dwc_otg: FIQ split-transaction FSM enabled
Sep 20 10:44:04 localhost kernel: Module dwc_common_port init
Sep 20 10:44:04 localhost kernel: usbcore: registered new interface driver usb-storage
Sep 20 10:44:04 localhost kernel: usbcore: registered new interface driver usbserial_generic
Sep 20 10:44:04 localhost kernel: usbserial: USB Serial support registered for generic
Sep 20 10:44:04 localhost kernel: mousedev: PS/2 mouse device common for all mice
Sep 20 10:44:04 localhost kernel: bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer
Sep 20 10:44:04 localhost kernel: sdhci: Secure Digital Host Controller Interface driver
Sep 20 10:44:04 localhost kernel: sdhci: Copyright(c) Pierre Ossman
Sep 20 10:44:04 localhost kernel: mmc-bcm2835 20300000.mmcnr: could not get clk, deferring probe
Sep 20 10:44:04 localhost kernel: sdhost-bcm2835 20202000.mmc: could not get clk, deferring probe
Sep 20 10:44:04 localhost kernel: sdhci-pltfm: SDHCI platform and OF driver helper
Sep 20 10:44:04 localhost kernel: ledtrig-cpu: registered to indicate activity on CPUs
Sep 20 10:44:04 localhost kernel: hid: raw HID events driver (C) Jiri Kosina
Sep 20 10:44:04 localhost kernel: usbcore: registered new interface driver usbhid
Sep 20 10:44:04 localhost kernel: usbhid: USB HID core driver
Sep 20 10:44:04 localhost kernel: ashmem: initialized
Sep 20 10:44:04 localhost kernel: IPVS: Registered protocols (TCP, UDP, SCTP, AH, ESP)
Sep 20 10:44:04 localhost kernel: IPVS: Connection hash table configured (size=4096, memory=32Kbytes)
Sep 20 10:44:04 localhost kernel: IPVS: ipvs loaded.
Sep 20 10:44:04 localhost kernel: Initializing XFRM netlink socket
Sep 20 10:44:04 localhost kernel: NET: Registered protocol family 10
Sep 20 10:44:04 localhost kernel: Segment Routing with IPv6
Sep 20 10:44:04 localhost kernel: NET: Registered protocol family 17
Sep 20 10:44:04 localhost kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this.
Sep 20 10:44:04 localhost kernel: Key type dns_resolver registered
Sep 20 10:44:04 localhost kernel: registered taskstats version 1
Sep 20 10:44:04 localhost kernel: Loading compiled-in X.509 certificates
Sep 20 10:44:04 localhost kernel: uart-pl011 20201000.serial: cts_event_workaround enabled
Sep 20 10:44:04 localhost kernel: 20201000.serial: ttyAMA0 at MMIO 0x20201000 (irq = 81, base_baud = 0) is a PL011 rev2
Sep 20 10:44:04 localhost kernel: bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver
Sep 20 10:44:04 localhost kernel: mmc-bcm2835 20300000.mmcnr: mmc_debug:0 mmc_debug2:0
Sep 20 10:44:04 localhost kernel: mmc-bcm2835 20300000.mmcnr: DMA channel allocated
Sep 20 10:44:04 localhost kernel: sdhost: log_buf @ (ptrval) (9ad13000)
Sep 20 10:44:04 localhost kernel: mmc0: sdhost-bcm2835 loaded - DMA enabled (>1)
Sep 20 10:44:04 localhost kernel: of_cfs_init
Sep 20 10:44:04 localhost kernel: of_cfs_init: OK
Sep 20 10:44:04 localhost kernel: Warning: unable to open an initial console.
Sep 20 10:44:04 localhost kernel: Freeing unused kernel memory: 10304K
Sep 20 10:44:04 localhost kernel: Kernel memory protection not selected by kernel config.
Sep 20 10:44:04 localhost kernel: Run /init as init process
Sep 20 10:44:04 localhost kernel:   with arguments:
Sep 20 10:44:04 localhost kernel:     /init
Sep 20 10:44:04 localhost kernel:   with environment:
Sep 20 10:44:04 localhost kernel:     HOME=/
Sep 20 10:44:04 localhost kernel:     TERM=linux
Sep 20 10:44:04 localhost kernel: Indeed it is in host mode hprt0 = 00021501
Sep 20 10:44:04 localhost kernel: mmc0: host does not support reading read-only switch, assuming write-enable
Sep 20 10:44:04 localhost kernel: mmc0: new high speed SDHC card at address aaaa
Sep 20 10:44:04 localhost kernel: mmcblk0: mmc0:aaaa SA16G 14.8 GiB
Sep 20 10:44:04 localhost kernel:  mmcblk0: p1 p2 p3 p4 < p5 p6 >
Sep 20 10:44:04 localhost kernel: usb 1-1: new high-speed USB device number 2 using dwc_otg
Sep 20 10:44:04 localhost kernel: Indeed it is in host mode hprt0 = 00001101
Sep 20 10:44:04 localhost kernel: usb 1-1: New USB device found, idVendor=7392, idProduct=7811, bcdDevice= 2.00
Sep 20 10:44:04 localhost kernel: usb 1-1: New USB device strings: Mfr=1, Product=2, SerialNumber=3
Sep 20 10:44:04 localhost kernel: usb 1-1: Product: 802.11n WLAN Adapter
Sep 20 10:44:04 localhost kernel: usb 1-1: Manufacturer: Realtek
Sep 20 10:44:04 localhost kernel: usb 1-1: SerialNumber: 00e04c000001
Sep 20 10:44:04 localhost systemd-journald[953]: Missed 1 kernel messages
Sep 20 10:44:04 localhost udevd[360]: /lib/udev/rules.d/50-udev-default.rules:39 Unknown group 'render', ignoring
Sep 20 10:44:04 localhost udevd[360]: /lib/udev/rules.d/50-udev-default.rules:40 Unknown group 'render', ignoring
Sep 20 10:44:04 localhost kernel: random: crng init done
Sep 20 10:44:04 localhost udevd[369]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
Sep 20 10:44:04 localhost kernel: zram0: detected capacity change from 0 to 249257984
Sep 20 10:44:04 localhost udevd[369]: vtcon0: Process '/lib/systemd/systemd-vconsole-setup' failed with exit code 1.
Sep 20 10:44:04 localhost udevd[376]: vtcon1: Process '/lib/systemd/systemd-vconsole-setup' failed with exit code 1.
Sep 20 10:44:04 localhost kernel: EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null)
Sep 20 10:44:04 localhost kernel: EXT4-fs (mmcblk0p5): mounted filesystem with ordered data mode. Opts: (null)
Sep 20 10:44:04 localhost kernel: EXT4-fs (mmcblk0p2): re-mounted. Opts: (null)
Sep 20 10:44:04 localhost kernel: aufs au_opts_verify:1611:init[1]: dirperm1 breaks the protection by the permission bits on the lower branch
Sep 20 10:44:04 localhost kernel: EXT4-fs (mmcblk0p6): recovery complete
Sep 20 10:44:04 localhost kernel: EXT4-fs (mmcblk0p6): mounted filesystem with ordered data mode. Opts: (null)
Sep 20 10:44:04 localhost systemd[1]: System time before build time, advancing clock.
Sep 20 10:44:04 localhost systemd[1]: /lib/systemd/system/balena-host.socket:5: ListenStream= references a path below legacy directory /var/run/, updating /var/run/balena-host.sock → /run/balena-host.sock; please update the unit file accordingly.
Sep 20 10:44:04 localhost systemd[1]: /lib/systemd/system/balena-engine.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/balena-engine.sock → /run/balena-engine.sock; please update the unit file accordingly.
Sep 20 10:44:04 localhost systemd-journald[953]: Journal started
Sep 20 10:44:04 localhost systemd-journald[953]: Runtime Journal (/run/log/journal/f558abe82fd74ef9b4b410c63a636368) is 4.0M, max 32.0M, 28.0M free.
Sep 20 10:44:04 localhost systemd-tmpfiles[911]: /etc/tmpfiles.d/openvpn.conf:1: Line references path below legacy directory /var/run/, updating /var/run/openvpn → /run/openvpn; please update the tmpfiles.d/ drop-in file accordingly.
Sep 20 10:44:04 localhost resin-partition-mounter[880]: INFO: /dev/disk/by-state/active (active) already mounted in /mnt/sysroot/active.
Sep 20 10:44:04 localhost resin-partition-mounter[881]: INFO: Mounting /dev/disk/by-state/resin-boot (resin-boot) in /mnt/boot.
Sep 20 10:44:04 localhost resin-partition-mounter[882]: INFO: /dev/disk/by-state/resin-state (resin-state) already mounted in /mnt/state.
Sep 20 10:44:04 localhost timeinit-buildtime.sh[902]: [timeinit-buildtime.sh][INFO] Setting system time from build time.
Sep 20 10:44:04 localhost timeinit-buildtime.sh[902]: [timeinit-buildtime.sh][INFO] System time already set.
Sep 20 10:44:04 localhost os-config[909]: `deviceApiKey` already generated
Sep 20 10:44:04 localhost resin-partition-mounter[919]: INFO: /dev/disk/by-state/resin-data (resin-data) already mounted in /mnt/data.
Sep 20 10:44:04 localhost fake-hwclock[947]: [fake-hwclock][INFO] Unable to read saved clock information: /etc/fake-hwclock/fake-hwclock.data does not exist
Sep 20 10:44:04 localhost systemd-tmpfiles[957]: /etc/tmpfiles.d/openvpn.conf:1: Line references path below legacy directory /var/run/, updating /var/run/openvpn → /run/openvpn; please update the tmpfiles.d/ drop-in file accordingly.
Sep 20 10:44:04 localhost bindmount[958]: INFO: Bindmounting /etc/NetworkManager/conf.d in /mnt/state/root-overlay/etc/NetworkManager/conf.d ...
Sep 20 10:44:04 localhost bindmount[958]: INFO: Successfully mounted /mnt/state/root-overlay/etc/NetworkManager/conf.d.
Sep 20 10:44:05 localhost bindmount[959]: INFO: Bindmounting /etc/NetworkManager/system-connections in /mnt/state/root-overlay/etc/NetworkManager/system-connections ...
Sep 20 10:44:05 localhost bindmount[959]: INFO: Successfully mounted /mnt/state/root-overlay/etc/NetworkManager/system-connections.
Sep 20 10:44:05 localhost bindmount[960]: INFO: Bindmounting /etc/balena-supervisor in /mnt/state/root-overlay/etc/balena-supervisor ...
Sep 20 10:44:05 localhost bindmount[960]: WARN: /etc/balena-supervisor is not an empty entry. You are going to shadow content.
Sep 20 10:44:05 localhost bindmount[960]: INFO: Successfully mounted /mnt/state/root-overlay/etc/balena-supervisor.
Sep 20 10:44:05 localhost bindmount[961]: INFO: Bindmounting /etc/docker in /mnt/state/root-overlay/etc/docker ...
Sep 20 10:44:05 localhost bindmount[961]: INFO: Successfully mounted /mnt/state/root-overlay/etc/docker.
Sep 20 10:44:05 localhost bindmount[962]: INFO: Bindmounting /etc/hostname in /mnt/state/root-overlay/etc/hostname ...
Sep 20 10:44:05 localhost bindmount[962]: INFO: Successfully mounted /mnt/state/root-overlay/etc/hostname.
Sep 20 10:44:05 localhost bindmount[963]: INFO: Bindmounting /etc/openvpn in /mnt/state/root-overlay/etc/openvpn ...
Sep 20 10:44:05 localhost bindmount[963]: INFO: Successfully mounted /mnt/state/root-overlay/etc/openvpn.
Sep 20 10:44:05 localhost bindmount[964]: INFO: Bindmounting /etc/ssh/hostkeys in /mnt/state/root-overlay/etc/ssh/hostkeys ...
Sep 20 10:44:05 localhost bindmount[964]: INFO: Successfully mounted /mnt/state/root-overlay/etc/ssh/hostkeys.
Sep 20 10:44:05 localhost bindmount[965]: INFO: Bindmounting /etc/udev/rules.d in /mnt/state/root-overlay/etc/udev/rules.d ...
Sep 20 10:44:05 localhost bindmount[965]: INFO: Successfully mounted /mnt/state/root-overlay/etc/udev/rules.d.
Sep 20 10:44:05 localhost bindmount[966]: INFO: Bindmounting /home/root/.docker in /mnt/state/root-overlay/home/root/.docker ...
Sep 20 10:44:05 localhost bindmount[966]: INFO: Successfully mounted /mnt/state/root-overlay/home/root/.docker.
Sep 20 10:44:05 localhost bindmount[967]: INFO: Bindmounting /home/root/.rnd in /mnt/state/root-overlay/home/root/.rnd ...
Sep 20 10:44:05 localhost bindmount[967]: INFO: Successfully mounted /mnt/state/root-overlay/home/root/.rnd.
Sep 20 10:44:05 localhost bindmount[968]: INFO: Bindmounting /home/root/.ssh in /mnt/state/root-overlay/home/root/.ssh ...
Sep 20 10:44:05 localhost bindmount[968]: INFO: Successfully mounted /mnt/state/root-overlay/home/root/.ssh.
Sep 20 10:44:05 localhost bindmount[969]: INFO: Bindmounting /usr/share/ca-certificates/balena in /mnt/state/root-overlay/usr/share/ca-certificates/balena ...
Sep 20 10:44:05 localhost bindmount[969]: INFO: Successfully mounted /mnt/state/root-overlay/usr/share/ca-certificates/balena.
Sep 20 10:44:05 localhost bindmount[970]: INFO: Bindmounting /var/lib/NetworkManager in /mnt/state/root-overlay/var/lib/NetworkManager ...
Sep 20 10:44:05 localhost bindmount[970]: INFO: Successfully mounted /mnt/state/root-overlay/var/lib/NetworkManager.
Sep 20 10:44:05 localhost bindmount[971]: INFO: Bindmounting /var/lib/bluetooth in /mnt/state/root-overlay/var/lib/bluetooth ...
Sep 20 10:44:05 localhost bindmount[971]: INFO: Successfully mounted /mnt/state/root-overlay/var/lib/bluetooth.
Sep 20 10:44:05 localhost bindmount[972]: INFO: Bindmounting /var/lib/chrony in /mnt/state/root-overlay/var/lib/chrony ...
Sep 20 10:44:05 localhost bindmount[972]: INFO: Successfully mounted /mnt/state/root-overlay/var/lib/chrony.
Sep 20 10:44:05 localhost bindmount[973]: INFO: Bindmounting /var/lib/systemd in /mnt/state/root-overlay/var/lib/systemd ...
Sep 20 10:44:05 localhost bindmount[973]: WARN: /var/lib/systemd is not an empty entry. You are going to shadow content.
Sep 20 10:44:05 localhost bindmount[973]: INFO: Successfully mounted /mnt/state/root-overlay/var/lib/systemd.
Sep 20 10:44:06 localhost audit: AUDIT1334 prog-id=5 op=LOAD
Sep 20 10:44:06 localhost kernel: audit: type=1334 audit(1600598646.330:2): prog-id=5 op=LOAD
Sep 20 10:44:06 localhost audit: AUDIT1334 prog-id=6 op=LOAD
Sep 20 10:44:06 localhost kernel: audit: type=1334 audit(1600598646.340:3): prog-id=6 op=LOAD
Sep 20 10:44:06 localhost systemd-udevd[976]: Configuration file /lib/udev/rules.d/10-local-bt-hci-up.rules is marked executable. Please remove executable permission bits. Proceeding anyway.
Sep 20 10:44:06 localhost systemd-udevd[976]: /lib/udev/rules.d/50-udev-default.rules:39 Unknown group 'render', ignoring
Sep 20 10:44:06 localhost systemd-udevd[976]: /lib/udev/rules.d/50-udev-default.rules:40 Unknown group 'render', ignoring
Sep 20 10:44:06 localhost systemd-udevd[976]: /lib/udev/rules.d/99-com.rules:10 Invalid value "/bin/sh -c 'ALIASES=/proc/device-tree/aliases; if cmp -s $ALIASES/uart0 $ALIASES/serial0; then echo 0;elif cmp -s $ALIASES/uart0 $ALIASES/serial1; then echo 1; else exit 1; fi'" for PROGRAM (char 58: invalid substitution type), ignoring, but please fix it.
Sep 20 10:44:06 localhost systemd-udevd[976]: /lib/udev/rules.d/99-com.rules:21 Invalid value "/bin/sh -c 'ALIASES=/proc/device-tree/aliases; if cmp -s $ALIASES/uart1 $ALIASES/serial0; then echo 0; elif cmp -s $ALIASES/uart1 $ALIASES/serial1; then echo 1; else exit 1; fi '" for PROGRAM (char 58: invalid substitution type), ignoring, but please fix it.
Sep 20 10:44:08 localhost kernel: mc: Linux media interface: v0.10
Sep 20 10:44:08 localhost kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned.
Sep 20 10:44:08 localhost kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver
Sep 20 10:44:08 localhost kernel: [vc_sm_connected_init]: start
Sep 20 10:44:08 localhost kernel: vc_sm_cma_vchi_init: failed to open VCHI service (-1)
Sep 20 10:44:08 localhost kernel: [vc_sm_connected_init]: failed to initialize shared memory service
Sep 20 10:44:08 localhost kernel: videodev: Linux video capture interface: v2.00
Sep 20 10:44:09 localhost kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
Sep 20 10:44:09 localhost kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
Sep 20 10:44:09 localhost kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
Sep 20 10:44:09 localhost kernel: bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned.
Sep 20 10:44:09 localhost kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned.
Sep 20 10:44:09 localhost kernel: bcm2835_mmal_vchiq: Failed to open VCHI service connection (status=-1)
Sep 20 10:44:09 localhost kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned.
Sep 20 10:44:09 localhost kernel: bcm2835_mmal_vchiq: Failed to open VCHI service connection (status=-1)
Sep 20 10:44:09 localhost kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned.
Sep 20 10:44:09 localhost kernel: bcm2835_audio bcm2835_audio: there is not valid maps for state default
Sep 20 10:44:09 localhost kernel: bcm2835_mmal_vchiq: Failed to open VCHI service connection (status=-1)
Sep 20 10:44:09 localhost kernel: bcm2835_audio bcm2835_audio: card created with 8 channels
Sep 20 10:44:11 localhost kernel: zram: Can't change algorithm for initialized device
Sep 20 10:44:11 localhost systemd-udevd[977]: zram0: Process '/lib/udev/zram-swap-init /dev/zram0' failed with exit code 1.
Sep 20 10:44:11 localhost systemd-udevd[979]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
Sep 20 10:44:14 localhost udevadm[955]: systemd-udev-settle.service is deprecated.
Sep 20 10:44:18 localhost kernel: Adding 243412k swap on /dev/zram0.  Priority:-2 extents:1 across:243412k SSFS
Sep 20 10:44:18 localhost rngd[1336]: Initializing available sources
Sep 20 10:44:18 localhost rngd[1336]: [hwrng ]: Initialized
Sep 20 10:44:18 localhost rngd[1336]: [jitter]: JITTER rng fails with code 2
Sep 20 10:44:18 localhost rngd[1336]: [jitter]: Initialization Failed
Sep 20 10:44:20 localhost btuart[1351]: /usr/bin/btuart: line 7: warning: command substitution: ignored null byte in input
Sep 20 10:44:20 localhost kernel: audit: type=1334 audit(1600598660.800:4): prog-id=7 op=LOAD
Sep 20 10:44:20 localhost audit: AUDIT1334 prog-id=7 op=LOAD
Sep 20 10:44:20 localhost audit: AUDIT1334 prog-id=8 op=LOAD
Sep 20 10:44:20 localhost kernel: audit: type=1334 audit(1600598660.910:5): prog-id=8 op=LOAD
Sep 20 10:44:22 localhost chronyd[1346]: 2020-09-20T10:44:22Z chronyd version 4.0 starting (+CMDMON +NTP +REFCLOCK +RTC -PRIVDROP -SCFILTER -SIGND +ASYNCDNS -NTS -SECHASH +IPV6 -DEBUG)
Sep 20 10:44:22 localhost ModemManager[1343]: ModemManager (version 1.14.2) starting in system bus...
Sep 20 10:44:22 localhost btuart[1351]: /usr/bin/btuart: line 20: warning: command substitution: ignored null byte in input
Sep 20 10:44:22 localhost btuart[1351]: /usr/bin/btuart: line 21: warning: command substitution: ignored null byte in input
Sep 20 10:44:23 localhost kernel: uart-pl011 20201000.serial: no DMA platform data
Sep 20 10:44:28 localhost kernel: Bluetooth: Core ver 2.22
Sep 20 10:44:28 localhost kernel: NET: Registered protocol family 31
Sep 20 10:44:28 localhost kernel: Bluetooth: HCI device and connection manager initialized
Sep 20 10:44:28 localhost kernel: Bluetooth: HCI socket layer initialized
Sep 20 10:44:28 localhost kernel: Bluetooth: L2CAP socket layer initialized
Sep 20 10:44:28 localhost kernel: Bluetooth: SCO socket layer initialized
Sep 20 10:44:28 localhost kernel: Bluetooth: HCI UART driver ver 2.3
Sep 20 10:44:28 localhost kernel: Bluetooth: HCI UART protocol H4 registered
Sep 20 10:44:28 localhost kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered
Sep 20 10:44:28 localhost kernel: Bluetooth: HCI UART protocol Broadcom registered
Sep 20 10:44:28 localhost btuart[1401]: bcm43xx_init
Sep 20 10:44:28 localhost btuart[1401]: Flash firmware /lib/firmware/brcm/BCM43430A1.hcd
Sep 20 10:44:28 localhost btuart[1401]: Set BDADDR UART: b8:27:eb:ea:e0:13
Sep 20 10:44:28 localhost btuart[1401]: Set Controller UART speed to 3000000 bit/s
Sep 20 10:44:28 localhost btuart[1401]: Device setup complete
Sep 20 10:44:30 localhost extract-balena-ca[1350]: [extract-balena-ca][INFO] The config.json file does not contain custom CA
Sep 20 10:44:31 localhost systemd-logind[1402]: New seat seat0.
Sep 20 10:44:31 localhost bash[1345]: balena-net-config: Found config.json in /mnt/boot/config.json .
Sep 20 10:44:32 localhost bthelper[1418]: Raspberry Pi BDADDR already set
Sep 20 10:44:32 localhost systemd-udevd[1407]: Using default interface naming scheme 'v243'.
Sep 20 10:44:33 localhost systemd-udevd[1407]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
Sep 20 10:44:33 localhost os-networkmanager[1357]: [os-networkmanager][INFO] Found config.json in /mnt/boot/config.json .
Sep 20 10:44:33 localhost os-networkmanager[1357]: [os-networkmanager][INFO] Using NetworkManager configuration fragment file in /etc/NetworkManager/conf.d/os-networkmanager.conf.
Sep 20 10:44:33 localhost os-networkmanager[1357]: [os-networkmanager][INFO] Connectivity URI not defined. Using default.
Sep 20 10:44:33 localhost os-networkmanager[1357]: [os-networkmanager][INFO] Using connectivity URI      : https://api.balena-cloud.com/connectivity-check
Sep 20 10:44:33 localhost os-networkmanager[1357]: [os-networkmanager][INFO] Using connectivity interval : 3600
Sep 20 10:44:33 localhost os-networkmanager[1357]: [os-networkmanager][INFO] Using connectivity response : ""
Sep 20 10:44:34 localhost os-networkmanager[1357]: [os-networkmanager][INFO] NM custom configuration fragment already in place.
Sep 20 10:44:35 localhost bindmount[1456]: INFO: Bindmounting /var/log/journal in /mnt/data/root-overlay/var/log/journal ...
Sep 20 10:44:35 localhost bindmount[1456]: INFO: Created /mnt/data/root-overlay/var/log/journal, sync filesystems...
Sep 20 10:44:35 localhost bindmount[1456]: INFO: Successfully mounted /mnt/data/root-overlay/var/log/journal.
Sep 20 10:44:35 localhost bluetoothd[1434]: Bluetooth daemon 5.58
Sep 20 10:44:35 localhost bluetoothd[1434]: Starting SDP server
Sep 20 10:44:36 localhost kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3
Sep 20 10:44:36 localhost kernel: Bluetooth: BNEP filters: protocol multicast
Sep 20 10:44:36 localhost kernel: Bluetooth: BNEP socket layer initialized
Sep 20 10:44:36 localhost bluetoothd[1434]: Bluetooth management interface 1.18 initialized
Sep 20 10:44:36 localhost dbus-daemon[1348]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.3' (uid=0 pid=1434 comm="/usr/libexec/bluetooth/bluetoothd --experimental ")
Sep 20 10:44:36 localhost kernel: NET: Registered protocol family 38
Sep 20 10:44:37 localhost bluetoothd[1434]: Battery Provider Manager created
Sep 20 10:44:37 localhost systemd-journald[953]: Time spent on flushing to /var is 778.976ms for 353 entries.
Sep 20 10:44:37 localhost systemd-journald[953]: System Journal (/var/log/journal/f558abe82fd74ef9b4b410c63a636368) is 4.0M, max 32.0M, 27.9M free.
Sep 20 10:44:38 localhost kernel: audit: type=1334 audit(1600598677.801:6): prog-id=9 op=LOAD
Sep 20 10:44:38 localhost kernel: audit: type=1334 audit(1600598677.801:7): prog-id=10 op=LOAD
Sep 20 10:44:37 localhost audit: AUDIT1334 prog-id=9 op=LOAD
Sep 20 10:44:37 localhost audit: AUDIT1334 prog-id=10 op=LOAD
Sep 20 10:44:37 localhost bluetoothd[1434]: Failed to set privacy: Rejected (0x0b)
Sep 20 10:44:38 localhost bthelper[1477]: Changing power off succeeded
Sep 20 10:44:37 localhost bluetoothd[1434]: Adv Monitor Manager created with supported features:0x00000000, enabled features:0x00000000, max number of supported monitors:32, max number of supported patterns:16
Sep 20 10:44:38 localhost bluetoothd[1434]: Path / reserved for Adv Monitor app :1.4
Sep 20 10:44:38 localhost bluetoothd[1434]: Adv Monitor app :1.4 disconnected from D-Bus
Sep 20 10:44:38 localhost resin-persistent-logs[1370]: resin-persistent-logs: Persistent logging activated.
Sep 20 10:44:39 localhost bluetoothd[1434]: Path / reserved for Adv Monitor app :1.5
Sep 20 10:44:39 localhost bthelper[1480]: AdvertisementMonitor path registered
Sep 20 10:44:39 localhost bluetoothd[1434]: Adv Monitor app :1.5 disconnected from D-Bus
Sep 20 10:44:40 localhost bash[1344]: [INFO] balena-unique-key : Device already has uuid assigned.
Sep 20 10:44:40 localhost NetworkManager[1466]: <info>  [1600598680.7298] NetworkManager (version 1.28.0) is starting... (for the first time)
Sep 20 10:44:40 localhost NetworkManager[1466]: <info>  [1600598680.7423] Read config: /etc/NetworkManager/NetworkManager.conf (etc: os-networkmanager.conf)
Sep 20 10:44:40 localhost NetworkManager[1466]: <info>  [1600598680.9714] bus-manager: acquired D-Bus service "org.freedesktop.NetworkManager"
Sep 20 10:44:41 localhost NetworkManager[1466]: <info>  [1600598681.4131] manager[0x2308070]: monitoring kernel firmware directory '/lib/firmware'.
Sep 20 10:44:41 localhost dnsmasq[1486]: dnsmasq: syntax check OK.
Sep 20 10:44:41 localhost bash[1490]: Board specific initialization...
Sep 20 10:44:42 localhost dnsmasq[1491]: dnsmasq[1491]: started, version 2.84rc2 cachesize 150
Sep 20 10:44:42 localhost kernel: i2c /dev entries driver
Sep 20 10:44:42 localhost dnsmasq[1491]: dnsmasq[1491]: compile time options: IPv6 GNU-getopt DBus no-UBus no-i18n no-IDN DHCP DHCPv6 no-Lua TFTP no-conntrack ipset auth no-cryptohash no-DNSSEC loop-detect inotify dumpfile
Sep 20 10:44:42 localhost dnsmasq[1491]: dnsmasq[1491]: DBus support enabled: connected to system bus
Sep 20 10:44:42 localhost dnsmasq[1491]: dnsmasq[1491]: reading /etc/resolv.dnsmasq
Sep 20 10:44:42 localhost dnsmasq[1491]: dnsmasq[1491]: using nameserver 8.8.8.8#53
Sep 20 10:44:42 localhost dnsmasq[1491]: dnsmasq[1491]: read /etc/hosts - 6 addresses
Sep 20 10:44:42 localhost dnsmasq[1491]: dnsmasq[1491]: using nameserver 8.8.8.8#53
Sep 20 10:44:42 localhost dnsmasq[1491]: dnsmasq[1491]: using nameserver 8.8.8.8#53
Sep 20 10:44:42 localhost os-sshkeys[1362]: os-sshkeys: No custom SSH public keys configured.
Sep 20 10:44:42 localhost os-udevrules[1364]: os-udevrules: No custom udev rules found in /mnt/boot/config.json
Sep 20 10:44:44 localhost os-config[1489]: Fetching service configuration from https://api.balena-cloud.com/os/v1/config...
Sep 20 10:44:44 localhost os-config[1489]: https://api.balena-cloud.com/os/v1/config: error trying to connect: failed to lookup address information: Temporary failure in name resolution
Sep 20 10:44:44 localhost balena-ntp-config[1454]: balena-ntp-config: Found config.json in /mnt/boot/config.json .
Sep 20 10:44:45 localhost dbus-daemon[1348]: [system] Successfully activated service 'org.freedesktop.hostname1'
Sep 20 10:44:45 localhost NetworkManager[1466]: <info>  [1600598685.3097] hostname: hostname: using hostnamed
Sep 20 10:44:45 localhost NetworkManager[1466]: <info>  [1600598685.3182] hostname: hostname changed from (none) to "38050cf"
Sep 20 10:44:45 localhost NetworkManager[1466]: <info>  [1600598685.3381] dns-mgr[0x2314000]: init: dns=default,systemd-resolved rc-manager=resolvconf (auto)
Sep 20 10:44:45 localhost NetworkManager[1466]: <info>  [1600598685.4439] manager[0x2308070]: rfkill: Wi-Fi hardware radio set enabled
Sep 20 10:44:45 localhost NetworkManager[1466]: <info>  [1600598685.4783] manager[0x2308070]: rfkill: WWAN hardware radio set enabled
Sep 20 10:44:45 localhost NetworkManager[1466]: <info>  [1600598685.8014] Loaded device plugin: NMBluezManager (/usr/lib/NetworkManager/1.28.0/libnm-device-plugin-bluetooth.so)
Sep 20 10:44:45 localhost NetworkManager[1466]: <info>  [1600598685.8343] Loaded device plugin: NMWwanFactory (/usr/lib/NetworkManager/1.28.0/libnm-device-plugin-wwan.so)
Sep 20 10:44:45 localhost NetworkManager[1466]: <info>  [1600598685.8580] Loaded device plugin: NMWifiFactory (/usr/lib/NetworkManager/1.28.0/libnm-device-plugin-wifi.so)
Sep 20 10:44:45 localhost NetworkManager[1466]: <info>  [1600598685.8748] Loaded device plugin: NMAtmManager (/usr/lib/NetworkManager/1.28.0/libnm-device-plugin-adsl.so)
Sep 20 10:44:45 localhost NetworkManager[1466]: <info>  [1600598685.9141] manager: rfkill: Wi-Fi enabled by radio killswitch; enabled by state file
Sep 20 10:44:45 localhost NetworkManager[1466]: <info>  [1600598685.9418] manager: rfkill: WWAN enabled by radio killswitch; enabled by state file
Sep 20 10:44:45 localhost NetworkManager[1466]: <info>  [1600598685.9552] manager: Networking is enabled by state file
Sep 20 10:44:45 localhost dbus-daemon[1348]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service' requested by ':1.6' (uid=0 pid=1466 comm="/usr/sbin/NetworkManager --no-daemon ")
Sep 20 10:44:45 localhost NetworkManager[1466]: <info>  [1600598685.9796] dhcp-init: Using DHCP client 'internal'
Sep 20 10:44:45 localhost NetworkManager[1466]: <info>  [1600598685.9898] settings: Loaded settings plugin: keyfile (internal)
Sep 20 10:44:46 localhost NetworkManager[1466]: <info>  [1600598686.0833] device (lo): carrier: link connected
Sep 20 10:44:46 localhost NetworkManager[1466]: <info>  [1600598686.1167] manager: (lo): new Generic device (/org/freedesktop/NetworkManager/Devices/1)
Sep 20 10:44:46 localhost NetworkManager[1466]: <info>  [1600598686.3248] manager: (resin-dns): new Bridge device (/org/freedesktop/NetworkManager/Devices/2)
Sep 20 10:44:46 localhost dbus-daemon[1348]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
Sep 20 10:44:46 localhost NetworkManager[1466]: <info>  [1600598686.9779] modem-manager: ModemManager available
Sep 20 10:44:46 localhost NetworkManager[1466]: <info>  [1600598686.9994] manager: startup complete
Sep 20 10:44:48 localhost balena-hostname[1482]: [balena-hostname][INFO] Setting hostname.
Sep 20 10:44:48 localhost balena-hostname[1482]: [balena-hostname][INFO] Generating default based on short UUID.
Sep 20 10:44:48 38050cf balena-hostname[1482]: [balena-hostname][INFO] Hostname set to 38050cf
Sep 20 10:44:49 38050cf sh[1495]: balena-proxy-config: Found config.json in /mnt/boot/config.json .
Sep 20 10:44:49 38050cf avahi-daemon[1534]: Found user 'avahi' (UID 994) and group 'avahi' (GID 991).
Sep 20 10:44:49 38050cf avahi-daemon[1534]: Successfully dropped root privileges.
Sep 20 10:44:49 38050cf avahi-daemon[1534]: avahi-daemon 0.7 starting up.
Sep 20 10:44:49 38050cf avahi-daemon[1534]: Successfully called chroot().
Sep 20 10:44:49 38050cf avahi-daemon[1534]: Successfully dropped remaining capabilities.
Sep 20 10:44:49 38050cf avahi-daemon[1534]: Loading service file /services/ssh.service.
Sep 20 10:44:49 38050cf avahi-daemon[1534]: Joining mDNS multicast group on interface resin-dns.IPv4 with address 10.114.102.1.
Sep 20 10:44:49 38050cf avahi-daemon[1534]: New relevant interface resin-dns.IPv4 for mDNS.
Sep 20 10:44:49 38050cf avahi-daemon[1534]: Network interface enumeration completed.
Sep 20 10:44:49 38050cf avahi-daemon[1534]: Registering new address record for 10.114.102.1 on resin-dns.IPv4.
Sep 20 10:44:49 38050cf sh[1535]: iptables: No chain/target/match by that name.
Sep 20 10:44:49 38050cf sh[1539]: iptables: No chain/target/match by that name.
Sep 20 10:44:49 38050cf sh[1540]: iptables v1.8.4 (legacy): Couldn't load target `REDSOCKS':No such file or directory
Sep 20 10:44:49 38050cf sh[1540]: Try `iptables -h' or 'iptables --help' for more information.
Sep 20 10:44:49 38050cf sh[1541]: iptables v1.8.4 (legacy): Couldn't load target `REDSOCKS':No such file or directory
Sep 20 10:44:49 38050cf sh[1541]: Try `iptables -h' or 'iptables --help' for more information.
Sep 20 10:44:49 38050cf sh[1542]: iptables v1.8.4 (legacy): Couldn't load target `REDSOCKS':No such file or directory
Sep 20 10:44:49 38050cf sh[1542]: Try `iptables -h' or 'iptables --help' for more information.
Sep 20 10:44:49 38050cf sh[1543]: iptables: No chain/target/match by that name.
Sep 20 10:44:49 38050cf sh[1544]: iptables: No chain/target/match by that name.
Sep 20 10:44:49 38050cf sh[1495]: balena-proxy-config: No proxy configuration found, skipping.
Sep 20 10:44:50 38050cf balenad[1497]: time="2020-09-20T10:44:50.029210000Z" level=info msg="Starting up"
Sep 20 10:44:50 38050cf balenad[1497]: time="2020-09-20T10:44:50.034382000Z" level=warning msg="Running experimental build"
Sep 20 10:44:50 38050cf balenad[1497]: time="2020-09-20T10:44:50.135172000Z" level=info msg="libcontainerd: started new balena-engine-containerd process" pid=1545
Sep 20 10:44:50 38050cf avahi-daemon[1534]: Server startup complete. Host name is 38050cf.local. Local service cookie is 1784746841.
Sep 20 10:44:51 38050cf balenad[1545]: time="2020-09-20T10:44:51.004850000Z" level=info msg="starting containerd" revision= version=1.2.0+unknown
Sep 20 10:44:51 38050cf balenad[1545]: time="2020-09-20T10:44:51.017006000Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1
Sep 20 10:44:51 38050cf balenad[1545]: time="2020-09-20T10:44:51.021184000Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1
Sep 20 10:44:51 38050cf balenad[1545]: time="2020-09-20T10:44:51.076545000Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1
Sep 20 10:44:51 38050cf balenad[1545]: time="2020-09-20T10:44:51.081159000Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1
Sep 20 10:44:51 38050cf balenad[1545]: time="2020-09-20T10:44:51.090150000Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1
Sep 20 10:44:51 38050cf balenad[1545]: time="2020-09-20T10:44:51.092658000Z" level=info msg="metadata content store policy set" policy=shared
Sep 20 10:44:51 38050cf balenad[1545]: time="2020-09-20T10:44:51.115289000Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1
Sep 20 10:44:51 38050cf balenad[1545]: time="2020-09-20T10:44:51.117349000Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1
Sep 20 10:44:51 38050cf balenad[1545]: time="2020-09-20T10:44:51.119985000Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1
Sep 20 10:44:51 38050cf balenad[1545]: time="2020-09-20T10:44:51.122179000Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1
Sep 20 10:44:51 38050cf balenad[1545]: time="2020-09-20T10:44:51.128424000Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1
Sep 20 10:44:51 38050cf balenad[1545]: time="2020-09-20T10:44:51.131072000Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1
Sep 20 10:44:51 38050cf balenad[1545]: time="2020-09-20T10:44:51.133177000Z" level=info msg="loading plugin \"io.containerd.service.v1.leases-service\"..." type=io.containerd.service.v1
Sep 20 10:44:51 38050cf balenad[1545]: time="2020-09-20T10:44:51.135270000Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1
Sep 20 10:44:51 38050cf balenad[1545]: time="2020-09-20T10:44:51.137147000Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1
Sep 20 10:44:51 38050cf balenad[1545]: time="2020-09-20T10:44:51.141533000Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1
Sep 20 10:44:51 38050cf balenad[1545]: time="2020-09-20T10:44:51.146429000Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2
Sep 20 10:44:51 38050cf balenad[1545]: time="2020-09-20T10:44:51.164730000Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1
Sep 20 10:44:51 38050cf avahi-daemon[1534]: Service "38050cf" (/services/ssh.service) successfully established.
Sep 20 10:44:51 38050cf balenad[1545]: time="2020-09-20T10:44:51.233155000Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1
Sep 20 10:44:51 38050cf balenad[1545]: time="2020-09-20T10:44:51.235187000Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1
Sep 20 10:44:51 38050cf balenad[1545]: time="2020-09-20T10:44:51.237330000Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1
Sep 20 10:44:51 38050cf balenad[1545]: time="2020-09-20T10:44:51.239142000Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1
Sep 20 10:44:51 38050cf balenad[1545]: time="2020-09-20T10:44:51.243040000Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1
Sep 20 10:44:51 38050cf balenad[1545]: time="2020-09-20T10:44:51.245409000Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1
Sep 20 10:44:51 38050cf balenad[1545]: time="2020-09-20T10:44:51.247423000Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1
Sep 20 10:44:51 38050cf balenad[1545]: time="2020-09-20T10:44:51.249550000Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1
Sep 20 10:44:51 38050cf balenad[1545]: time="2020-09-20T10:44:51.251390000Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1
Sep 20 10:44:51 38050cf balenad[1545]: time="2020-09-20T10:44:51.255368000Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1
Sep 20 10:44:51 38050cf balenad[1545]: time="2020-09-20T10:44:51.257784000Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1
Sep 20 10:44:51 38050cf balenad[1545]: time="2020-09-20T10:44:51.259724000Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1
Sep 20 10:44:51 38050cf balenad[1545]: time="2020-09-20T10:44:51.260438000Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1
Sep 20 10:44:51 38050cf balenad[1545]: time="2020-09-20T10:44:51.263177000Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1
Sep 20 10:44:51 38050cf balenad[1545]: time="2020-09-20T10:44:51.271736000Z" level=info msg=serving... address=/var/run/balena-engine/containerd/balena-engine-containerd-debug.sock
Sep 20 10:44:51 38050cf balenad[1545]: time="2020-09-20T10:44:51.274487000Z" level=info msg=serving... address=/var/run/balena-engine/containerd/balena-engine-containerd.sock.ttrpc
Sep 20 10:44:51 38050cf balenad[1545]: time="2020-09-20T10:44:51.281112000Z" level=info msg=serving... address=/var/run/balena-engine/containerd/balena-engine-containerd.sock
Sep 20 10:44:51 38050cf balenad[1545]: time="2020-09-20T10:44:51.282760000Z" level=info msg="containerd successfully booted in 0.301759s"
Sep 20 10:44:51 38050cf balenad[1497]: time="2020-09-20T10:44:51.587953000Z" level=warning msg="[graphdriver] WARNING: the aufs storage-driver is deprecated, and will be removed in a future release"
Sep 20 10:44:52 38050cf balenad[1497]: time="2020-09-20T10:44:52.745907000Z" level=warning msg="Your kernel does not support cgroup rt period"
Sep 20 10:44:52 38050cf balenad[1497]: time="2020-09-20T10:44:52.751998000Z" level=warning msg="Your kernel does not support cgroup rt runtime"
Sep 20 10:44:52 38050cf balenad[1497]: time="2020-09-20T10:44:52.752540000Z" level=warning msg="Your kernel does not support cgroup blkio weight"
Sep 20 10:44:52 38050cf balenad[1497]: time="2020-09-20T10:44:52.752861000Z" level=warning msg="Your kernel does not support cgroup blkio weight_device"
Sep 20 10:44:52 38050cf balenad[1497]: time="2020-09-20T10:44:52.753313000Z" level=warning msg="Your kernel does not support cgroup blkio throttle.read_bps_device"
Sep 20 10:44:52 38050cf balenad[1497]: time="2020-09-20T10:44:52.753720000Z" level=warning msg="Your kernel does not support cgroup blkio throttle.write_bps_device"
Sep 20 10:44:52 38050cf balenad[1497]: time="2020-09-20T10:44:52.754022000Z" level=warning msg="Your kernel does not support cgroup blkio throttle.read_iops_device"
Sep 20 10:44:52 38050cf balenad[1497]: time="2020-09-20T10:44:52.754305000Z" level=warning msg="Your kernel does not support cgroup blkio throttle.write_iops_device"
Sep 20 10:44:52 38050cf balenad[1497]: time="2020-09-20T10:44:52.754690000Z" level=warning msg="Unable to find cpuset cgroup in mounts"
Sep 20 10:44:52 38050cf balenad[1497]: time="2020-09-20T10:44:52.765417000Z" level=info msg="Loading containers: start."
Sep 20 10:44:54 38050cf kernel: Bridge firewalling registered
Sep 20 10:44:54 38050cf os-config[1489]: Awaiting service configuration...
Sep 20 10:44:54 38050cf audit[1591]: NETFILTER_CFG table=nat family=2 entries=5 op=xt_replace pid=1591 comm="iptables"
Sep 20 10:44:54 38050cf kernel: audit: type=1325 audit(1600598694.741:8): table=nat family=2 entries=5 op=xt_replace pid=1591 comm="iptables"
Sep 20 10:44:54 38050cf audit[1593]: NETFILTER_CFG table=filter family=2 entries=4 op=xt_replace pid=1593 comm="iptables"
Sep 20 10:44:54 38050cf kernel: audit: type=1325 audit(1600598694.791:9): table=filter family=2 entries=4 op=xt_replace pid=1593 comm="iptables"
Sep 20 10:44:54 38050cf audit[1595]: NETFILTER_CFG table=filter family=2 entries=6 op=xt_replace pid=1595 comm="iptables"
Sep 20 10:44:54 38050cf kernel: audit: type=1325 audit(1600598694.851:10): table=filter family=2 entries=6 op=xt_replace pid=1595 comm="iptables"
Sep 20 10:44:54 38050cf audit[1597]: NETFILTER_CFG table=filter family=2 entries=8 op=xt_replace pid=1597 comm="iptables"
Sep 20 10:44:54 38050cf kernel: audit: type=1325 audit(1600598694.901:11): table=filter family=2 entries=8 op=xt_replace pid=1597 comm="iptables"
Sep 20 10:44:54 38050cf audit[1599]: NETFILTER_CFG table=filter family=2 entries=10 op=xt_replace pid=1599 comm="iptables"
Sep 20 10:44:54 38050cf kernel: audit: type=1325 audit(1600598694.961:12): table=filter family=2 entries=10 op=xt_replace pid=1599 comm="iptables"
Sep 20 10:44:55 38050cf audit[1601]: NETFILTER_CFG table=filter family=2 entries=11 op=xt_replace pid=1601 comm="iptables"
Sep 20 10:44:55 38050cf kernel: audit: type=1325 audit(1600598695.011:13): table=filter family=2 entries=11 op=xt_replace pid=1601 comm="iptables"
Sep 20 10:44:55 38050cf audit[1602]: NETFILTER_CFG table=filter family=2 entries=12 op=xt_replace pid=1602 comm="iptables"
Sep 20 10:44:55 38050cf kernel: audit: type=1325 audit(1600598695.061:14): table=filter family=2 entries=12 op=xt_replace pid=1602 comm="iptables"
Sep 20 10:44:55 38050cf systemd-udevd[1570]: Using default interface naming scheme 'v243'.
Sep 20 10:44:55 38050cf NetworkManager[1466]: <info>  [1600598695.2512] manager: (supervisor0): new Bridge device (/org/freedesktop/NetworkManager/Devices/3)
Sep 20 10:44:55 38050cf systemd-udevd[1570]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
Sep 20 10:44:55 38050cf audit[1609]: NETFILTER_CFG table=nat family=2 entries=7 op=xt_replace pid=1609 comm="iptables"
Sep 20 10:44:55 38050cf kernel: audit: type=1325 audit(1600598695.461:15): table=nat family=2 entries=7 op=xt_replace pid=1609 comm="iptables"
Sep 20 10:44:55 38050cf audit[1611]: NETFILTER_CFG table=nat family=2 entries=8 op=xt_replace pid=1611 comm="iptables"
Sep 20 10:44:55 38050cf kernel: audit: type=1325 audit(1600598695.611:16): table=nat family=2 entries=8 op=xt_replace pid=1611 comm="iptables"
Sep 20 10:44:55 38050cf audit[1614]: NETFILTER_CFG table=filter family=2 entries=12 op=xt_replace pid=1614 comm="iptables"
Sep 20 10:44:55 38050cf kernel: audit: type=1325 audit(1600598695.711:17): table=filter family=2 entries=12 op=xt_replace pid=1614 comm="iptables"
Sep 20 10:44:55 38050cf audit[1616]: NETFILTER_CFG table=filter family=2 entries=13 op=xt_replace pid=1616 comm="iptables"
Sep 20 10:44:55 38050cf audit[1618]: NETFILTER_CFG table=nat family=2 entries=9 op=xt_replace pid=1618 comm="iptables"
Sep 20 10:44:55 38050cf audit[1620]: NETFILTER_CFG table=nat family=2 entries=10 op=xt_replace pid=1620 comm="iptables"
Sep 20 10:44:56 38050cf audit[1622]: NETFILTER_CFG table=filter family=2 entries=14 op=xt_replace pid=1622 comm="iptables"
Sep 20 10:44:56 38050cf audit[1624]: NETFILTER_CFG table=filter family=2 entries=15 op=xt_replace pid=1624 comm="iptables"
Sep 20 10:44:56 38050cf audit[1626]: NETFILTER_CFG table=filter family=2 entries=16 op=xt_replace pid=1626 comm="iptables"
Sep 20 10:44:56 38050cf audit[1628]: NETFILTER_CFG table=filter family=2 entries=17 op=xt_replace pid=1628 comm="iptables"
Sep 20 10:44:56 38050cf audit[1630]: NETFILTER_CFG table=filter family=2 entries=18 op=xt_replace pid=1630 comm="iptables"
Sep 20 10:44:56 38050cf avahi-daemon[1534]: Joining mDNS multicast group on interface supervisor0.IPv4 with address 10.114.104.1.
Sep 20 10:44:56 38050cf NetworkManager[1466]: <info>  [1600598696.2797] device (supervisor0): state change: unmanaged -> unavailable (reason 'connection-assumed', sys-iface-state: 'external')
Sep 20 10:44:56 38050cf avahi-daemon[1534]: New relevant interface supervisor0.IPv4 for mDNS.
Sep 20 10:44:56 38050cf avahi-daemon[1534]: Registering new address record for 10.114.104.1 on supervisor0.IPv4.
Sep 20 10:44:56 38050cf systemd-udevd[1605]: Using default interface naming scheme 'v243'.
Sep 20 10:44:56 38050cf systemd-udevd[1605]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
Sep 20 10:44:56 38050cf NetworkManager[1466]: <warn>  [1600598696.4980] device (supervisor0): failed to read bridge setting 'vlan_protocol'
Sep 20 10:44:56 38050cf audit[1633]: NETFILTER_CFG table=nat family=2 entries=11 op=xt_replace pid=1633 comm="iptables"
Sep 20 10:44:56 38050cf NetworkManager[1466]: <warn>  [1600598696.5199] device (supervisor0): failed to read bridge setting 'vlan_stats_enabled'
Sep 20 10:44:56 38050cf NetworkManager[1466]: <info>  [1600598696.6432] device (supervisor0): state change: unavailable -> disconnected (reason 'connection-assumed', sys-iface-state: 'external')
Sep 20 10:44:56 38050cf NetworkManager[1466]: <info>  [1600598696.7124] device (supervisor0): Activation: starting connection 'supervisor0' (b869be5e-0bb1-4502-90d4-ddf30bfe1fef)
Sep 20 10:44:56 38050cf audit[1635]: NETFILTER_CFG table=nat family=2 entries=12 op=xt_replace pid=1635 comm="iptables"
Sep 20 10:44:56 38050cf NetworkManager[1466]: <info>  [1600598696.8925] manager: (balena0): new Bridge device (/org/freedesktop/NetworkManager/Devices/4)
Sep 20 10:44:56 38050cf NetworkManager[1466]: <info>  [1600598696.9693] device (supervisor0): state change: disconnected -> prepare (reason 'none', sys-iface-state: 'external')
Sep 20 10:44:56 38050cf audit[1641]: NETFILTER_CFG table=filter family=2 entries=19 op=xt_replace pid=1641 comm="iptables"
Sep 20 10:44:57 38050cf nm-dispatcher[1640]: Setting NTP source on/offline status (connectivity-change NONE).
Sep 20 10:44:57 38050cf NetworkManager[1466]: <info>  [1600598697.0323] device (supervisor0): state change: prepare -> config (reason 'none', sys-iface-state: 'external')
Sep 20 10:44:57 38050cf NetworkManager[1466]: <info>  [1600598697.1026] device (supervisor0): state change: config -> ip-config (reason 'none', sys-iface-state: 'external')
Sep 20 10:44:57 38050cf NetworkManager[1466]: <info>  [1600598697.1969] device (supervisor0): state change: ip-config -> ip-check (reason 'none', sys-iface-state: 'external')
Sep 20 10:44:57 38050cf audit[1648]: NETFILTER_CFG table=filter family=2 entries=20 op=xt_replace pid=1648 comm="iptables"
Sep 20 10:44:57 38050cf NetworkManager[1466]: <info>  [1600598697.3717] device (supervisor0): state change: ip-check -> secondaries (reason 'none', sys-iface-state: 'external')
Sep 20 10:44:57 38050cf NetworkManager[1466]: <info>  [1600598697.4039] device (supervisor0): state change: secondaries -> activated (reason 'none', sys-iface-state: 'external')
Sep 20 10:44:57 38050cf NetworkManager[1466]: <info>  [1600598697.4536] manager: NetworkManager state is now CONNECTED_LOCAL
Sep 20 10:44:57 38050cf NetworkManager[1466]: <info>  [1600598697.5520] device (supervisor0): Activation: successful, device activated.
Sep 20 10:44:57 38050cf audit[1656]: NETFILTER_CFG table=filter family=2 entries=21 op=xt_replace pid=1656 comm="iptables"
Sep 20 10:44:57 38050cf audit[1658]: NETFILTER_CFG table=filter family=2 entries=22 op=xt_replace pid=1658 comm="iptables"
Sep 20 10:44:58 38050cf audit[1660]: NETFILTER_CFG table=filter family=2 entries=23 op=xt_replace pid=1660 comm="iptables"
Sep 20 10:44:58 38050cf audit[1662]: NETFILTER_CFG table=filter family=2 entries=22 op=xt_replace pid=1662 comm="iptables"
Sep 20 10:44:58 38050cf audit[1665]: NETFILTER_CFG table=filter family=2 entries=23 op=xt_replace pid=1665 comm="iptables"
Sep 20 10:44:58 38050cf audit[1667]: NETFILTER_CFG table=filter family=2 entries=24 op=xt_replace pid=1667 comm="iptables"
Sep 20 10:44:58 38050cf avahi-daemon[1534]: Joining mDNS multicast group on interface balena0.IPv4 with address 10.114.101.1.
Sep 20 10:44:58 38050cf avahi-daemon[1534]: New relevant interface balena0.IPv4 for mDNS.
Sep 20 10:44:58 38050cf avahi-daemon[1534]: Registering new address record for 10.114.101.1 on balena0.IPv4.
Sep 20 10:44:58 38050cf NetworkManager[1466]: <info>  [1600598698.4738] manager: (br-3e44b985e598): new Bridge device (/org/freedesktop/NetworkManager/Devices/5)
Sep 20 10:44:58 38050cf systemd-udevd[1605]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
Sep 20 10:44:58 38050cf audit[1672]: NETFILTER_CFG table=nat family=2 entries=13 op=xt_replace pid=1672 comm="iptables"
Sep 20 10:44:58 38050cf audit[1674]: NETFILTER_CFG table=nat family=2 entries=14 op=xt_replace pid=1674 comm="iptables"
Sep 20 10:44:58 38050cf audit[1677]: NETFILTER_CFG table=filter family=2 entries=25 op=xt_replace pid=1677 comm="iptables"
Sep 20 10:44:58 38050cf audit[1679]: NETFILTER_CFG table=filter family=2 entries=26 op=xt_replace pid=1679 comm="iptables"
Sep 20 10:44:59 38050cf audit[1685]: NETFILTER_CFG table=filter family=2 entries=27 op=xt_replace pid=1685 comm="iptables"
Sep 20 10:44:59 38050cf audit[1687]: NETFILTER_CFG table=filter family=2 entries=28 op=xt_replace pid=1687 comm="iptables"
Sep 20 10:44:59 38050cf audit[1689]: NETFILTER_CFG table=filter family=2 entries=29 op=xt_replace pid=1689 comm="iptables"
Sep 20 10:44:59 38050cf audit[1690]: NETFILTER_CFG table=filter family=2 entries=28 op=xt_replace pid=1690 comm="iptables"
Sep 20 10:44:59 38050cf audit[1692]: NETFILTER_CFG table=filter family=2 entries=29 op=xt_replace pid=1692 comm="iptables"
Sep 20 10:44:59 38050cf audit[1694]: NETFILTER_CFG table=filter family=2 entries=30 op=xt_replace pid=1694 comm="iptables"
Sep 20 10:44:59 38050cf avahi-daemon[1534]: Joining mDNS multicast group on interface br-3e44b985e598.IPv4 with address 172.17.0.1.
Sep 20 10:44:59 38050cf avahi-daemon[1534]: New relevant interface br-3e44b985e598.IPv4 for mDNS.
Sep 20 10:44:59 38050cf avahi-daemon[1534]: Registering new address record for 172.17.0.1 on br-3e44b985e598.IPv4.
Sep 20 10:45:01 38050cf balenad[1497]: time="2020-09-20T10:45:01.077335000Z" level=info msg="Removing stale sandbox e1c2de55e42002a1240b5fc60865be45d05f6490097e89fc48840032e0c3b03b (5f2c2b8b8ac2353a081e506f4577a27f9c745bb4b25c71bfb83bc4f5eefaa722)"
Sep 20 10:45:01 38050cf balenad[1497]: time="2020-09-20T10:45:01.103667000Z" level=warning msg="Error (Unable to complete atomic operation, key modified) deleting object [endpoint 25641827b55f2ab1e1fbacbe7ad427af5af34b2dfa09c5d3717d0ee856291dba e77fa1e1087f4af1988030f7e88e6e15222664d35e12c98281bb2e80bb11cd54], retrying...."
Sep 20 10:45:02 38050cf balenad[1497]: time="2020-09-20T10:45:02.729972000Z" level=info msg="Removing stale sandbox 5b6f293f4411a86f3b7c229c838fff3366009af6466597251afef5cf9d4a33c0 (2795154eaef08d1fa6db9b6598c2a2464a37b3221db0e9b86062a60387180c33)"
Sep 20 10:45:02 38050cf balenad[1497]: time="2020-09-20T10:45:02.760876000Z" level=warning msg="Error (Unable to complete atomic operation, key modified) deleting object [endpoint 25641827b55f2ab1e1fbacbe7ad427af5af34b2dfa09c5d3717d0ee856291dba 6cf7dc39ea30707395ea516b008f07117cd3b436b60fcb6eb050dfa535608eff], retrying...."
Sep 20 10:45:03 38050cf audit[1720]: NETFILTER_CFG table=nat family=2 entries=15 op=xt_replace pid=1720 comm="iptables"
Sep 20 10:45:03 38050cf kernel: kauditd_printk_skb: 28 callbacks suppressed
Sep 20 10:45:03 38050cf kernel: audit: type=1325 audit(1600598703.041:46): table=nat family=2 entries=15 op=xt_replace pid=1720 comm="iptables"
Sep 20 10:45:03 38050cf audit[1722]: NETFILTER_CFG table=nat family=2 entries=14 op=xt_replace pid=1722 comm="iptables"
Sep 20 10:45:03 38050cf kernel: audit: type=1325 audit(1600598703.131:47): table=nat family=2 entries=14 op=xt_replace pid=1722 comm="iptables"
Sep 20 10:45:03 38050cf audit[1724]: NETFILTER_CFG table=filter family=2 entries=31 op=xt_replace pid=1724 comm="iptables"
Sep 20 10:45:03 38050cf kernel: audit: type=1325 audit(1600598703.231:48): table=filter family=2 entries=31 op=xt_replace pid=1724 comm="iptables"
Sep 20 10:45:03 38050cf audit[1726]: NETFILTER_CFG table=filter family=2 entries=30 op=xt_replace pid=1726 comm="iptables"
Sep 20 10:45:03 38050cf kernel: audit: type=1325 audit(1600598703.351:49): table=filter family=2 entries=30 op=xt_replace pid=1726 comm="iptables"
Sep 20 10:45:03 38050cf audit[1729]: NETFILTER_CFG table=filter family=2 entries=29 op=xt_replace pid=1729 comm="iptables"
Sep 20 10:45:03 38050cf kernel: audit: type=1325 audit(1600598703.501:50): table=filter family=2 entries=29 op=xt_replace pid=1729 comm="iptables"
Sep 20 10:45:03 38050cf audit[1732]: NETFILTER_CFG table=filter family=2 entries=28 op=xt_replace pid=1732 comm="iptables"
Sep 20 10:45:03 38050cf kernel: audit: type=1325 audit(1600598703.681:51): table=filter family=2 entries=28 op=xt_replace pid=1732 comm="iptables"
Sep 20 10:45:03 38050cf audit[1734]: NETFILTER_CFG table=filter family=2 entries=27 op=xt_replace pid=1734 comm="iptables"
Sep 20 10:45:03 38050cf kernel: audit: type=1325 audit(1600598703.771:52): table=filter family=2 entries=27 op=xt_replace pid=1734 comm="iptables"
Sep 20 10:45:03 38050cf audit[1736]: NETFILTER_CFG table=filter family=2 entries=26 op=xt_replace pid=1736 comm="iptables"
Sep 20 10:45:03 38050cf kernel: audit: type=1325 audit(1600598703.841:53): table=filter family=2 entries=26 op=xt_replace pid=1736 comm="iptables"
Sep 20 10:45:04 38050cf audit[1738]: NETFILTER_CFG table=nat family=2 entries=13 op=xt_replace pid=1738 comm="iptables"
Sep 20 10:45:04 38050cf kernel: audit: type=1325 audit(1600598704.001:54): table=nat family=2 entries=13 op=xt_replace pid=1738 comm="iptables"
Sep 20 10:45:04 38050cf audit[1740]: NETFILTER_CFG table=nat family=2 entries=14 op=xt_replace pid=1740 comm="iptables"
Sep 20 10:45:04 38050cf kernel: audit: type=1325 audit(1600598704.071:55): table=nat family=2 entries=14 op=xt_replace pid=1740 comm="iptables"
Sep 20 10:45:04 38050cf audit[1743]: NETFILTER_CFG table=filter family=2 entries=25 op=xt_replace pid=1743 comm="iptables"
Sep 20 10:45:04 38050cf audit[1745]: NETFILTER_CFG table=filter family=2 entries=26 op=xt_replace pid=1745 comm="iptables"
Sep 20 10:45:04 38050cf os-config[1489]: Awaiting service configuration...
Sep 20 10:45:04 38050cf audit[1751]: NETFILTER_CFG table=filter family=2 entries=27 op=xt_replace pid=1751 comm="iptables"
Sep 20 10:45:04 38050cf audit[1753]: NETFILTER_CFG table=filter family=2 entries=28 op=xt_replace pid=1753 comm="iptables"
Sep 20 10:45:04 38050cf audit[1755]: NETFILTER_CFG table=filter family=2 entries=29 op=xt_replace pid=1755 comm="iptables"
Sep 20 10:45:04 38050cf audit[1756]: NETFILTER_CFG table=filter family=2 entries=28 op=xt_replace pid=1756 comm="iptables"
Sep 20 10:45:04 38050cf audit[1758]: NETFILTER_CFG table=filter family=2 entries=29 op=xt_replace pid=1758 comm="iptables"
Sep 20 10:45:04 38050cf audit[1760]: NETFILTER_CFG table=filter family=2 entries=30 op=xt_replace pid=1760 comm="iptables"
Sep 20 10:45:04 38050cf audit[1762]: NETFILTER_CFG table=filter family=2 entries=31 op=xt_replace pid=1762 comm="iptables"
Sep 20 10:45:04 38050cf audit[1764]: NETFILTER_CFG table=filter family=2 entries=33 op=xt_replace pid=1764 comm="iptables"
Sep 20 10:45:04 38050cf audit[1766]: NETFILTER_CFG table=filter family=2 entries=34 op=xt_replace pid=1766 comm="iptables"
Sep 20 10:45:06 38050cf balenad[1545]: time="2020-09-20T10:45:06.953341000Z" level=info msg="shim balena-engine-containerd-shim started" address=/containerd-shim/218e5efefd5105f7592687513fea7df192047de926cc129e52f6794ac5d8accc.sock debug=false pid=1774
Sep 20 10:45:07 38050cf balenad[1545]: time="2020-09-20T10:45:07.153935000Z" level=info msg="shim balena-engine-containerd-shim started" address=/containerd-shim/ad63686f8638ac215c2932e18a644d539b2d59578718fcec864dcfdc273fabd0.sock debug=false pid=1779
Sep 20 10:45:11 38050cf audit: AUDIT1334 prog-id=9 op=UNLOAD
Sep 20 10:45:11 38050cf kernel: kauditd_printk_skb: 11 callbacks suppressed
Sep 20 10:45:11 38050cf kernel: audit: type=1334 audit(1600598711.201:67): prog-id=9 op=UNLOAD
Sep 20 10:45:11 38050cf audit: AUDIT1334 prog-id=10 op=UNLOAD
Sep 20 10:45:11 38050cf audit: AUDIT1334 prog-id=11 op=LOAD
Sep 20 10:45:11 38050cf kernel: audit: type=1334 audit(1600598711.211:68): prog-id=10 op=UNLOAD
Sep 20 10:45:11 38050cf kernel: audit: type=1334 audit(1600598711.211:69): prog-id=11 op=LOAD
Sep 20 10:45:11 38050cf kernel: audit: type=1334 audit(1600598711.221:70): prog-id=12 op=LOAD
Sep 20 10:45:11 38050cf audit: AUDIT1334 prog-id=12 op=LOAD
Sep 20 10:45:11 38050cf audit: AUDIT1334 prog-id=5 op=UNLOAD
Sep 20 10:45:11 38050cf kernel: audit: type=1334 audit(1600598711.241:71): prog-id=5 op=UNLOAD
Sep 20 10:45:11 38050cf audit: AUDIT1334 prog-id=6 op=UNLOAD
Sep 20 10:45:11 38050cf kernel: audit: type=1334 audit(1600598711.251:72): prog-id=6 op=UNLOAD
Sep 20 10:45:11 38050cf audit: AUDIT1334 prog-id=13 op=LOAD
Sep 20 10:45:11 38050cf audit: AUDIT1334 prog-id=14 op=LOAD
Sep 20 10:45:11 38050cf kernel: audit: type=1334 audit(1600598711.261:73): prog-id=13 op=LOAD
Sep 20 10:45:11 38050cf kernel: audit: type=1334 audit(1600598711.261:74): prog-id=14 op=LOAD
Sep 20 10:45:11 38050cf audit: AUDIT1334 prog-id=7 op=UNLOAD
Sep 20 10:45:11 38050cf kernel: audit: type=1334 audit(1600598711.341:75): prog-id=7 op=UNLOAD
Sep 20 10:45:11 38050cf audit: AUDIT1334 prog-id=8 op=UNLOAD
Sep 20 10:45:11 38050cf kernel: audit: type=1334 audit(1600598711.351:76): prog-id=8 op=UNLOAD
Sep 20 10:45:11 38050cf audit: AUDIT1334 prog-id=15 op=LOAD
Sep 20 10:45:11 38050cf audit: AUDIT1334 prog-id=16 op=LOAD
Sep 20 10:45:11 38050cf kernel: cgroup: cgroup: disabling cgroup2 socket matching due to net_prio or net_cls activation
Sep 20 10:45:11 38050cf audit: AUDIT1334 prog-id=3 op=UNLOAD
Sep 20 10:45:11 38050cf audit: AUDIT1334 prog-id=4 op=UNLOAD
Sep 20 10:45:11 38050cf audit: AUDIT1334 prog-id=17 op=LOAD
Sep 20 10:45:11 38050cf audit: AUDIT1334 prog-id=18 op=LOAD
Sep 20 10:45:14 38050cf os-config[1489]: Awaiting service configuration...
Sep 20 10:45:14 38050cf balenad[1545]: time="2020-09-20T10:45:14.633202000Z" level=info msg="shim reaped" id=5f2c2b8b8ac2353a081e506f4577a27f9c745bb4b25c71bfb83bc4f5eefaa722
Sep 20 10:45:14 38050cf balenad[1497]: time="2020-09-20T10:45:14.666171000Z" level=error msg="stream copy error: reading from a closed fifo"
Sep 20 10:45:14 38050cf balenad[1497]: time="2020-09-20T10:45:14.679676000Z" level=error msg="stream copy error: reading from a closed fifo"
Sep 20 10:45:15 38050cf balenad[1497]: time="2020-09-20T10:45:15.170769000Z" level=error msg="5f2c2b8b8ac2353a081e506f4577a27f9c745bb4b25c71bfb83bc4f5eefaa722 cleanup: failed to delete container from containerd: no such container"
Sep 20 10:45:15 38050cf balenad[1497]: time="2020-09-20T10:45:15.173164000Z" level=error msg="Failed to start container 5f2c2b8b8ac2353a081e506f4577a27f9c745bb4b25c71bfb83bc4f5eefaa722: OCI runtime create failed: container_linux.go:349: starting container process caused \"process_linux.go:449: container init caused \\\"rootfs_linux.go:58: mounting \\\\\\\"/var/log/supervisor-log\\\\\\\" to rootfs \\\\\\\"/var/lib/docker/aufs/mnt/6c2bfde196d824c19f28b59db58c49096a3245079d8892299e69286b63038a0c\\\\\\\" at \\\\\\\"/var/log\\\\\\\" caused \\\\\\\"stat /var/log/supervisor-log: no such file or directory\\\\\\\"\\\"\": unknown"
Sep 20 10:45:15 38050cf audit: AUDIT1334 prog-id=12 op=UNLOAD
Sep 20 10:45:15 38050cf audit: AUDIT1334 prog-id=11 op=UNLOAD
Sep 20 10:45:18 38050cf 2795154eaef0[1497]: Starting WiFi Connect
Sep 20 10:45:19 38050cf 2795154eaef0[1497]: Error: Cannot find a WiFi device
Sep 20 10:45:19 38050cf balenad[1497]: time="2020-09-20T10:45:19.717689000Z" level=info msg="Loading containers: done."
Sep 20 10:45:20 38050cf balenad[1497]: time="2020-09-20T10:45:20.854005000Z" level=info msg="Docker daemon" commit=66b9691181aa5134b1aeff9282a2d8512b77431c graphdriver(s)=aufs version=19.03.24
Sep 20 10:45:20 38050cf balenad[1497]: time="2020-09-20T10:45:20.859342000Z" level=info msg="Daemon has completed initialization"
Sep 20 10:45:20 38050cf balenad[1497]: time="2020-09-20T10:45:20.994475000Z" level=info msg="API listen on /var/run/balena-engine.sock"
Sep 20 10:45:21 38050cf balenad[1497]: time="2020-09-20T10:45:21.014640000Z" level=info msg="API listen on /run/balena-engine.sock"
Sep 20 10:45:21 38050cf balenad[1497]: time="2020-09-20T10:45:21.019864000Z" level=info msg="API listen on /var/run/balena.sock"
Sep 20 10:45:22 38050cf balena-supervisor[1884]: Error response from daemon: No such container: resin_supervisor
Sep 20 10:45:24 38050cf balena-supervisor[1894]: balena_supervisor
Sep 20 10:45:24 38050cf balena-supervisor[1906]: active
Sep 20 10:45:24 38050cf os-config[1489]: Awaiting service configuration...
Sep 20 10:45:31 38050cf balenad[1545]: time="2020-09-20T10:45:31.234518000Z" level=info msg="shim balena-engine-containerd-shim started" address=/containerd-shim/f3cc315d8dc869a489c8669f318f709907952bc53bf500341e8343989e9aa5d5.sock debug=false pid=1952
Sep 20 10:45:34 38050cf os-config[1489]: Awaiting service configuration...
Sep 20 10:45:44 38050cf os-config[1489]: Awaiting service configuration...
Sep 20 10:45:51 38050cf balena-supervisor[1908]: Container config has not changed
Sep 20 10:45:54 38050cf balenad[1545]: time="2020-09-20T10:45:54.283128000Z" level=info msg="shim balena-engine-containerd-shim started" address=/containerd-shim/ad63686f8638ac215c2932e18a644d539b2d59578718fcec864dcfdc273fabd0.sock debug=false pid=2093
Sep 20 10:45:54 38050cf os-config[1489]: Awaiting service configuration...
Sep 20 10:45:56 38050cf balenad[1545]: time="2020-09-20T10:45:56.013557000Z" level=info msg="shim reaped" id=e8e060f7a8699f3cefe1bebc22dee1e7d079b42434f98cbf3b626d59dd232cc5
Sep 20 10:45:56 38050cf balenad[1497]: time="2020-09-20T10:45:56.081957000Z" level=info msg="ignoring event" module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete"
Sep 20 10:46:04 38050cf os-config[1489]: Awaiting service configuration...
Sep 20 10:46:14 38050cf os-config[1489]: Awaiting service configuration...
Sep 20 10:46:19 38050cf 5f2c2b8b8ac2[1497]: [info]    Supervisor v12.10.3 starting up...
Sep 20 10:46:19 38050cf balena-supervisor[2069]: [info]    Supervisor v12.10.3 starting up...
Sep 20 10:46:21 38050cf 5f2c2b8b8ac2[1497]: [info]    Setting host to discoverable
Sep 20 10:46:21 38050cf balena-supervisor[2069]: [info]    Setting host to discoverable
Sep 20 10:46:21 38050cf 5f2c2b8b8ac2[1497]: [warn]    Invalid firewall mode: . Reverting to state: off
Sep 20 10:46:21 38050cf 5f2c2b8b8ac2[1497]: [info]    Applying firewall mode: off
Sep 20 10:46:21 38050cf balena-supervisor[2069]: [warn]    Invalid firewall mode: . Reverting to state: off
Sep 20 10:46:21 38050cf balena-supervisor[2069]: [info]    Applying firewall mode: off
Sep 20 10:46:21 38050cf 5f2c2b8b8ac2[1497]: [debug]   Starting systemd unit: avahi-daemon.service
Sep 20 10:46:21 38050cf balena-supervisor[2069]: [debug]   Starting systemd unit: avahi-daemon.service
Sep 20 10:46:21 38050cf 5f2c2b8b8ac2[1497]: [debug]   Starting systemd unit: avahi-daemon.socket
Sep 20 10:46:21 38050cf balena-supervisor[2069]: [debug]   Starting systemd unit: avahi-daemon.socket
Sep 20 10:46:21 38050cf 5f2c2b8b8ac2[1497]: [debug]   Starting logging infrastructure
Sep 20 10:46:21 38050cf balena-supervisor[2069]: [debug]   Starting logging infrastructure
Sep 20 10:46:22 38050cf 5f2c2b8b8ac2[1497]: [debug]   Performing database cleanup for container log timestamps
Sep 20 10:46:22 38050cf balena-supervisor[2069]: [debug]   Performing database cleanup for container log timestamps
Sep 20 10:46:22 38050cf 5f2c2b8b8ac2[1497]: [info]    Starting firewall
Sep 20 10:46:22 38050cf balena-supervisor[2069]: [info]    Starting firewall
Sep 20 10:46:22 38050cf audit[2165]: NETFILTER_CFG table=filter family=2 entries=35 op=xt_replace pid=2165 comm="iptables-restor"
Sep 20 10:46:22 38050cf kernel: kauditd_printk_skb: 8 callbacks suppressed
Sep 20 10:46:22 38050cf kernel: audit: type=1325 audit(1600598782.761:85): table=filter family=2 entries=35 op=xt_replace pid=2165 comm="iptables-restor"
Sep 20 10:46:22 38050cf audit[2171]: NETFILTER_CFG table=filter family=10 entries=0 op=xt_register pid=2171 comm="modprobe"
Sep 20 10:46:22 38050cf kernel: audit: type=1325 audit(1600598782.951:86): table=filter family=10 entries=0 op=xt_register pid=2171 comm="modprobe"
Sep 20 10:46:23 38050cf audit[2170]: NETFILTER_CFG table=filter family=10 entries=4 op=xt_replace pid=2170 comm="ip6tables-resto"
Sep 20 10:46:23 38050cf kernel: audit: type=1325 audit(1600598783.011:87): table=filter family=10 entries=4 op=xt_replace pid=2170 comm="ip6tables-resto"
Sep 20 10:46:23 38050cf 5f2c2b8b8ac2[1497]: [success] Firewall mode applied
Sep 20 10:46:23 38050cf balena-supervisor[2069]: [success] Firewall mode applied
Sep 20 10:46:23 38050cf 5f2c2b8b8ac2[1497]: [debug]   Starting api binder
Sep 20 10:46:23 38050cf balena-supervisor[2069]: [debug]   Starting api binder
Sep 20 10:46:23 38050cf 5f2c2b8b8ac2[1497]: [info]    Previous engine snapshot was not stored. Skipping cleanup.
Sep 20 10:46:23 38050cf balena-supervisor[2069]: [info]    Previous engine snapshot was not stored. Skipping cleanup.
Sep 20 10:46:23 38050cf 5f2c2b8b8ac2[1497]: [debug]   Handling of local mode switch is completed
Sep 20 10:46:23 38050cf balena-supervisor[2069]: [debug]   Handling of local mode switch is completed
Sep 20 10:46:23 38050cf 5f2c2b8b8ac2[1497]: (node:1) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead.
Sep 20 10:46:23 38050cf balena-supervisor[2069]: (node:1) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead.
Sep 20 10:46:23 38050cf 5f2c2b8b8ac2[1497]: [info]    API Binder bound to: https://api.balena-cloud.com/v6/
Sep 20 10:46:23 38050cf balena-supervisor[2069]: [info]    API Binder bound to: https://api.balena-cloud.com/v6/
Sep 20 10:46:23 38050cf 5f2c2b8b8ac2[1497]: [event]   Event: Supervisor start {}
Sep 20 10:46:23 38050cf balena-supervisor[2069]: [event]   Event: Supervisor start {}
Sep 20 10:46:24 38050cf 5f2c2b8b8ac2[1497]: [debug]   Spawning journald with: chroot  /mnt/root journalctl -a -S 2020-09-20 10:46:33 -o json CONTAINER_ID_FULL=2795154eaef08d1fa6db9b6598c2a2464a37b3221db0e9b86062a60387180c33
Sep 20 10:46:24 38050cf balena-supervisor[2069]: [debug]   Spawning journald with: chroot  /mnt/root journalctl -a -S 2020-09-20 10:46:33 -o json CONTAINER_ID_FULL=2795154eaef08d1fa6db9b6598c2a2464a37b3221db0e9b86062a60387180c33
Sep 20 10:46:24 38050cf 5f2c2b8b8ac2[1497]: [debug]   Connectivity check enabled: true
Sep 20 10:46:24 38050cf balena-supervisor[2069]: [debug]   Connectivity check enabled: true
Sep 20 10:46:24 38050cf 5f2c2b8b8ac2[1497]: [debug]   Starting periodic check for IP addresses
Sep 20 10:46:24 38050cf balena-supervisor[2069]: [debug]   Starting periodic check for IP addresses
Sep 20 10:46:24 38050cf 5f2c2b8b8ac2[1497]: [info]    Reporting initial state, supervisor version and API info
Sep 20 10:46:24 38050cf balena-supervisor[2069]: [info]    Reporting initial state, supervisor version and API info
Sep 20 10:46:24 38050cf 5f2c2b8b8ac2[1497]: [info]    Attempting to load any preloaded applications
Sep 20 10:46:24 38050cf balena-supervisor[2069]: [info]    Attempting to load any preloaded applications
Sep 20 10:46:24 38050cf 5f2c2b8b8ac2[1497]: [info]    VPN connection is not active.
Sep 20 10:46:24 38050cf balena-supervisor[2069]: [info]    VPN connection is not active.
Sep 20 10:46:24 38050cf 5f2c2b8b8ac2[1497]: [info]    Waiting for connectivity...
Sep 20 10:46:24 38050cf balena-supervisor[2069]: [info]    Waiting for connectivity...
Sep 20 10:46:24 38050cf os-config[1489]: Awaiting service configuration...
Sep 20 10:46:25 38050cf 5f2c2b8b8ac2[1497]: [success] Preloading complete
Sep 20 10:46:25 38050cf balena-supervisor[2069]: [success] Preloading complete
Sep 20 10:46:25 38050cf 5f2c2b8b8ac2[1497]: [info]    Starting API server
Sep 20 10:46:25 38050cf balena-supervisor[2069]: [info]    Starting API server
Sep 20 10:46:26 38050cf 5f2c2b8b8ac2[1497]: [info]    Supervisor API successfully started on port 48484
Sep 20 10:46:26 38050cf balena-supervisor[2069]: [info]    Supervisor API successfully started on port 48484
Sep 20 10:46:26 38050cf 5f2c2b8b8ac2[1497]: [info]    Applying target state
Sep 20 10:46:26 38050cf balena-supervisor[2069]: [info]    Applying target state
Sep 20 10:46:26 38050cf 5f2c2b8b8ac2[1497]: [debug]   Ensuring device is provisioned
Sep 20 10:46:26 38050cf balena-supervisor[2069]: [debug]   Ensuring device is provisioned
Sep 20 10:46:26 38050cf 5f2c2b8b8ac2[1497]: [event]   Event: Device bootstrap {}
Sep 20 10:46:26 38050cf balena-supervisor[2069]: [event]   Event: Device bootstrap {}
Sep 20 10:46:26 38050cf 5f2c2b8b8ac2[1497]: [info]    New device detected. Provisioning...
Sep 20 10:46:26 38050cf balena-supervisor[2069]: [info]    New device detected. Provisioning...
Sep 20 10:46:27 38050cf 5f2c2b8b8ac2[1497]: [debug]   Starting systemd unit: openvpn.service
Sep 20 10:46:27 38050cf balena-supervisor[2069]: [debug]   Starting systemd unit: openvpn.service
Sep 20 10:46:34 38050cf os-config[1489]: Awaiting service configuration...
Sep 20 10:46:38 38050cf 5f2c2b8b8ac2[1497]: [error]   LogBackend: unexpected error: Error: getaddrinfo EAI_AGAIN api.balena-cloud.com
Sep 20 10:46:38 38050cf balena-supervisor[2069]: [error]   LogBackend: unexpected error: Error: getaddrinfo EAI_AGAIN api.balena-cloud.com
Sep 20 10:46:38 38050cf balena-supervisor[2069]: [error]         at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:64:26)
Sep 20 10:46:38 38050cf 5f2c2b8b8ac2[1497]: [error]         at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:64:26)
Sep 20 10:46:44 38050cf os-config[1489]: Awaiting service configuration...
Sep 20 10:46:54 38050cf 5f2c2b8b8ac2[1497]: [event]   Event: Device bootstrap failed, retrying {"delay":30000,"error":{"message":""}}
Sep 20 10:46:54 38050cf balena-supervisor[2069]: [event]   Event: Device bootstrap failed, retrying {"delay":30000,"error":{"message":""}}
Sep 20 10:46:54 38050cf os-config[1489]: Awaiting service configuration...
Sep 20 10:47:04 38050cf os-config[1489]: Awaiting service configuration...
Sep 20 10:47:14 38050cf os-config[1489]: Awaiting service configuration...
Sep 20 10:47:24 38050cf 5f2c2b8b8ac2[1497]: [event]   Event: Device bootstrap {}
Sep 20 10:47:24 38050cf balena-supervisor[2069]: [event]   Event: Device bootstrap {}
Sep 20 10:47:24 38050cf 5f2c2b8b8ac2[1497]: [info]    New device detected. Provisioning...
Sep 20 10:47:24 38050cf balena-supervisor[2069]: [info]    New device detected. Provisioning...
Sep 20 10:47:24 38050cf os-config[1489]: Awaiting service configuration...