Nov 14 19:27:01 raspberrypi Node-RED[366]: 14 Nov 19:27:01 - [info] Nov 14 19:27:01 raspberrypi Node-RED[366]: Welcome to Node-RED Nov 14 19:27:01 raspberrypi Node-RED[366]: =================== Nov 14 19:27:01 raspberrypi Node-RED[366]: 14 Nov 19:27:01 - [info] Node-RED version: v1.0.2 Nov 14 19:27:01 raspberrypi Node-RED[366]: 14 Nov 19:27:01 - [info] Node.js version: v10.17.0 Nov 14 19:27:01 raspberrypi Node-RED[366]: 14 Nov 19:27:01 - [info] Linux 4.19.75-v7l+ arm LE Nov 14 19:27:01 raspberrypi systemd[1]: systemd-rfkill.service: Succeeded. Nov 14 19:27:02 raspberrypi Node-RED[366]: 14 Nov 19:27:02 - [info] Loading palette nodes Nov 14 19:27:05 raspberrypi dhcpcd[417]: wlan0: carrier acquired Nov 14 19:27:05 raspberrypi kernel: [ 15.377725] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready Nov 14 19:27:05 raspberrypi dhcpcd[417]: wlan0: IAID 32:32:f2:20 Nov 14 19:27:05 raspberrypi dhcpcd[417]: wlan0: adding address fe80::63fe:f269:5099:91ef Nov 14 19:27:05 raspberrypi avahi-daemon[412]: Joining mDNS multicast group on interface wlan0.IPv6 with address fe80::63fe:f269:5099:91ef. Nov 14 19:27:05 raspberrypi avahi-daemon[412]: New relevant interface wlan0.IPv6 for mDNS. Nov 14 19:27:05 raspberrypi avahi-daemon[412]: Registering new address record for fe80::63fe:f269:5099:91ef on wlan0.*. Nov 14 19:27:05 raspberrypi dhcpcd[417]: wlan0: soliciting an IPv6 router Nov 14 19:27:05 raspberrypi kernel: [ 15.464964] Bluetooth: Core ver 2.22 Nov 14 19:27:05 raspberrypi kernel: [ 15.465000] NET: Registered protocol family 31 Nov 14 19:27:05 raspberrypi kernel: [ 15.465004] Bluetooth: HCI device and connection manager initialized Nov 14 19:27:05 raspberrypi kernel: [ 15.465016] Bluetooth: HCI socket layer initialized Nov 14 19:27:05 raspberrypi kernel: [ 15.465022] Bluetooth: L2CAP socket layer initialized Nov 14 19:27:05 raspberrypi kernel: [ 15.465042] Bluetooth: SCO socket layer initialized Nov 14 19:27:05 raspberrypi btuart[368]: bcm43xx_init Nov 14 19:27:05 raspberrypi kernel: [ 15.474462] Bluetooth: HCI UART driver ver 2.3 Nov 14 19:27:05 raspberrypi kernel: [ 15.474470] Bluetooth: HCI UART protocol H4 registered Nov 14 19:27:05 raspberrypi kernel: [ 15.474515] Bluetooth: HCI UART protocol Three-wire (H5) registered Nov 14 19:27:05 raspberrypi kernel: [ 15.474632] Bluetooth: HCI UART protocol Broadcom registered Nov 14 19:27:05 raspberrypi btuart[368]: Flash firmware /lib/firmware/brcm/BCM4345C0.hcd Nov 14 19:27:05 raspberrypi btuart[368]: Set Controller UART speed to 3000000 bit/s Nov 14 19:27:05 raspberrypi btuart[368]: Device setup complete Nov 14 19:27:05 raspberrypi systemd[1]: Starting Load/Save RF Kill Switch Status... Nov 14 19:27:05 raspberrypi systemd[1]: Started Configure Bluetooth Modems connected by UART. Nov 14 19:27:05 raspberrypi systemd[1]: Started Load/Save RF Kill Switch Status. Nov 14 19:27:05 raspberrypi systemd[1]: Created slice system-bthelper.slice. Nov 14 19:27:05 raspberrypi systemd[1]: Starting Bluetooth service... Nov 14 19:27:05 raspberrypi bluetoothd[585]: Bluetooth daemon 5.50 Nov 14 19:27:05 raspberrypi systemd[1]: Started Bluetooth service. Nov 14 19:27:05 raspberrypi bluetoothd[585]: Starting SDP server Nov 14 19:27:05 raspberrypi systemd[1]: Started BluezALSA proxy. Nov 14 19:27:05 raspberrypi systemd[1]: Started Raspberry Pi bluetooth helper. Nov 14 19:27:05 raspberrypi systemd[1]: Reached target Bluetooth. Nov 14 19:27:05 raspberrypi kernel: [ 15.631304] Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Nov 14 19:27:05 raspberrypi kernel: [ 15.631311] Bluetooth: BNEP filters: protocol multicast Nov 14 19:27:05 raspberrypi kernel: [ 15.631323] Bluetooth: BNEP socket layer initialized Nov 14 19:27:05 raspberrypi bthelper[588]: Can't init device hci0: Operation not possible due to RF-kill (132) Nov 14 19:27:05 raspberrypi bluetoothd[585]: Bluetooth management interface 1.14 initialized Nov 14 19:27:05 raspberrypi dbus-daemon[394]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.7' (uid=0 pid=585 comm="/usr/lib/bluetooth/bluetoothd ") Nov 14 19:27:05 raspberrypi systemd[1]: bthelper@hci0.service: Main process exited, code=exited, status=1/FAILURE Nov 14 19:27:05 raspberrypi bluetoothd[585]: Sap driver initialization failed. Nov 14 19:27:05 raspberrypi systemd[1]: bthelper@hci0.service: Failed with result 'exit-code'. Nov 14 19:27:05 raspberrypi bluetoothd[585]: sap-server: Operation not permitted (1) Nov 14 19:27:05 raspberrypi bluetoothd[585]: Failed to set mode: Blocked through rfkill (0x12) Nov 14 19:27:05 raspberrypi systemd[1]: Starting Hostname Service... Nov 14 19:27:05 raspberrypi bluetoothd[585]: Endpoint registered: sender=:1.8 path=/org/bluez/hci0/A2DP/SBC/Source/1 Nov 14 19:27:05 raspberrypi bluetoothd[585]: Endpoint registered: sender=:1.8 path=/org/bluez/hci0/A2DP/SBC/Source/2 Nov 14 19:27:05 raspberrypi kernel: [ 15.685889] Bluetooth: RFCOMM TTY layer initialized Nov 14 19:27:05 raspberrypi kernel: [ 15.685907] Bluetooth: RFCOMM socket layer initialized Nov 14 19:27:05 raspberrypi kernel: [ 15.685924] Bluetooth: RFCOMM ver 1.11 Nov 14 19:27:05 raspberrypi dbus-daemon[394]: [system] Successfully activated service 'org.freedesktop.hostname1' Nov 14 19:27:05 raspberrypi systemd[1]: Started Hostname Service. Nov 14 19:27:05 raspberrypi Node-RED[366]: 14 Nov 19:27:05 - [info] Settings file : /home/pi/.node-red/settings.js Nov 14 19:27:05 raspberrypi Node-RED[366]: 14 Nov 19:27:05 - [info] Context store : 'default' [module=memory] Nov 14 19:27:05 raspberrypi Node-RED[366]: 14 Nov 19:27:05 - [info] User directory : /home/pi/.node-red Nov 14 19:27:05 raspberrypi Node-RED[366]: 14 Nov 19:27:05 - [warn] Projects disabled : editorTheme.projects.enabled=false Nov 14 19:27:05 raspberrypi Node-RED[366]: 14 Nov 19:27:05 - [info] Flows file : /home/pi/.node-red/hvoiot_flows.json Nov 14 19:27:05 raspberrypi Node-RED[366]: 14 Nov 19:27:05 - [info] Server now running at http://127.0.0.1:1880/ Nov 14 19:27:05 raspberrypi Node-RED[366]: 14 Nov 19:27:05 - [warn] Nov 14 19:27:05 raspberrypi Node-RED[366]: --------------------------------------------------------------------- Nov 14 19:27:05 raspberrypi Node-RED[366]: Your flow credentials file is encrypted using a system-generated key. Nov 14 19:27:05 raspberrypi Node-RED[366]: If the system-generated key is lost for any reason, your credentials Nov 14 19:27:05 raspberrypi Node-RED[366]: file will not be recoverable, you will have to delete it and re-enter Nov 14 19:27:05 raspberrypi Node-RED[366]: your credentials. Nov 14 19:27:05 raspberrypi Node-RED[366]: You should set your own key using the 'credentialSecret' option in Nov 14 19:27:05 raspberrypi Node-RED[366]: your settings file. Node-RED will then re-encrypt your credentials Nov 14 19:27:05 raspberrypi Node-RED[366]: file using your chosen key the next time you deploy a change. Nov 14 19:27:05 raspberrypi Node-RED[366]: --------------------------------------------------------------------- Nov 14 19:27:05 raspberrypi Node-RED[366]: 14 Nov 19:27:05 - [info] Starting flows Nov 14 19:27:06 raspberrypi dhcpcd[417]: wlan0: rebinding lease of 192.168.1.91 Nov 14 19:27:06 raspberrypi dhcpcd[417]: wlan0: probing address 192.168.1.91/24 Nov 14 19:27:06 raspberrypi Node-RED[366]: 14 Nov 19:27:06 - [info] Started flows Nov 14 19:27:06 raspberrypi Node-RED[366]: 14 Nov 19:27:06 - [info] [sqlitedb:8e7321b2.c4ec5] opened /home/pi/hvo/db/hvotrax.sqlite ok Nov 14 19:27:06 raspberrypi Node-RED[366]: 14 Nov 19:27:06 - [info] serial port /dev/ttyS0 opened at 19200 baud 8N1 Nov 14 19:27:06 raspberrypi Node-RED[366]: 14 Nov 19:27:06 - [info] [queue:Queue on http error] Opened /home/pi/hvo/db/hvotrax.sqlite successfully. Nov 14 19:27:06 raspberrypi Node-RED[366]: 14 Nov 19:27:06 - [info] [exec:1a703c44.1790c4] error:Error: Command failed: sudo rm /home/pi/hvo/data/lpmflow.* Nov 14 19:27:06 raspberrypi Node-RED[366]: rm: cannot remove '/home/pi/hvo/data/lpmflow.*': No such file or directory Nov 14 19:27:06 raspberrypi Node-RED[366]: 14 Nov 19:27:06 - [error] [function:Get PSI, Purity & Settings] TypeError: Cannot read property 'temperature' of undefined Nov 14 19:27:07 raspberrypi Node-RED[366]: 14 Nov 19:27:07 - [error] [function:Get PSI, Purity & Settings] TypeError: Cannot read property 'temperature' of undefined Nov 14 19:27:10 raspberrypi systemd[1]: systemd-rfkill.service: Succeeded. Nov 14 19:27:10 raspberrypi Node-RED[366]: 14 Nov 19:27:10 - [error] [http request:ff600b9a.6e3b58] Error: getaddrinfo EAI_AGAIN seeingeye.highvolumeoxygen.com seeingeye.highvolumeoxygen.com:443 Nov 14 19:27:10 raspberrypi Node-RED[366]: 14 Nov 19:27:10 - [error] [http request:ff600b9a.6e3b58] Error: getaddrinfo EAI_AGAIN seeingeye.highvolumeoxygen.com seeingeye.highvolumeoxygen.com:443 Nov 14 19:27:11 raspberrypi dhcpcd[417]: wlan0: leased 192.168.1.91 for 86400 seconds Nov 14 19:27:11 raspberrypi avahi-daemon[412]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.91. Nov 14 19:27:11 raspberrypi avahi-daemon[412]: New relevant interface wlan0.IPv4 for mDNS. Nov 14 19:27:11 raspberrypi dhcpcd[417]: wlan0: adding route to 192.168.1.0/24 Nov 14 19:27:11 raspberrypi avahi-daemon[412]: Registering new address record for 192.168.1.91 on wlan0.IPv4. Nov 14 19:27:11 raspberrypi dhcpcd[417]: wlan0: adding default route via 192.168.1.1 Nov 14 19:27:11 raspberrypi dhcpcd[417]: forked to background, child pid 669 Nov 14 19:27:11 raspberrypi systemd[1]: Started dhcpcd on all interfaces. Nov 14 19:27:11 raspberrypi systemd[1]: Reached target Network. Nov 14 19:27:11 raspberrypi systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Nov 14 19:27:11 raspberrypi systemd[1]: Starting /etc/rc.local Compatibility... Nov 14 19:27:11 raspberrypi systemd[1]: Starting Permit User Sessions... Nov 14 19:27:11 raspberrypi systemd[1]: Starting OpenBSD Secure Shell server... Nov 14 19:27:11 raspberrypi systemd[1]: Started /etc/rc.local Compatibility. Nov 14 19:27:11 raspberrypi systemd[1]: Started Permit User Sessions. Nov 14 19:27:11 raspberrypi systemd[1]: Starting Hold until boot process finishes up... Nov 14 19:27:11 raspberrypi systemd[1]: Starting Light Display Manager... Nov 14 19:27:11 raspberrypi systemd[1]: Started OpenBSD Secure Shell server. Nov 14 19:27:11 raspberrypi lightdm[677]: Error getting user list from org.freedesktop.Accounts: GDBus.Error:org.freedesktop.DBus.Error.ServiceUnknown: The name org.freedesktop.Accounts was not provided by any .service files Nov 14 19:27:11 raspberrypi systemd[1]: Received SIGRTMIN+21 from PID 162 (plymouthd). Nov 14 19:27:11 raspberrypi systemd[1]: Received SIGRTMIN+21 from PID 162 (plymouthd). Nov 14 19:27:11 raspberrypi systemd[1]: plymouth-start.service: Succeeded. Nov 14 19:27:11 raspberrypi systemd[1]: plymouth-quit-wait.service: Succeeded. Nov 14 19:27:11 raspberrypi systemd[1]: Started Hold until boot process finishes up. Nov 14 19:27:11 raspberrypi systemd[1]: Started Light Display Manager. Nov 14 19:27:11 raspberrypi systemd[1]: Started Getty on tty1. Nov 14 19:27:11 raspberrypi systemd[1]: Reached target Login Prompts. Nov 14 19:27:11 raspberrypi systemd[1]: Reached target Multi-User System. Nov 14 19:27:11 raspberrypi systemd[1]: Reached target Graphical Interface. Nov 14 19:27:11 raspberrypi systemd[1]: Starting Update UTMP about System Runlevel Changes... Nov 14 19:27:11 raspberrypi systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Nov 14 19:27:11 raspberrypi systemd[1]: Started Update UTMP about System Runlevel Changes. Nov 14 19:27:11 raspberrypi systemd[1]: Startup finished in 3.128s (kernel) + 18.622s (userspace) = 21.751s. Nov 14 19:27:11 raspberrypi systemd[1]: Created slice User Slice of UID 1000. Nov 14 19:27:11 raspberrypi systemd[1]: Starting User Runtime Directory /run/user/1000... Nov 14 19:27:11 raspberrypi systemd[1]: Started User Runtime Directory /run/user/1000. Nov 14 19:27:11 raspberrypi systemd[1]: Starting User Manager for UID 1000... Nov 14 19:27:12 raspberrypi systemd[700]: Reached target Timers. Nov 14 19:27:12 raspberrypi systemd[700]: Listening on GnuPG network certificate management daemon. Nov 14 19:27:12 raspberrypi systemd[700]: Listening on GnuPG cryptographic agent and passphrase cache (access for web browsers). Nov 14 19:27:12 raspberrypi systemd[700]: Reached target Paths. Nov 14 19:27:12 raspberrypi systemd[700]: Listening on GnuPG cryptographic agent and passphrase cache (restricted). Nov 14 19:27:12 raspberrypi systemd[700]: Listening on GnuPG cryptographic agent and passphrase cache. Nov 14 19:27:12 raspberrypi systemd[700]: Listening on GnuPG cryptographic agent (ssh-agent emulation). Nov 14 19:27:12 raspberrypi systemd[700]: Starting D-Bus User Message Bus Socket. Nov 14 19:27:12 raspberrypi systemd[700]: Listening on D-Bus User Message Bus Socket. Nov 14 19:27:12 raspberrypi systemd[700]: Reached target Sockets. Nov 14 19:27:12 raspberrypi systemd[700]: Reached target Basic System. Nov 14 19:27:12 raspberrypi systemd[700]: Reached target Default. Nov 14 19:27:12 raspberrypi systemd[700]: Startup finished in 295ms. Nov 14 19:27:12 raspberrypi systemd[1]: Started User Manager for UID 1000. Nov 14 19:27:12 raspberrypi systemd[1]: Started Session c1 of user pi. Nov 14 19:27:14 raspberrypi lightdm[735]: Error getting user list from org.freedesktop.Accounts: GDBus.Error:org.freedesktop.DBus.Error.ServiceUnknown: The name org.freedesktop.Accounts was not provided by any .service files Nov 14 19:27:14 raspberrypi systemd[1]: Started Session c2 of user pi. Nov 14 19:27:14 raspberrypi lightdm[677]: Error opening audit socket: Protocol not supported Nov 14 19:27:14 raspberrypi systemd[700]: Started D-Bus User Message Bus. Nov 14 19:27:15 raspberrypi dbus-daemon[752]: [session uid=1000 pid=752] Activating via systemd: service name='org.gtk.vfs.Daemon' unit='gvfs-daemon.service' requested by ':1.3' (uid=1000 pid=743 comm="/usr/bin/lxsession -s LXDE-pi -e LXDE ") Nov 14 19:27:15 raspberrypi systemd[700]: Starting Virtual filesystem service... Nov 14 19:27:15 raspberrypi dbus-daemon[752]: [session uid=1000 pid=752] Successfully activated service 'org.gtk.vfs.Daemon' Nov 14 19:27:15 raspberrypi systemd[700]: Started Virtual filesystem service. Nov 14 19:27:15 raspberrypi kernel: [ 25.556102] fuse init (API version 7.27) Nov 14 19:27:15 raspberrypi systemd[1]: Mounting FUSE Control File System... Nov 14 19:27:15 raspberrypi systemd[1]: Mounted FUSE Control File System. Nov 14 19:27:16 raspberrypi Node-RED[366]: 14 Nov 19:27:16 - [info] [queue:Queue on http error] Processing messages in queue Nov 14 19:27:16 raspberrypi Node-RED[366]: 14 Nov 19:27:16 - [info] [queue:Queue on http error] Queue now empty Nov 14 19:27:16 raspberrypi dbus-daemon[752]: [session uid=1000 pid=752] Activating via systemd: service name='org.gtk.vfs.UDisks2VolumeMonitor' unit='gvfs-udisks2-volume-monitor.service' requested by ':1.7' (uid=1000 pid=810 comm="pcmanfm --desktop --profile LXDE-pi ") Nov 14 19:27:16 raspberrypi systemd[700]: Starting Virtual filesystem service - disk device monitor... Nov 14 19:27:16 raspberrypi dbus-daemon[752]: [session uid=1000 pid=752] Successfully activated service 'org.gtk.vfs.UDisks2VolumeMonitor' Nov 14 19:27:16 raspberrypi systemd[700]: Started Virtual filesystem service - disk device monitor. Nov 14 19:27:16 raspberrypi dbus-daemon[752]: [session uid=1000 pid=752] Activating via systemd: service name='org.gtk.vfs.AfcVolumeMonitor' unit='gvfs-afc-volume-monitor.service' requested by ':1.7' (uid=1000 pid=810 comm="pcmanfm --desktop --profile LXDE-pi ") Nov 14 19:27:16 raspberrypi systemd[700]: Starting Virtual filesystem service - Apple File Conduit monitor... Nov 14 19:27:16 raspberrypi gvfs-afc-volume-monitor[865]: Volume monitor alive Nov 14 19:27:16 raspberrypi dbus-daemon[752]: [session uid=1000 pid=752] Successfully activated service 'org.gtk.vfs.AfcVolumeMonitor' Nov 14 19:27:16 raspberrypi systemd[700]: Started Virtual filesystem service - Apple File Conduit monitor. Nov 14 19:27:16 raspberrypi dbus-daemon[752]: [session uid=1000 pid=752] Activating via systemd: service name='org.gtk.vfs.MTPVolumeMonitor' unit='gvfs-mtp-volume-monitor.service' requested by ':1.7' (uid=1000 pid=810 comm="pcmanfm --desktop --profile LXDE-pi ") Nov 14 19:27:16 raspberrypi systemd[700]: Starting Virtual filesystem service - Media Transfer Protocol monitor... Nov 14 19:27:16 raspberrypi dbus-daemon[752]: [session uid=1000 pid=752] Successfully activated service 'org.gtk.vfs.MTPVolumeMonitor' Nov 14 19:27:16 raspberrypi systemd[700]: Started Virtual filesystem service - Media Transfer Protocol monitor. Nov 14 19:27:16 raspberrypi dbus-daemon[752]: [session uid=1000 pid=752] Activating via systemd: service name='org.gtk.vfs.GoaVolumeMonitor' unit='gvfs-goa-volume-monitor.service' requested by ':1.8' (uid=1000 pid=807 comm="lxpanel --profile LXDE-pi ") Nov 14 19:27:16 raspberrypi systemd[700]: Starting Virtual filesystem service - GNOME Online Accounts monitor... Nov 14 19:27:16 raspberrypi dbus-daemon[752]: [session uid=1000 pid=752] Successfully activated service 'org.gtk.vfs.GoaVolumeMonitor' Nov 14 19:27:16 raspberrypi systemd[700]: Started Virtual filesystem service - GNOME Online Accounts monitor. Nov 14 19:27:16 raspberrypi dbus-daemon[752]: [session uid=1000 pid=752] Activating via systemd: service name='org.gtk.vfs.GPhoto2VolumeMonitor' unit='gvfs-gphoto2-volume-monitor.service' requested by ':1.7' (uid=1000 pid=810 comm="pcmanfm --desktop --profile LXDE-pi ") Nov 14 19:27:16 raspberrypi systemd[700]: Starting Virtual filesystem service - digital camera monitor... Nov 14 19:27:16 raspberrypi dbus-daemon[752]: [session uid=1000 pid=752] Successfully activated service 'org.gtk.vfs.GPhoto2VolumeMonitor' Nov 14 19:27:16 raspberrypi systemd[700]: Started Virtual filesystem service - digital camera monitor. Nov 14 19:27:19 raspberrypi dhcpcd[669]: wlan0: no IPv6 Routers available Nov 14 19:27:27 raspberrypi systemd[1]: systemd-fsckd.service: Succeeded. Nov 14 19:28:03 raspberrypi systemd-timesyncd[319]: Synchronized to time server for the first time 204.2.134.164:123 (2.debian.pool.ntp.org). Nov 14 19:28:10 raspberrypi systemd[1]: systemd-hostnamed.service: Succeeded. Nov 14 19:28:13 raspberrypi systemd[1]: Started Session c3 of user pi.