Jul  4 06:25:01 bbctrl liblogging-stdlog:  [origin software="rsyslogd" swVersion="8.24.0" x-pid="316" x-info="http://www.rsyslog.com"] rsyslogd was HUPed
Jul  4 06:47:01 bbctrl CRON[27805]: (root) CMD (test -x /usr/sbin/anacron || ( cd / && run-parts --report /etc/cron.weekly ))
Jul  4 06:52:58 bbctrl systemd[1]: Starting Daily apt upgrade and clean activities...
Jul  4 06:53:00 bbctrl systemd[1]: Started Daily apt upgrade and clean activities.
Jul  4 06:53:00 bbctrl systemd[1]: apt-daily-upgrade.timer: Adding 45min 20.676223s random time.
Jul  4 06:53:00 bbctrl systemd[1]: apt-daily-upgrade.timer: Adding 34min 36.525793s random time.
Jul  4 06:57:35 bbctrl wpa_supplicant[362]: wlan0: WPA: Group rekeying completed with 04:d9:f5:b1:7b:88 [GTK=CCMP]
Jul  4 07:00:30 bbctrl dhcpcd[483]: wlan0: carrier lost
Jul  4 07:00:30 bbctrl wpa_supplicant[362]: wlan0: CTRL-EVENT-DISCONNECTED bssid=04:d9:f5:b1:7b:88 reason=0 locally_generated=1
Jul  4 07:00:30 bbctrl wpa_supplicant[362]: p2p-dev-wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD
Jul  4 07:00:30 bbctrl dhcpcd[483]: wlan0: deleting address fe80::c9c2:7b78:c7a8:d62c
Jul  4 07:00:30 bbctrl avahi-daemon[317]: Withdrawing address record for fe80::c9c2:7b78:c7a8:d62c on wlan0.
Jul  4 07:00:30 bbctrl avahi-daemon[317]: Leaving mDNS multicast group on interface wlan0.IPv6 with address fe80::c9c2:7b78:c7a8:d62c.
Jul  4 07:00:30 bbctrl avahi-daemon[317]: Interface wlan0.IPv6 no longer relevant for mDNS.
Jul  4 07:00:30 bbctrl dhcpcd[483]: wlan0: deleting default route via 192.168.1.1
Jul  4 07:00:30 bbctrl dhcpcd[483]: wlan0: deleting route to 192.168.1.0/24
Jul  4 07:00:30 bbctrl avahi-daemon[317]: Withdrawing address record for 192.168.1.134 on wlan0.
Jul  4 07:00:30 bbctrl avahi-daemon[317]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.134.
Jul  4 07:00:30 bbctrl avahi-daemon[317]: Interface wlan0.IPv4 no longer relevant for mDNS.
Jul  4 07:02:42 bbctrl wpa_supplicant[362]: wlan0: Trying to associate with 04:d9:f5:b1:7b:88 (SSID='Pinson' freq=2412 MHz)
Jul  4 07:02:42 bbctrl wpa_supplicant[362]: wlan0: Associated with 04:d9:f5:b1:7b:88
Jul  4 07:02:42 bbctrl wpa_supplicant[362]: wlan0: WPA: Key negotiation completed with 04:d9:f5:b1:7b:88 [PTK=CCMP GTK=CCMP]
Jul  4 07:02:43 bbctrl wpa_supplicant[362]: wlan0: CTRL-EVENT-CONNECTED - Connection to 04:d9:f5:b1:7b:88 completed [id=0 id_str=]
Jul  4 07:02:43 bbctrl dhcpcd[483]: wlan0: carrier acquired
Jul  4 07:02:43 bbctrl dhcpcd[483]: wlan0: IAID eb:f5:15:54
Jul  4 07:02:43 bbctrl dhcpcd[483]: wlan0: adding address fe80::c9c2:7b78:c7a8:d62c
Jul  4 07:02:43 bbctrl wpa_supplicant[362]: p2p-dev-wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=US
Jul  4 07:02:43 bbctrl dhcpcd[483]: wlan0: soliciting an IPv6 router
Jul  4 07:02:43 bbctrl dhcpcd[483]: wlan0: rebinding lease of 192.168.1.134
Jul  4 07:02:43 bbctrl dhcpcd[483]: wlan0: probing address 192.168.1.134/24
Jul  4 07:02:44 bbctrl avahi-daemon[317]: Joining mDNS multicast group on interface wlan0.IPv6 with address fe80::c9c2:7b78:c7a8:d62c.
Jul  4 07:02:44 bbctrl avahi-daemon[317]: New relevant interface wlan0.IPv6 for mDNS.
Jul  4 07:02:44 bbctrl avahi-daemon[317]: Registering new address record for fe80::c9c2:7b78:c7a8:d62c on wlan0.*.
Jul  4 07:02:49 bbctrl dhcpcd[483]: wlan0: leased 192.168.1.134 for 86400 seconds
Jul  4 07:02:49 bbctrl avahi-daemon[317]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.134.
Jul  4 07:02:49 bbctrl avahi-daemon[317]: New relevant interface wlan0.IPv4 for mDNS.
Jul  4 07:02:49 bbctrl avahi-daemon[317]: Registering new address record for 192.168.1.134 on wlan0.IPv4.
Jul  4 07:02:49 bbctrl dhcpcd[483]: wlan0: adding route to 192.168.1.0/24
Jul  4 07:02:49 bbctrl dhcpcd[483]: wlan0: adding default route via 192.168.1.1
Jul  4 07:02:56 bbctrl dhcpcd[483]: wlan0: no IPv6 Routers available
Jul  4 07:17:01 bbctrl CRON[27976]: (root) CMD (   cd / && run-parts --report /etc/cron.hourly)
Jul  4 08:02:05 bbctrl wpa_supplicant[362]: wlan0: WPA: Group rekeying completed with 04:d9:f5:b1:7b:88 [GTK=CCMP]
Jul  4 08:02:05 bbctrl wpa_supplicant[362]: wlan0: WPA: Group rekeying completed with 04:d9:f5:b1:7b:88 [GTK=CCMP]
Jul  4 08:17:02 bbctrl CRON[28008]: (root) CMD (   cd / && run-parts --report /etc/cron.hourly)
Jul  4 09:02:00 bbctrl wpa_supplicant[362]: wlan0: WPA: Group rekeying completed with 04:d9:f5:b1:7b:88 [GTK=CCMP]
Jul  4 09:02:00 bbctrl wpa_supplicant[362]: wlan0: WPA: Group rekeying completed with 04:d9:f5:b1:7b:88 [GTK=CCMP]
Jul  4 09:02:00 bbctrl wpa_supplicant[362]: wlan0: WPA: Group rekeying completed with 04:d9:f5:b1:7b:88 [GTK=CCMP]
Jul  4 09:17:01 bbctrl CRON[28040]: (root) CMD (   cd / && run-parts --report /etc/cron.hourly)
Jul  4 10:01:57 bbctrl wpa_supplicant[362]: wlan0: WPA: Group rekeying completed with 04:d9:f5:b1:7b:88 [GTK=CCMP]
Jul  4 10:01:57 bbctrl wpa_supplicant[362]: wlan0: WPA: Group rekeying completed with 04:d9:f5:b1:7b:88 [GTK=CCMP]
Jul  4 10:17:01 bbctrl CRON[28075]: (root) CMD (   cd / && run-parts --report /etc/cron.hourly)
Jul  4 11:01:44 bbctrl wpa_supplicant[362]: wlan0: WPA: Group rekeying completed with 04:d9:f5:b1:7b:88 [GTK=CCMP]
Jul  4 11:17:01 bbctrl CRON[28107]: (root) CMD (   cd / && run-parts --report /etc/cron.hourly)
Jul  4 12:01:38 bbctrl wpa_supplicant[362]: wlan0: WPA: Group rekeying completed with 04:d9:f5:b1:7b:88 [GTK=CCMP]
Jul  4 12:17:01 bbctrl CRON[28137]: (root) CMD (   cd / && run-parts --report /etc/cron.hourly)
Jul  4 13:01:30 bbctrl wpa_supplicant[362]: wlan0: WPA: Group rekeying completed with 04:d9:f5:b1:7b:88 [GTK=CCMP]
Jul  4 13:17:01 bbctrl CRON[28170]: (root) CMD (   cd / && run-parts --report /etc/cron.hourly)
Jul  4 14:01:13 bbctrl wpa_supplicant[362]: wlan0: WPA: Group rekeying completed with 04:d9:f5:b1:7b:88 [GTK=CCMP]
Jul  4 14:01:13 bbctrl wpa_supplicant[362]: wlan0: WPA: Group rekeying completed with 04:d9:f5:b1:7b:88 [GTK=CCMP]
Jul  4 14:09:22 bbctrl systemd[1]: Starting Daily apt download activities...
Jul  4 14:09:23 bbctrl systemd[1]: Started Daily apt download activities.
Jul  4 14:09:23 bbctrl systemd[1]: apt-daily.timer: Adding 9h 54min 37.288377s random time.
Jul  4 14:09:23 bbctrl systemd[1]: apt-daily.timer: Adding 7h 15min 56.340595s random time.
Jul  4 14:17:01 bbctrl CRON[28249]: (root) CMD (   cd / && run-parts --report /etc/cron.hourly)
Jul  4 15:01:03 bbctrl wpa_supplicant[362]: wlan0: WPA: Group rekeying completed with 04:d9:f5:b1:7b:88 [GTK=CCMP]
Jul  4 15:16:47 bbctrl dhclient[533]: DHCPREQUEST of 192.168.1.134 on wlan0 to 192.168.1.1 port 67
Jul  4 15:16:47 bbctrl dhclient[533]: DHCPACK of 192.168.1.134 from 192.168.1.1
Jul  4 15:16:47 bbctrl dhclient[533]: bound to 192.168.1.134 -- renewal in 29828 seconds.
Jul  4 15:17:01 bbctrl CRON[28296]: (root) CMD (   cd / && run-parts --report /etc/cron.hourly)
Jul  4 16:00:45 bbctrl wpa_supplicant[362]: wlan0: WPA: Group rekeying completed with 04:d9:f5:b1:7b:88 [GTK=CCMP]
Jul  4 16:17:01 bbctrl CRON[28332]: (root) CMD (   cd / && run-parts --report /etc/cron.hourly)
Jul  4 17:00:31 bbctrl wpa_supplicant[362]: wlan0: WPA: Group rekeying completed with 04:d9:f5:b1:7b:88 [GTK=CCMP]
Jul  4 16:36:24 bbctrl systemd-modules-load[122]: Inserted module 'i2c_bcm2708'
Jul  4 16:36:24 bbctrl systemd-modules-load[122]: Inserted module 'i2c_dev'
Jul  4 16:36:24 bbctrl fake-hwclock[115]: Sun  4 Jul 16:17:01 UTC 2021
Jul  4 16:36:24 bbctrl systemd-fsck[117]: e2fsck 1.43.4 (31-Jan-2017)
Jul  4 16:36:24 bbctrl systemd-fsck[117]: /dev/mmcblk0p2: clean, 42265/938672 files, 595261/3877760 blocks
Jul  4 16:36:24 bbctrl systemd[1]: Started Apply Kernel Variables.
Jul  4 16:36:24 bbctrl systemd[1]: Started File System Check on Root Device.
Jul  4 16:36:24 bbctrl systemd[1]: Starting Remount Root and Kernel File Systems...
Jul  4 16:36:24 bbctrl systemd[1]: Started Create Static Device Nodes in /dev.
Jul  4 16:36:24 bbctrl systemd[1]: Starting udev Kernel Device Manager...
Jul  4 16:36:24 bbctrl systemd[1]: Started Remount Root and Kernel File Systems.
Jul  4 16:36:24 bbctrl systemd[1]: Starting Flush Journal to Persistent Storage...
Jul  4 16:36:24 bbctrl systemd[1]: Starting udev Coldplug all Devices...
Jul  4 16:36:24 bbctrl systemd[1]: Starting Load/Save Random Seed...
Jul  4 16:36:24 bbctrl systemd[1]: Started Load/Save Random Seed.
Jul  4 16:36:24 bbctrl systemd[1]: Started Flush Journal to Persistent Storage.
Jul  4 16:36:24 bbctrl systemd[1]: Started udev Kernel Device Manager.
Jul  4 16:36:24 bbctrl systemd[1]: Started Set the console keyboard layout.
Jul  4 16:36:24 bbctrl systemd[1]: Reached target Local File Systems (Pre).
Jul  4 16:36:24 bbctrl mtp-probe: checking bus 1, device 4: "/sys/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.5"
Jul  4 16:36:24 bbctrl mtp-probe: bus: 1, device: 4 was not an MTP device
Jul  4 16:36:24 bbctrl systemd-udevd[172]: Process '/usr/local/bin/mount-usb sdhci' failed with exit code 1.
Jul  4 16:36:24 bbctrl systemd-udevd[178]: Process '/usr/local/bin/mount-usb sdio' failed with exit code 1.
Jul  4 16:36:24 bbctrl systemd-udevd[171]: Process '/usr/local/bin/mount-usb sdhost-bcm2835' failed with exit code 1.
Jul  4 16:36:24 bbctrl systemd[1]: Started udev Coldplug all Devices.
Jul  4 16:36:24 bbctrl systemd[1]: Starting Show Plymouth Boot Screen...
Jul  4 16:36:24 bbctrl systemd[1]: Received SIGRTMIN+20 from PID 199 (plymouthd).
Jul  4 16:36:24 bbctrl systemd[1]: Started Show Plymouth Boot Screen.
Jul  4 16:36:24 bbctrl systemd[1]: Started Forward Password Requests to Plymouth Directory Watch.
Jul  4 16:36:24 bbctrl systemd[1]: Reached target Encrypted Volumes.
Jul  4 16:36:24 bbctrl systemd[1]: Reached target Paths.
Jul  4 16:36:24 bbctrl systemd-udevd[165]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1.
Jul  4 16:36:24 bbctrl systemd-udevd[168]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1.
Jul  4 16:36:24 bbctrl mtp-probe: checking bus 1, device 4: "/sys/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.5"
Jul  4 16:36:24 bbctrl mtp-probe: bus: 1, device: 4 was not an MTP device
Jul  4 16:36:24 bbctrl mtp-probe: checking bus 1, device 3: "/sys/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.1"
Jul  4 16:36:24 bbctrl systemd[1]: Reached target Sound Card.
Jul  4 16:36:24 bbctrl systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.
Jul  4 16:36:24 bbctrl mtp-probe: bus: 1, device: 3 was not an MTP device
Jul  4 16:36:24 bbctrl systemd[1]: Found device /dev/disk/by-partuuid/32bfa6cc-01.
Jul  4 16:36:24 bbctrl systemd[1]: Starting File System Check on /dev/disk/by-partuuid/32bfa6cc-01...
Jul  4 16:36:24 bbctrl systemd-fsck[251]: fsck.fat 4.1 (2017-01-24)
Jul  4 16:36:24 bbctrl systemd-fsck[251]: /dev/mmcblk0p1: 144 files, 42655/84272 clusters
Jul  4 16:36:24 bbctrl systemd[1]: Started File System Check on /dev/disk/by-partuuid/32bfa6cc-01.
Jul  4 16:36:24 bbctrl systemd[1]: Mounting /boot...
Jul  4 16:36:24 bbctrl systemd[1]: Starting Load/Save RF Kill Switch Status...
Jul  4 16:36:24 bbctrl systemd[1]: Mounted /boot.
Jul  4 16:36:24 bbctrl systemd[1]: Reached target Local File Systems.
Jul  4 16:36:24 bbctrl systemd[1]: Starting Set console font and keymap...
Jul  4 16:36:24 bbctrl systemd[1]: Starting Create Volatile Files and Directories...
Jul  4 16:36:24 bbctrl systemd[1]: Starting Raise network interfaces...
Jul  4 16:36:24 bbctrl systemd[1]: Starting Preprocess NFS configuration...
Jul  4 16:36:24 bbctrl systemd[1]: Starting Tell Plymouth To Write Out Runtime Data...
Jul  4 16:36:24 bbctrl systemd[1]: Started Load/Save RF Kill Switch Status.
Jul  4 16:36:24 bbctrl systemd[1]: Started Set console font and keymap.
Jul  4 16:36:24 bbctrl systemd[1]: Started Preprocess NFS configuration.
Jul  4 16:36:24 bbctrl systemd[1]: Reached target NFS client services.
Jul  4 16:36:24 bbctrl systemd[1]: Reached target Remote File Systems (Pre).
Jul  4 16:36:24 bbctrl systemd[1]: Reached target Remote File Systems.
Jul  4 16:36:24 bbctrl systemd[1]: Started Tell Plymouth To Write Out Runtime Data.
Jul  4 16:36:24 bbctrl systemd[1]: Started Create Volatile Files and Directories.
Jul  4 16:36:24 bbctrl systemd[1]: Starting Network Time Synchronization...
Jul  4 16:36:24 bbctrl systemd[1]: Starting Update UTMP about System Boot/Shutdown...
Jul  4 16:36:24 bbctrl systemd[1]: Found device /sys/subsystem/net/devices/wlan0.
Jul  4 16:36:24 bbctrl systemd[1]: Started Update UTMP about System Boot/Shutdown.
Jul  4 16:36:24 bbctrl systemd[1]: Started ifup for wlan0.
Jul  4 16:36:24 bbctrl wpa_supplicant[322]: Successfully initialized wpa_supplicant
Jul  4 16:36:24 bbctrl systemd-timesyncd[290]: System clock time unset or jumped backwards, restoring from recorded timestamp: Sun 2021-07-04 16:36:24 UTC
Jul  4 16:36:24 bbctrl systemd[1]: Time has been changed
Jul  4 16:36:24 bbctrl systemd[1]: Started Network Time Synchronization.
Jul  4 16:36:24 bbctrl systemd[1]: Reached target System Time Synchronized.
Jul  4 16:36:24 bbctrl systemd[1]: Reached target System Initialization.
Jul  4 16:36:24 bbctrl systemd[1]: Started Daily Cleanup of Temporary Directories.
Jul  4 16:36:24 bbctrl systemd[1]: Listening on D-Bus System Message Bus Socket.
Jul  4 16:36:24 bbctrl systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket.
Jul  4 16:36:24 bbctrl systemd[1]: apt-daily.timer: Adding 6h 14min 54.228875s random time.
Jul  4 16:36:24 bbctrl systemd[1]: Started Daily apt download activities.
Jul  4 16:36:24 bbctrl systemd[1]: apt-daily-upgrade.timer: Adding 8min 10.704088s random time.
Jul  4 16:36:24 bbctrl systemd[1]: Started Daily apt upgrade and clean activities.
Jul  4 16:36:24 bbctrl systemd[1]: Reached target Timers.
Jul  4 16:36:24 bbctrl systemd[1]: Listening on triggerhappy.socket.
Jul  4 16:36:24 bbctrl systemd[1]: Reached target Sockets.
Jul  4 16:36:24 bbctrl systemd[1]: Reached target Basic System.
Jul  4 16:36:24 bbctrl systemd[1]: Starting Login Service...
Jul  4 16:36:24 bbctrl systemd[1]: Started D-Bus System Message Bus.
Jul  4 16:36:24 bbctrl ifup[296]: ifup: waiting for lock on /run/network/ifstate.wlan0
Jul  4 16:36:24 bbctrl systemd[1]: Starting LSB: Autogenerate and use a swap file...
Jul  4 16:36:24 bbctrl systemd[1]: Starting Avahi mDNS/DNS-SD Stack...
Jul  4 16:36:24 bbctrl systemd[1]: Starting dhcpcd on all interfaces...
Jul  4 16:36:24 bbctrl systemd[1]: Started Regular background program processing daemon.
Jul  4 16:36:24 bbctrl systemd[1]: Starting Save/Restore Sound Card State...
Jul  4 16:36:24 bbctrl systemd[1]: Starting triggerhappy global hotkey daemon...
Jul  4 16:36:24 bbctrl systemd[1]: Starting LSB: Switch to ondemand cpu governor (unless shift key is pressed)...
Jul  4 16:36:24 bbctrl systemd[1]: Starting System Logging Service...
Jul  4 16:36:24 bbctrl cron[349]: (CRON) INFO (pidfile fd = 3)
Jul  4 16:36:24 bbctrl systemd[1]: Started Save/Restore Sound Card State.
Jul  4 16:36:24 bbctrl systemd[1]: Started Login Service.
Jul  4 16:36:24 bbctrl thd[353]: Found socket passed from systemd
Jul  4 16:36:24 bbctrl avahi-daemon[346]: Found user 'avahi' (UID 108) and group 'avahi' (GID 112).
Jul  4 16:36:24 bbctrl avahi-daemon[346]: Successfully dropped root privileges.
Jul  4 16:36:24 bbctrl avahi-daemon[346]: avahi-daemon 0.6.32 starting up.
Jul  4 16:36:24 bbctrl dhcpcd[347]: dev: loaded udev
Jul  4 16:36:24 bbctrl cron[349]: (CRON) INFO (Running @reboot jobs)
Jul  4 16:36:24 bbctrl systemd[1]: Started triggerhappy global hotkey daemon.
Jul  4 16:36:24 bbctrl systemd[1]: Started Avahi mDNS/DNS-SD Stack.
Jul  4 16:36:24 bbctrl avahi-daemon[346]: Successfully called chroot().
Jul  4 16:36:24 bbctrl avahi-daemon[346]: Successfully dropped remaining capabilities.
Jul  4 16:36:24 bbctrl avahi-daemon[346]: No service file found in /etc/avahi/services.
Jul  4 16:36:24 bbctrl dphys-swapfile[345]: Starting dphys-swapfile swapfile setup ...
Jul  4 16:36:24 bbctrl avahi-daemon[346]: Network interface enumeration completed.
Jul  4 16:36:24 bbctrl avahi-daemon[346]: Server startup complete. Host name is bbctrl.local. Local service cookie is 73097850.
Jul  4 16:36:24 bbctrl liblogging-stdlog:  [origin software="rsyslogd" swVersion="8.24.0" x-pid="355" x-info="http://www.rsyslog.com"] start
Jul  4 16:36:24 bbctrl systemd[1]: Started System Logging Service.
Jul  4 16:36:24 bbctrl kernel: [    0.000000] Booting Linux on physical CPU 0x0
Jul  4 16:36:24 bbctrl kernel: [    0.000000] Linux version 4.9.59-v7+ (dc4@dc4-XPS13-9333) (gcc version 4.9.3 (crosstool-NG crosstool-ng-1.22.0-88-g8460611) ) #1047 SMP Sun Oct 29 12:19:23 GMT 2017
Jul  4 16:36:24 bbctrl kernel: [    0.000000] CPU: ARMv7 Processor [410fd034] revision 4 (ARMv7), cr=10c5383d
Jul  4 16:36:24 bbctrl kernel: [    0.000000] CPU: div instructions available: patching division code
Jul  4 16:36:24 bbctrl kernel: [    0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache
Jul  4 16:36:24 bbctrl kernel: [    0.000000] OF: fdt:Machine model: Raspberry Pi 3 Model B Rev 1.2
Jul  4 16:36:24 bbctrl kernel: [    0.000000] cma: Reserved 8 MiB at 0x3ac00000
Jul  4 16:36:24 bbctrl kernel: [    0.000000] Memory policy: Data cache writealloc
Jul  4 16:36:24 bbctrl kernel: [    0.000000] On node 0 totalpages: 242688
Jul  4 16:36:24 bbctrl kernel: [    0.000000] free_area_init_node: node 0, pgdat 80c6f380, node_mem_map ba3a1000
Jul  4 16:36:24 bbctrl kernel: [    0.000000]   Normal zone: 2133 pages used for memmap
Jul  4 16:36:24 bbctrl kernel: [    0.000000]   Normal zone: 0 pages reserved
Jul  4 16:36:24 bbctrl kernel: [    0.000000]   Normal zone: 242688 pages, LIFO batch:31
Jul  4 16:36:24 bbctrl kernel: [    0.000000] percpu: Embedded 14 pages/cpu @ba35b000 s25600 r8192 d23552 u57344
Jul  4 16:36:24 bbctrl kernel: [    0.000000] pcpu-alloc: s25600 r8192 d23552 u57344 alloc=14*4096
Jul  4 16:36:24 bbctrl kernel: [    0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 
Jul  4 16:36:24 bbctrl kernel: [    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 240555
Jul  4 16:36:24 bbctrl kernel: [    0.000000] Kernel command line: 8250.nr_uarts=1 bcm2708_fb.fbwidth=656 bcm2708_fb.fbheight=416 bcm2708_fb.fbswap=1 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000  dwc_otg.lpm_enable=0 console=tty1 root=PARTUUID=32bfa6cc-02 rootfstype=ext4 elevator=deadline fsck.repair=yes rootwait dwc_otg.fiq_fsm_mask=0x3 cgroup_memory=1
Jul  4 16:36:24 bbctrl kernel: [    0.000000] PID hash table entries: 4096 (order: 2, 16384 bytes)
Jul  4 16:36:24 bbctrl kernel: [    0.000000] Dentry cache hash table entries: 131072 (order: 7, 524288 bytes)
Jul  4 16:36:24 bbctrl kernel: [    0.000000] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes)
Jul  4 16:36:24 bbctrl kernel: [    0.000000] Memory: 940364K/970752K available (7168K kernel code, 486K rwdata, 2012K rodata, 1024K init, 770K bss, 22196K reserved, 8192K cma-reserved)
Jul  4 16:36:24 bbctrl kernel: [    0.000000] Virtual kernel memory layout:
Jul  4 16:36:24 bbctrl kernel: [    0.000000]     vector  : 0xffff0000 - 0xffff1000   (   4 kB)
Jul  4 16:36:24 bbctrl kernel: [    0.000000]     fixmap  : 0xffc00000 - 0xfff00000   (3072 kB)
Jul  4 16:36:24 bbctrl kernel: [    0.000000]     vmalloc : 0xbb800000 - 0xff800000   (1088 MB)
Jul  4 16:36:24 bbctrl kernel: [    0.000000]     lowmem  : 0x80000000 - 0xbb400000   ( 948 MB)
Jul  4 16:36:24 bbctrl kernel: [    0.000000]     modules : 0x7f000000 - 0x80000000   (  16 MB)
Jul  4 16:36:24 bbctrl kernel: [    0.000000]       .text : 0x80008000 - 0x80800000   (8160 kB)
Jul  4 16:36:24 bbctrl kernel: [    0.000000]       .init : 0x80b00000 - 0x80c00000   (1024 kB)
Jul  4 16:36:24 bbctrl kernel: [    0.000000]       .data : 0x80c00000 - 0x80c79834   ( 487 kB)
Jul  4 16:36:24 bbctrl kernel: [    0.000000]        .bss : 0x80c7b000 - 0x80d3b964   ( 771 kB)
Jul  4 16:36:24 bbctrl kernel: [    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
Jul  4 16:36:24 bbctrl kernel: [    0.000000] Hierarchical RCU implementation.
Jul  4 16:36:24 bbctrl kernel: [    0.000000] 	Build-time adjustment of leaf fanout to 32.
Jul  4 16:36:24 bbctrl kernel: [    0.000000] NR_IRQS:16 nr_irqs:16 16
Jul  4 16:36:24 bbctrl kernel: [    0.000000] arm_arch_timer: Architected cp15 timer(s) running at 19.20MHz (phys).
Jul  4 16:36:24 bbctrl kernel: [    0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x46d987e47, max_idle_ns: 440795202767 ns
Jul  4 16:36:24 bbctrl kernel: [    0.000007] sched_clock: 56 bits at 19MHz, resolution 52ns, wraps every 4398046511078ns
Jul  4 16:36:24 bbctrl kernel: [    0.000023] Switching to timer-based delay loop, resolution 52ns
Jul  4 16:36:24 bbctrl kernel: [    0.000303] Console: colour dummy device 80x30
Jul  4 16:36:24 bbctrl kernel: [    0.001210] console [tty1] enabled
Jul  4 16:36:24 bbctrl kernel: [    0.001256] Calibrating delay loop (skipped), value calculated using timer frequency.. 38.40 BogoMIPS (lpj=192000)
Jul  4 16:36:24 bbctrl kernel: [    0.001324] pid_max: default: 32768 minimum: 301
Jul  4 16:36:24 bbctrl kernel: [    0.001656] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes)
Jul  4 16:36:24 bbctrl kernel: [    0.001698] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes)
Jul  4 16:36:24 bbctrl kernel: [    0.002721] CPU: Testing write buffer coherency: ok
Jul  4 16:36:24 bbctrl kernel: [    0.002786] ftrace: allocating 22392 entries in 66 pages
Jul  4 16:36:24 bbctrl kernel: [    0.049885] CPU0: update cpu_capacity 1024
Jul  4 16:36:24 bbctrl kernel: [    0.049938] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000
Jul  4 16:36:24 bbctrl kernel: [    0.049999] Setting up static identity map for 0x100000 - 0x100034
Jul  4 16:36:24 bbctrl kernel: [    0.051889] CPU1: update cpu_capacity 1024
Jul  4 16:36:24 bbctrl kernel: [    0.051897] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001
Jul  4 16:36:24 bbctrl kernel: [    0.052581] CPU2: update cpu_capacity 1024
Jul  4 16:36:24 bbctrl kernel: [    0.052587] CPU2: thread -1, cpu 2, socket 0, mpidr 80000002
Jul  4 16:36:24 bbctrl kernel: [    0.053259] CPU3: update cpu_capacity 1024
Jul  4 16:36:24 bbctrl kernel: [    0.053266] CPU3: thread -1, cpu 3, socket 0, mpidr 80000003
Jul  4 16:36:24 bbctrl kernel: [    0.053353] Brought up 4 CPUs
Jul  4 16:36:24 bbctrl kernel: [    0.053527] SMP: Total of 4 processors activated (153.60 BogoMIPS).
Jul  4 16:36:24 bbctrl kernel: [    0.053557] CPU: All CPU(s) started in HYP mode.
Jul  4 16:36:24 bbctrl kernel: [    0.053583] CPU: Virtualization extensions available.
Jul  4 16:36:24 bbctrl kernel: [    0.054404] devtmpfs: initialized
Jul  4 16:36:24 bbctrl kernel: [    0.065694] VFP support v0.3: implementor 41 architecture 3 part 40 variant 3 rev 4
Jul  4 16:36:24 bbctrl kernel: [    0.066005] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
Jul  4 16:36:24 bbctrl kernel: [    0.066067] futex hash table entries: 1024 (order: 4, 65536 bytes)
Jul  4 16:36:24 bbctrl kernel: [    0.066624] pinctrl core: initialized pinctrl subsystem
Jul  4 16:36:24 bbctrl kernel: [    0.067557] NET: Registered protocol family 16
Jul  4 16:36:24 bbctrl kernel: [    0.069847] DMA: preallocated 1024 KiB pool for atomic coherent allocations
Jul  4 16:36:24 bbctrl kernel: [    0.078745] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers.
Jul  4 16:36:24 bbctrl kernel: [    0.078794] hw-breakpoint: maximum watchpoint size is 8 bytes.
Jul  4 16:36:24 bbctrl kernel: [    0.078959] Serial: AMBA PL011 UART driver
Jul  4 16:36:24 bbctrl kernel: [    0.080888] bcm2835-mbox 3f00b880.mailbox: mailbox enabled
Jul  4 16:36:24 bbctrl kernel: [    0.081440] uart-pl011 3f201000.serial: could not find pctldev for node /soc/gpio@7e200000/uart0_pins, deferring probe
Jul  4 16:36:24 bbctrl kernel: [    0.150723] bcm2835-dma 3f007000.dma: DMA legacy API manager at bb80f000, dmachans=0x1
Jul  4 16:36:24 bbctrl kernel: [    0.152595] SCSI subsystem initialized
Jul  4 16:36:24 bbctrl kernel: [    0.152779] usbcore: registered new interface driver usbfs
Jul  4 16:36:24 bbctrl kernel: [    0.152882] usbcore: registered new interface driver hub
Jul  4 16:36:24 bbctrl kernel: [    0.153005] usbcore: registered new device driver usb
Jul  4 16:36:24 bbctrl kernel: [    0.159693] raspberrypi-firmware soc:firmware: Attached to firmware from 2017-10-24 17:09
Jul  4 16:36:24 bbctrl kernel: [    0.161139] clocksource: Switched to clocksource arch_sys_counter
Jul  4 16:36:24 bbctrl kernel: [    0.208271] VFS: Disk quotas dquot_6.6.0
Jul  4 16:36:24 bbctrl kernel: [    0.208381] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
Jul  4 16:36:24 bbctrl kernel: [    0.208613] FS-Cache: Loaded
Jul  4 16:36:24 bbctrl kernel: [    0.208875] CacheFiles: Loaded
Jul  4 16:36:24 bbctrl kernel: [    0.221059] NET: Registered protocol family 2
Jul  4 16:36:24 bbctrl kernel: [    0.221981] TCP established hash table entries: 8192 (order: 3, 32768 bytes)
Jul  4 16:36:24 bbctrl kernel: [    0.222118] TCP bind hash table entries: 8192 (order: 4, 65536 bytes)
Jul  4 16:36:24 bbctrl kernel: [    0.222329] TCP: Hash tables configured (established 8192 bind 8192)
Jul  4 16:36:24 bbctrl kernel: [    0.222442] UDP hash table entries: 512 (order: 2, 16384 bytes)
Jul  4 16:36:24 bbctrl kernel: [    0.222509] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes)
Jul  4 16:36:24 bbctrl kernel: [    0.222741] NET: Registered protocol family 1
Jul  4 16:36:24 bbctrl kernel: [    0.223171] RPC: Registered named UNIX socket transport module.
Jul  4 16:36:24 bbctrl kernel: [    0.223204] RPC: Registered udp transport module.
Jul  4 16:36:24 bbctrl kernel: [    0.223231] RPC: Registered tcp transport module.
Jul  4 16:36:24 bbctrl kernel: [    0.223259] RPC: Registered tcp NFSv4.1 backchannel transport module.
Jul  4 16:36:24 bbctrl kernel: [    0.224261] hw perfevents: enabled with armv7_cortex_a7 PMU driver, 7 counters available
Jul  4 16:36:24 bbctrl kernel: [    0.226600] workingset: timestamp_bits=14 max_order=18 bucket_order=4
Jul  4 16:36:24 bbctrl kernel: [    0.242660] FS-Cache: Netfs 'nfs' registered for caching
Jul  4 16:36:24 bbctrl kernel: [    0.243664] NFS: Registering the id_resolver key type
Jul  4 16:36:24 bbctrl kernel: [    0.243713] Key type id_resolver registered
Jul  4 16:36:24 bbctrl kernel: [    0.243741] Key type id_legacy registered
Jul  4 16:36:24 bbctrl kernel: [    0.246183] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251)
Jul  4 16:36:24 bbctrl kernel: [    0.246330] io scheduler noop registered
Jul  4 16:36:24 bbctrl kernel: [    0.246360] io scheduler deadline registered (default)
Jul  4 16:36:24 bbctrl kernel: [    0.246633] io scheduler cfq registered
Jul  4 16:36:24 bbctrl kernel: [    0.252318] BCM2708FB: allocated DMA memory fad10000
Jul  4 16:36:24 bbctrl kernel: [    0.252372] BCM2708FB: allocated DMA channel 0 @ bb80f000
Jul  4 16:36:24 bbctrl kernel: [    0.261076] Console: switching to colour frame buffer device 82x26
Jul  4 16:36:24 bbctrl kernel: [    0.268525] Serial: 8250/16550 driver, 1 ports, IRQ sharing enabled
Jul  4 16:36:24 bbctrl kernel: [    0.272408] bcm2835-rng 3f104000.rng: hwrng registered
Jul  4 16:36:24 bbctrl kernel: [    0.274794] vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB)
Jul  4 16:36:24 bbctrl kernel: [    0.280064] vc-sm: Videocore shared memory driver
Jul  4 16:36:24 bbctrl kernel: [    0.297478] brd: module loaded
Jul  4 16:36:24 bbctrl kernel: [    0.308676] loop: module loaded
Jul  4 16:36:24 bbctrl kernel: [    0.311020] Loading iSCSI transport class v2.0-870.
Jul  4 16:36:24 bbctrl kernel: [    0.313860] usbcore: registered new interface driver smsc95xx
Jul  4 16:36:24 bbctrl kernel: [    0.316171] dwc_otg: version 3.00a 10-AUG-2012 (platform bus)
Jul  4 16:36:24 bbctrl kernel: [    0.546485] Core Release: 2.80a
Jul  4 16:36:24 bbctrl kernel: [    0.548761] Setting default values for core params
Jul  4 16:36:24 bbctrl kernel: [    0.551164] Finished setting default values for core params
Jul  4 16:36:24 bbctrl kernel: [    0.753887] Using Buffer DMA mode
Jul  4 16:36:24 bbctrl kernel: [    0.756122] Periodic Transfer Interrupt Enhancement - disabled
Jul  4 16:36:24 bbctrl kernel: [    0.758441] Multiprocessor Interrupt Enhancement - disabled
Jul  4 16:36:24 bbctrl kernel: [    0.760802] OTG VER PARAM: 0, OTG VER FLAG: 0
Jul  4 16:36:24 bbctrl kernel: [    0.763192] Dedicated Tx FIFOs mode
Jul  4 16:36:24 bbctrl kernel: [    0.765858] WARN::dwc_otg_hcd_init:1032: FIQ DMA bounce buffers: virt = 0xbad04000 dma = 0xfad04000 len=9024
Jul  4 16:36:24 bbctrl kernel: [    0.770835] FIQ FSM acceleration enabled for :
Jul  4 16:36:24 bbctrl kernel: [    0.770835] Non-periodic Split Transactions
Jul  4 16:36:24 bbctrl kernel: [    0.770835] Periodic Split Transactions
Jul  4 16:36:24 bbctrl kernel: [    0.778022] dwc_otg: Microframe scheduler enabled
Jul  4 16:36:24 bbctrl kernel: [    0.778065] WARN::hcd_init_fiq:459: FIQ on core 1 at 0x8058f5b0
Jul  4 16:36:24 bbctrl kernel: [    0.780383] WARN::hcd_init_fiq:460: FIQ ASM at 0x8058f920 length 36
Jul  4 16:36:24 bbctrl kernel: [    0.782675] WARN::hcd_init_fiq:486: MPHI regs_base at 0xbb87a000
Jul  4 16:36:24 bbctrl kernel: [    0.785018] dwc_otg 3f980000.usb: DWC OTG Controller
Jul  4 16:36:24 bbctrl kernel: [    0.787367] dwc_otg 3f980000.usb: new USB bus registered, assigned bus number 1
Jul  4 16:36:24 bbctrl kernel: [    0.789774] dwc_otg 3f980000.usb: irq 62, io mem 0x00000000
Jul  4 16:36:24 bbctrl kernel: [    0.792163] Init: Port Power? op_state=1
Jul  4 16:36:24 bbctrl kernel: [    0.794451] Init: Power Port (0)
Jul  4 16:36:24 bbctrl kernel: [    0.796854] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
Jul  4 16:36:24 bbctrl kernel: [    0.799205] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
Jul  4 16:36:24 bbctrl kernel: [    0.801572] usb usb1: Product: DWC OTG Controller
Jul  4 16:36:24 bbctrl kernel: [    0.803878] usb usb1: Manufacturer: Linux 4.9.59-v7+ dwc_otg_hcd
Jul  4 16:36:24 bbctrl kernel: [    0.806200] usb usb1: SerialNumber: 3f980000.usb
Jul  4 16:36:24 bbctrl kernel: [    0.809282] hub 1-0:1.0: USB hub found
Jul  4 16:36:24 bbctrl kernel: [    0.811567] hub 1-0:1.0: 1 port detected
Jul  4 16:36:24 bbctrl kernel: [    0.814436] dwc_otg: FIQ enabled
Jul  4 16:36:24 bbctrl kernel: [    0.814440] dwc_otg: NAK holdoff enabled
Jul  4 16:36:24 bbctrl kernel: [    0.814444] dwc_otg: FIQ split-transaction FSM enabled
Jul  4 16:36:24 bbctrl kernel: [    0.814456] Module dwc_common_port init
Jul  4 16:36:24 bbctrl kernel: [    0.814677] usbcore: registered new interface driver usb-storage
Jul  4 16:36:24 bbctrl kernel: [    0.817104] mousedev: PS/2 mouse device common for all mice
Jul  4 16:36:24 bbctrl kernel: [    0.820175] bcm2835-wdt 3f100000.watchdog: Broadcom BCM2835 watchdog timer
Jul  4 16:36:24 bbctrl kernel: [    0.822705] bcm2835-cpufreq: min=600000 max=1200000
Jul  4 16:36:24 bbctrl kernel: [    0.825308] sdhci: Secure Digital Host Controller Interface driver
Jul  4 16:36:24 bbctrl kernel: [    0.827615] sdhci: Copyright(c) Pierre Ossman
Jul  4 16:36:24 bbctrl kernel: [    0.830141] sdhost-bcm2835 3f202000.sdhost: could not get clk, deferring probe
Jul  4 16:36:24 bbctrl kernel: [    0.834735] mmc-bcm2835 3f300000.mmc: could not get clk, deferring probe
Jul  4 16:36:24 bbctrl kernel: [    0.837278] sdhci-pltfm: SDHCI platform and OF driver helper
Jul  4 16:36:24 bbctrl kernel: [    0.842340] ledtrig-cpu: registered to indicate activity on CPUs
Jul  4 16:36:24 bbctrl kernel: [    0.844990] hidraw: raw HID events driver (C) Jiri Kosina
Jul  4 16:36:24 bbctrl kernel: [    0.847644] usbcore: registered new interface driver usbhid
Jul  4 16:36:24 bbctrl kernel: [    0.850103] usbhid: USB HID core driver
Jul  4 16:36:24 bbctrl kernel: [    0.853348] vchiq: vchiq_init_state: slot_zero = 0xbad80000, is_master = 0
Jul  4 16:36:24 bbctrl kernel: [    0.857218] [vc_sm_connected_init]: start
Jul  4 16:36:24 bbctrl kernel: [    0.865885] [vc_sm_connected_init]: end - returning 0
Jul  4 16:36:24 bbctrl kernel: [    0.868697] Initializing XFRM netlink socket
Jul  4 16:36:24 bbctrl kernel: [    0.871179] NET: Registered protocol family 17
Jul  4 16:36:24 bbctrl kernel: [    0.873714] Key type dns_resolver registered
Jul  4 16:36:24 bbctrl kernel: [    0.876551] Registering SWP/SWPB emulation handler
Jul  4 16:36:24 bbctrl kernel: [    0.879709] registered taskstats version 1
Jul  4 16:36:24 bbctrl kernel: [    0.887391] uart-pl011 3f201000.serial: cts_event_workaround enabled
Jul  4 16:36:24 bbctrl kernel: [    0.889962] 3f201000.serial: ttyAMA0 at MMIO 0x3f201000 (irq = 87, base_baud = 0) is a PL011 rev2
Jul  4 16:36:24 bbctrl kernel: [    0.896487] sdhost: log_buf @ bad07000 (fad07000)
Jul  4 16:36:24 bbctrl kernel: [    0.971166] mmc0: sdhost-bcm2835 loaded - DMA enabled (>1)
Jul  4 16:36:24 bbctrl kernel: [    0.975902] mmc-bcm2835 3f300000.mmc: mmc_debug:0 mmc_debug2:0
Jul  4 16:36:24 bbctrl kernel: [    0.978393] mmc-bcm2835 3f300000.mmc: DMA channel allocated
Jul  4 16:36:24 bbctrl kernel: [    1.031316] Indeed it is in host mode hprt0 = 00021501
Jul  4 16:36:24 bbctrl kernel: [    1.093834] of_cfs_init
Jul  4 16:36:24 bbctrl kernel: [    1.096216] of_cfs_init: OK
Jul  4 16:36:24 bbctrl kernel: [    1.098887] Waiting for root device PARTUUID=32bfa6cc-02...
Jul  4 16:36:24 bbctrl kernel: [    1.117780] random: fast init done
Jul  4 16:36:24 bbctrl kernel: [    1.122490] mmc1: queuing unknown CIS tuple 0x80 (2 bytes)
Jul  4 16:36:24 bbctrl kernel: [    1.126302] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
Jul  4 16:36:24 bbctrl kernel: [    1.129985] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
Jul  4 16:36:24 bbctrl kernel: [    1.134997] mmc1: queuing unknown CIS tuple 0x80 (7 bytes)
Jul  4 16:36:24 bbctrl kernel: [    1.143368] mmc0: host does not support reading read-only switch, assuming write-enable
Jul  4 16:36:24 bbctrl kernel: [    1.150281] mmc0: overclocking to 100000000Hz
Jul  4 16:36:24 bbctrl kernel: [    1.152537] mmc0: new high speed SDHC card at address aaaa
Jul  4 16:36:24 bbctrl kernel: [    1.155396] mmcblk0: mmc0:aaaa SC16G 14.8 GiB
Jul  4 16:36:24 bbctrl kernel: [    1.160263]  mmcblk0: p1 p2
Jul  4 16:36:24 bbctrl kernel: [    1.225507] EXT4-fs (mmcblk0p2): INFO: recovery required on readonly filesystem
Jul  4 16:36:24 bbctrl kernel: [    1.227650] EXT4-fs (mmcblk0p2): write access will be enabled during recovery
Jul  4 16:36:24 bbctrl kernel: [    1.241180] usb 1-1: new high-speed USB device number 2 using dwc_otg
Jul  4 16:36:24 bbctrl kernel: [    1.243464] Indeed it is in host mode hprt0 = 00001101
Jul  4 16:36:24 bbctrl kernel: [    1.313713] mmc1: new high speed SDIO card at address 0001
Jul  4 16:36:24 bbctrl kernel: [    1.340959] EXT4-fs (mmcblk0p2): recovery complete
Jul  4 16:36:24 bbctrl kernel: [    1.344736] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null)
Jul  4 16:36:24 bbctrl kernel: [    1.349142] VFS: Mounted root (ext4 filesystem) readonly on device 179:2.
Jul  4 16:36:24 bbctrl kernel: [    1.359983] devtmpfs: mounted
Jul  4 16:36:24 bbctrl kernel: [    1.364096] Freeing unused kernel memory: 1024K
Jul  4 16:36:24 bbctrl kernel: [    1.471495] usb 1-1: New USB device found, idVendor=0424, idProduct=9514
Jul  4 16:36:24 bbctrl kernel: [    1.474085] usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
Jul  4 16:36:24 bbctrl kernel: [    1.477547] hub 1-1:1.0: USB hub found
Jul  4 16:36:24 bbctrl kernel: [    1.480298] hub 1-1:1.0: 5 ports detected
Jul  4 16:36:24 bbctrl kernel: [    1.741118] NET: Registered protocol family 10
Jul  4 16:36:24 bbctrl kernel: [    1.755874] ip_tables: (C) 2000-2006 Netfilter Core Team
Jul  4 16:36:24 bbctrl kernel: [    1.801184] usb 1-1.1: new high-speed USB device number 3 using dwc_otg
Jul  4 16:36:24 bbctrl kernel: [    1.931500] usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00
Jul  4 16:36:24 bbctrl kernel: [    1.934312] usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
Jul  4 16:36:24 bbctrl kernel: [    1.939897] smsc95xx v1.0.5
Jul  4 16:36:24 bbctrl kernel: [    2.044948] smsc95xx 1-1.1:1.0 eth0: register 'smsc95xx' at usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet, b8:27:eb:a0:40:01
Jul  4 16:36:24 bbctrl kernel: [    2.351253] usb 1-1.5: new full-speed USB device number 4 using dwc_otg
Jul  4 16:36:24 bbctrl kernel: [    2.421037] i2c /dev entries driver
Jul  4 16:36:24 bbctrl kernel: [    2.525234] usb 1-1.5: New USB device found, idVendor=046d, idProduct=c21d
Jul  4 16:36:24 bbctrl kernel: [    2.525249] usb 1-1.5: New USB device strings: Mfr=1, Product=2, SerialNumber=3
Jul  4 16:36:24 bbctrl kernel: [    2.525255] usb 1-1.5: Product: Gamepad F310
Jul  4 16:36:24 bbctrl kernel: [    2.525262] usb 1-1.5: Manufacturer: Logitech
Jul  4 16:36:24 bbctrl kernel: [    2.525268] usb 1-1.5: SerialNumber: ECD22D5B
Jul  4 16:36:24 bbctrl kernel: [    2.697131] EXT4-fs (mmcblk0p2): re-mounted. Opts: (null)
Jul  4 16:36:24 bbctrl kernel: [    3.077613] input: Logitech Gamepad F310 as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.5/1-1.5:1.0/input/input0
Jul  4 16:36:24 bbctrl kernel: [    3.077758] usbcore: registered new interface driver xpad
Jul  4 16:36:24 bbctrl kernel: [    3.552973] bbserial: no symbol version for module_layout
Jul  4 16:36:24 bbctrl kernel: [    3.552996] bbserial: loading out-of-tree module taints kernel.
Jul  4 16:36:24 bbctrl kernel: [    3.554012] bbserial: loaded
Jul  4 16:36:24 bbctrl kernel: [    3.570615] gpiomem-bcm2835 3f200000.gpiomem: Initialised: Registers at 0x3f200000
Jul  4 16:36:24 bbctrl kernel: [    3.747601] brcmfmac: F1 signature read @0x18000000=0x1541a9a6
Jul  4 16:36:24 bbctrl kernel: [    3.754038] usbcore: registered new interface driver brcmfmac
Jul  4 16:36:24 bbctrl kernel: [    3.946836] brcmfmac: Firmware version = wl0: Aug  7 2017 00:46:29 version 7.45.41.46 (r666254 CY) FWID 01-f8a78378
Jul  4 16:36:24 bbctrl kernel: [    3.947620] brcmfmac: brcmf_c_preinit_dcmds: CLM version = API: 12.2 Data: 7.11.15 Compiler: 1.24.2 ClmImport: 1.24.1 Creation: 2014-05-26 10:53:55 Inc Data: 9.10.41 Inc Compiler: 1.29.4 Inc ClmImport: 1.36.3 Creation: 2017-08-07 00:37:47 
Jul  4 16:36:24 bbctrl kernel: [    4.523076] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
Jul  4 16:36:24 bbctrl kernel: [    4.523091] brcmfmac: power management disabled
Jul  4 16:36:24 bbctrl dphys-swapfile[345]: want /var/swap=1000MByte, checking existing: keeping it
Jul  4 16:36:24 bbctrl dhcpcd[347]: eth0: waiting for carrier
Jul  4 16:36:24 bbctrl dhcpcd[347]: wlan0: waiting for carrier
Jul  4 16:36:24 bbctrl kernel: [    5.093521] smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup
Jul  4 16:36:24 bbctrl kernel: [    5.093867] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
Jul  4 16:36:25 bbctrl dhclient[426]: Internet Systems Consortium DHCP Client 4.3.5
Jul  4 16:36:25 bbctrl sh[301]: Internet Systems Consortium DHCP Client 4.3.5
Jul  4 16:36:25 bbctrl dhclient[426]: Copyright 2004-2016 Internet Systems Consortium.
Jul  4 16:36:25 bbctrl sh[301]: Copyright 2004-2016 Internet Systems Consortium.
Jul  4 16:36:25 bbctrl dhclient[426]: All rights reserved.
Jul  4 16:36:25 bbctrl sh[301]: All rights reserved.
Jul  4 16:36:25 bbctrl sh[301]: For info, please visit https://www.isc.org/software/dhcp/
Jul  4 16:36:25 bbctrl dhclient[426]: For info, please visit https://www.isc.org/software/dhcp/
Jul  4 16:36:25 bbctrl kernel: [    5.182397] Adding 1023996k swap on /var/swap.  Priority:-1 extents:7 across:1458172k SSFS
Jul  4 16:36:25 bbctrl dhclient[426]: 
Jul  4 16:36:25 bbctrl dphys-swapfile[345]: done.
Jul  4 16:36:25 bbctrl systemd[1]: Started LSB: Autogenerate and use a swap file.
Jul  4 16:36:25 bbctrl dhclient[426]: Listening on LPF/wlan0/b8:27:eb:f5:15:54
Jul  4 16:36:25 bbctrl sh[301]: Listening on LPF/wlan0/b8:27:eb:f5:15:54
Jul  4 16:36:25 bbctrl sh[301]: Sending on   LPF/wlan0/b8:27:eb:f5:15:54
Jul  4 16:36:25 bbctrl sh[301]: Sending on   Socket/fallback
Jul  4 16:36:25 bbctrl sh[301]: DHCPREQUEST of 192.168.1.134 on wlan0 to 255.255.255.255 port 67
Jul  4 16:36:25 bbctrl dhclient[426]: Sending on   LPF/wlan0/b8:27:eb:f5:15:54
Jul  4 16:36:25 bbctrl dhclient[426]: Sending on   Socket/fallback
Jul  4 16:36:25 bbctrl dhclient[426]: DHCPREQUEST of 192.168.1.134 on wlan0 to 255.255.255.255 port 67
Jul  4 16:36:25 bbctrl wpa_supplicant[382]: wlan0: Trying to associate with 04:d9:f5:b1:7b:88 (SSID='Pinson' freq=2412 MHz)
Jul  4 16:36:25 bbctrl wpa_supplicant[382]: wlan0: Associated with 04:d9:f5:b1:7b:88
Jul  4 16:36:25 bbctrl kernel: [    5.691264] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
Jul  4 16:36:25 bbctrl wpa_supplicant[382]: wlan0: WPA: Key negotiation completed with 04:d9:f5:b1:7b:88 [PTK=CCMP GTK=CCMP]
Jul  4 16:36:25 bbctrl wpa_supplicant[382]: wlan0: CTRL-EVENT-CONNECTED - Connection to 04:d9:f5:b1:7b:88 completed [id=0 id_str=]
Jul  4 16:36:25 bbctrl dhcpcd[347]: wlan0: carrier acquired
Jul  4 16:36:25 bbctrl dhcpcd[347]: DUID 00:01:00:01:21:44:28:37:b8:27:eb:f5:15:54
Jul  4 16:36:25 bbctrl dhcpcd[347]: wlan0: IAID eb:f5:15:54
Jul  4 16:36:25 bbctrl dhcpcd[347]: wlan0: adding address fe80::c9c2:7b78:c7a8:d62c
Jul  4 16:36:25 bbctrl wpa_supplicant[382]: p2p-dev-wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=US
Jul  4 16:36:25 bbctrl raspi-config[354]: Checking if shift key is held down: No. Switching to ondemand scaling governor.
Jul  4 16:36:25 bbctrl systemd[1]: Started LSB: Switch to ondemand cpu governor (unless shift key is pressed).
Jul  4 16:36:26 bbctrl dhcpcd[347]: wlan0: rebinding lease of 192.168.1.134
Jul  4 16:36:26 bbctrl dhcpcd[347]: wlan0: probing address 192.168.1.134/24
Jul  4 16:36:26 bbctrl dhcpcd[347]: wlan0: soliciting an IPv6 router
Jul  4 16:36:27 bbctrl avahi-daemon[346]: Joining mDNS multicast group on interface wlan0.IPv6 with address fe80::c9c2:7b78:c7a8:d62c.
Jul  4 16:36:27 bbctrl avahi-daemon[346]: New relevant interface wlan0.IPv6 for mDNS.
Jul  4 16:36:27 bbctrl avahi-daemon[346]: Registering new address record for fe80::c9c2:7b78:c7a8:d62c on wlan0.*.
Jul  4 16:36:29 bbctrl dhclient[426]: DHCPREQUEST of 192.168.1.134 on wlan0 to 255.255.255.255 port 67
Jul  4 16:36:29 bbctrl sh[301]: DHCPREQUEST of 192.168.1.134 on wlan0 to 255.255.255.255 port 67
Jul  4 16:36:29 bbctrl dhclient[426]: DHCPACK of 192.168.1.134 from 192.168.1.1
Jul  4 16:36:29 bbctrl sh[301]: DHCPACK of 192.168.1.134 from 192.168.1.1
Jul  4 16:36:29 bbctrl avahi-daemon[346]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.134.
Jul  4 16:36:29 bbctrl avahi-daemon[346]: New relevant interface wlan0.IPv4 for mDNS.
Jul  4 16:36:29 bbctrl avahi-daemon[346]: Registering new address record for 192.168.1.134 on wlan0.IPv4.
Jul  4 16:36:29 bbctrl dhclient[426]: bound to 192.168.1.134 -- renewal in 32861 seconds.
Jul  4 16:36:29 bbctrl sh[301]: bound to 192.168.1.134 -- renewal in 32861 seconds.
Jul  4 16:36:29 bbctrl sh[301]: wlan0=wlan0
Jul  4 16:36:29 bbctrl systemd[1]: Started Raise network interfaces.
Jul  4 16:36:31 bbctrl dhcpcd[347]: wlan0: leased 192.168.1.134 for 86400 seconds
Jul  4 16:36:31 bbctrl dhcpcd[347]: wlan0: adding route to 192.168.1.0/24
Jul  4 16:36:31 bbctrl dhcpcd[347]: wlan0: adding default route via 192.168.1.1
Jul  4 16:36:31 bbctrl dhcpcd[347]: forked to background, child pid 580
Jul  4 16:36:31 bbctrl systemd[1]: Started dhcpcd on all interfaces.
Jul  4 16:36:31 bbctrl systemd[1]: Reached target Network.
Jul  4 16:36:31 bbctrl systemd[1]: Starting /etc/rc.local Compatibility...
Jul  4 16:36:31 bbctrl systemd[1]: Starting OpenBSD Secure Shell server...
Jul  4 16:36:31 bbctrl systemd[1]: Starting Permit User Sessions...
Jul  4 16:36:31 bbctrl systemd[1]: Started Buildbotics Controller.
Jul  4 16:36:31 bbctrl systemd[1]: Started Permit User Sessions.
Jul  4 16:36:31 bbctrl kernel: [   11.526143] Trying to free nonexistent resource <000000003f201000-000000003f201fff>
Jul  4 16:36:31 bbctrl kernel: [   11.535526] bbserial: unloaded
Jul  4 16:36:31 bbctrl kernel: [   11.585757] bbserial: loaded
Jul  4 16:36:31 bbctrl systemd[1]: Started OpenBSD Secure Shell server.
Jul  4 16:36:31 bbctrl systemd[1]: Stopping udev Kernel Device Manager...
Jul  4 16:36:31 bbctrl systemd[1]: Stopped udev Kernel Device Manager.
Jul  4 16:36:31 bbctrl systemd[1]: Starting udev Kernel Device Manager...
Jul  4 16:36:31 bbctrl systemd[1]: Started udev Kernel Device Manager.
Jul  4 16:36:31 bbctrl systemd[1]: Received SIGRTMIN+21 from PID 199 (plymouthd).
Jul  4 16:36:31 bbctrl rc.local[581]: sudo: startx: command not found
Jul  4 16:36:31 bbctrl systemd[1]: rc-local.service: Control process exited, code=exited status=1
Jul  4 16:36:31 bbctrl systemd[1]: Failed to start /etc/rc.local Compatibility.
Jul  4 16:36:31 bbctrl systemd[1]: rc-local.service: Unit entered failed state.
Jul  4 16:36:31 bbctrl systemd[1]: rc-local.service: Failed with result 'exit-code'.
Jul  4 16:36:31 bbctrl systemd[1]: Starting Hold until boot process finishes up...
Jul  4 16:36:31 bbctrl systemd[1]: Starting Terminate Plymouth Boot Screen...
Jul  4 16:36:31 bbctrl systemd[1]: Started Hold until boot process finishes up.
Jul  4 16:36:31 bbctrl systemd[1]: Started Getty on tty1.
Jul  4 16:36:31 bbctrl systemd[1]: Reached target Login Prompts.
Jul  4 16:36:31 bbctrl systemd[1]: Started Terminate Plymouth Boot Screen.
Jul  4 16:36:31 bbctrl systemd[1]: Reached target Multi-User System.
Jul  4 16:36:31 bbctrl systemd[1]: Reached target Graphical Interface.
Jul  4 16:36:31 bbctrl systemd[1]: Starting Update UTMP about System Runlevel Changes...
Jul  4 16:36:31 bbctrl systemd[1]: Started Update UTMP about System Runlevel Changes.
Jul  4 16:36:31 bbctrl systemd[1]: Startup finished in 1.625s (kernel) + 10.406s (userspace) = 12.031s.
Jul  4 16:36:39 bbctrl dhcpcd[580]: wlan0: no IPv6 Routers available
Jul  4 16:36:48 bbctrl kernel: [   28.351027] random: crng init done
Jul  4 17:03:31 bbctrl systemd[1]: Time has been changed
Jul  4 17:03:31 bbctrl systemd[1]: apt-daily.timer: Adding 42min 6.109281s random time.
Jul  4 17:03:31 bbctrl systemd[1]: apt-daily-upgrade.timer: Adding 18min 4.349664s random time.
Jul  4 17:03:31 bbctrl systemd-timesyncd[290]: Synchronized to time server 185.77.225.135:123 (2.debian.pool.ntp.org).
Jul  4 17:17:01 bbctrl CRON[675]: (root) CMD (   cd / && run-parts --report /etc/cron.hourly)
Jul  4 17:18:22 bbctrl systemd[1]: Starting Cleanup of Temporary Directories...
Jul  4 17:18:22 bbctrl systemd[1]: Started Cleanup of Temporary Directories.
Jul  4 18:00:24 bbctrl wpa_supplicant[382]: wlan0: WPA: Group rekeying completed with 04:d9:f5:b1:7b:88 [GTK=CCMP]
Jul  4 18:17:01 bbctrl CRON[785]: (root) CMD (   cd / && run-parts --report /etc/cron.hourly)
