-- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Tue 2022-11-22 04:56:56 UTC. -- Nov 22 04:55:05 hifi-berry kernel: usb 1-1.2: new full-speed USB device number 9 using dwc_otg Nov 22 04:55:05 hifi-berry kernel: usb 1-1.2: New USB device found, idVendor=1209, idProduct=2303, bcdDevice= 1.00 Nov 22 04:55:05 hifi-berry kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Nov 22 04:55:05 hifi-berry kernel: usb 1-1.2: Product: Atreus Nov 22 04:55:05 hifi-berry kernel: usb 1-1.2: Manufacturer: Keyboardio Nov 22 04:55:05 hifi-berry kernel: usb 1-1.2: SerialNumber: CatreusE Nov 22 04:55:05 hifi-berry kernel: cdc_acm 1-1.2:1.0: ttyACM0: USB ACM device Nov 22 04:55:05 hifi-berry kernel: input: Keyboardio Atreus as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.2/1-1.2:1.2/0003:1209:2303.0009/input/input18 Nov 22 04:55:05 hifi-berry kernel: hid-generic 0003:1209:2303.0009: input,hidraw0: USB HID v1.01 Mouse [Keyboardio Atreus] on usb-3f980000.usb-1.2/input2 Nov 22 04:55:05 hifi-berry kernel: input: Keyboardio Atreus System Control as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.2/1-1.2:1.3/0003:1209:2303.000A/input/input19 Nov 22 04:55:05 hifi-berry kernel: input: Keyboardio Atreus Keyboard as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.2/1-1.2:1.3/0003:1209:2303.000A/input/input20 Nov 22 04:55:05 hifi-berry kernel: input: Keyboardio Atreus Consumer Control as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.2/1-1.2:1.3/0003:1209:2303.000A/input/input21 Nov 22 04:55:05 hifi-berry kernel: input: Keyboardio Atreus Mouse as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.2/1-1.2:1.3/0003:1209:2303.000A/input/input22 Nov 22 04:55:05 hifi-berry kernel: hid-generic 0003:1209:2303.000A: input,hidraw1: USB HID v1.01 Keyboard [Keyboardio Atreus] on usb-3f980000.usb-1.2/input3 Nov 22 04:55:05 hifi-berry kernel: input: Keyboardio Atreus as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.2/1-1.2:1.4/0003:1209:2303.000B/input/input23 Nov 22 04:55:05 hifi-berry kernel: hid-generic 0003:1209:2303.000B: input,hidraw2: USB HID v1.01 Keyboard [Keyboardio Atreus] on usb-3f980000.usb-1.2/input4 Nov 22 04:55:05 hifi-berry systemd-udevd[1656]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Nov 22 04:55:05 hifi-berry systemd-udevd[1648]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Nov 22 04:55:05 hifi-berry systemd-udevd[1650]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Nov 22 04:55:05 hifi-berry systemd-logind[502]: Watching system buttons on /dev/input/event2 (Keyboardio Atreus Keyboard) Nov 22 04:55:05 hifi-berry systemd-udevd[1651]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Nov 22 04:55:05 hifi-berry systemd-udevd[1661]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Nov 22 04:55:05 hifi-berry systemd-udevd[1660]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Nov 22 04:55:05 hifi-berry systemd-logind[502]: Watching system buttons on /dev/input/event5 (Keyboardio Atreus) Nov 22 04:55:05 hifi-berry systemd-udevd[1652]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Nov 22 04:55:05 hifi-berry systemd-udevd[1662]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Nov 22 04:55:05 hifi-berry systemd-logind[502]: Watching system buttons on /dev/input/event3 (Keyboardio Atreus Consumer Control) Nov 22 04:55:05 hifi-berry systemd-logind[502]: Watching system buttons on /dev/input/event1 (Keyboardio Atreus System Control) Nov 22 04:55:06 hifi-berry systemd-udevd[1649]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Nov 22 04:55:15 hifi-berry ntpd[673]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Nov 22 04:55:15 hifi-berry ntpd[673]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Nov 22 04:55:16 hifi-berry ntpd[673]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Nov 22 04:55:21 hifi-berry ntpd[673]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Nov 22 04:56:20 hifi-berry ntpd[673]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Nov 22 04:56:21 hifi-berry ntpd[673]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Nov 22 04:56:22 hifi-berry ntpd[673]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Nov 22 04:56:24 hifi-berry volumio[853]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNetworkSettings Nov 22 04:56:24 hifi-berry volumio[853]: info: Saving new wireless network Nov 22 04:56:24 hifi-berry sudo[1688]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/wpa_supplicant/wpa_supplicant.conf Nov 22 04:56:24 hifi-berry sudo[1688]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 04:56:24 hifi-berry sudo[1688]: pam_unix(sudo:session): session closed for user root Nov 22 04:56:24 hifi-berry volumio[853]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Nov 22 04:56:24 hifi-berry volumio[853]: info: Discovery: Restarting Advertising due to device name change Nov 22 04:56:24 hifi-berry sudo[1691]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Nov 22 04:56:24 hifi-berry sudo[1691]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 04:56:24 hifi-berry systemd[1]: wireless.service: Killing process 770 (wpa_supplicant) with signal SIGKILL. Nov 22 04:56:24 hifi-berry systemd[1]: wireless.service: Killing process 782 (dhcpcd) with signal SIGKILL. Nov 22 04:56:24 hifi-berry systemd[1]: Stopping Wireless Services... Nov 22 04:56:24 hifi-berry systemd[1]: wireless.service: Succeeded. Nov 22 04:56:24 hifi-berry systemd[1]: Stopped Wireless Services. Nov 22 04:56:24 hifi-berry systemd[1]: Starting Wireless Services... Nov 22 04:56:25 hifi-berry wireless.js[1694]: Cleaning previous... Nov 22 04:56:25 hifi-berry sudo[1708]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Nov 22 04:56:25 hifi-berry sudo[1708]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 04:56:25 hifi-berry sudo[1708]: pam_unix(sudo:session): session closed for user root Nov 22 04:56:25 hifi-berry sudo[1710]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Nov 22 04:56:25 hifi-berry sudo[1710]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 04:56:26 hifi-berry sudo[1710]: pam_unix(sudo:session): session closed for user root Nov 22 04:56:26 hifi-berry wireless.js[1694]: Stopped aP Nov 22 04:56:26 hifi-berry sudo[1718]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Nov 22 04:56:26 hifi-berry sudo[1718]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 04:56:26 hifi-berry kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Nov 22 04:56:26 hifi-berry sudo[1718]: pam_unix(sudo:session): session closed for user root Nov 22 04:56:26 hifi-berry sudo[1721]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Nov 22 04:56:26 hifi-berry sudo[1721]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 04:56:26 hifi-berry sudo[1721]: pam_unix(sudo:session): session closed for user root Nov 22 04:56:26 hifi-berry sudo[1728]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Nov 22 04:56:26 hifi-berry sudo[1728]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 04:56:26 hifi-berry sudo[1728]: pam_unix(sudo:session): session closed for user root Nov 22 04:56:26 hifi-berry sudo[1730]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Nov 22 04:56:26 hifi-berry sudo[1730]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 04:56:27 hifi-berry ntpd[673]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Nov 22 04:56:27 hifi-berry volumio[853]: info: CorePlayQueue::getTrack 0 Nov 22 04:56:27 hifi-berry volumio[853]: info: CorePlayQueue::getTrack 1 Nov 22 04:56:27 hifi-berry volumio[853]: info: Prefetching next song Nov 22 04:56:27 hifi-berry volumio[853]: info: DOING PREFETCH IN MPD Nov 22 04:56:27 hifi-berry volumio[853]: verbose: ControllerMpd::sendMpdCommand add "USB/TOSHIBA_EXT/Ambient-Electronic/bvdub/Departing In Descent/bvdub & James Bernard - Departing In Descent - 02 Stares in Sapphires.flac" Nov 22 04:56:27 hifi-berry volumio[853]: info: Nov 22 04:56:27 hifi-berry volumio[853]: ---------------------------- MPD announces system playlist update Nov 22 04:56:27 hifi-berry volumio[853]: info: Ignoring MPD Status Update Nov 22 04:56:27 hifi-berry volumio[853]: info: sendMpdCommand add "USB/TOSHIBA_EXT/Ambient-Electronic/bvdub/Departing In Descent/bvdub & James Bernard - Departing In Descent - 02 Stares in Sapphires.flac" took 2 milliseconds Nov 22 04:56:27 hifi-berry volumio[853]: verbose: ControllerMpd::sendMpdCommand consume 1 Nov 22 04:56:27 hifi-berry volumio[853]: info: Nov 22 04:56:27 hifi-berry volumio[853]: ---------------------------- MPD announces system playlist update Nov 22 04:56:27 hifi-berry volumio[853]: info: Ignoring MPD Status Update Nov 22 04:56:27 hifi-berry volumio[853]: info: Nov 22 04:56:27 hifi-berry volumio[853]: ---------------------------- MPD announces system playlist update Nov 22 04:56:27 hifi-berry volumio[853]: info: Ignoring MPD Status Update Nov 22 04:56:27 hifi-berry volumio[853]: info: Nov 22 04:56:27 hifi-berry volumio[853]: ---------------------------- MPD announces state update: options Nov 22 04:56:27 hifi-berry volumio[853]: info: ------------------------------ 8ms Nov 22 04:56:27 hifi-berry volumio[853]: info: sendMpdCommand consume 1 took 6 milliseconds Nov 22 04:56:27 hifi-berry volumio[853]: info: ControllerMpd::getState Nov 22 04:56:27 hifi-berry volumio[853]: verbose: ControllerMpd::sendMpdCommand status Nov 22 04:56:27 hifi-berry volumio[853]: info: ------------------------------ 6ms Nov 22 04:56:27 hifi-berry volumio[853]: info: ------------------------------ 5ms Nov 22 04:56:27 hifi-berry volumio[853]: info: Nov 22 04:56:27 hifi-berry volumio[853]: ---------------------------- MPD announces state update: options Nov 22 04:56:27 hifi-berry volumio[853]: info: ControllerMpd::getState Nov 22 04:56:27 hifi-berry volumio[853]: verbose: ControllerMpd::sendMpdCommand status Nov 22 04:56:27 hifi-berry volumio[853]: info: Nov 22 04:56:27 hifi-berry volumio[853]: ---------------------------- MPD announces state update: options Nov 22 04:56:27 hifi-berry volumio[853]: info: ControllerMpd::getState Nov 22 04:56:27 hifi-berry volumio[853]: verbose: ControllerMpd::sendMpdCommand status Nov 22 04:56:27 hifi-berry volumio[853]: info: sendMpdCommand status took 5 milliseconds Nov 22 04:56:27 hifi-berry volumio[853]: info: sendMpdCommand status took 4 milliseconds Nov 22 04:56:27 hifi-berry volumio[853]: info: sendMpdCommand status took 6 milliseconds Nov 22 04:56:27 hifi-berry volumio[853]: verbose: ControllerMpd::parseState Nov 22 04:56:27 hifi-berry volumio[853]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 04:56:27 hifi-berry volumio[853]: verbose: ControllerMpd::parseState Nov 22 04:56:27 hifi-berry volumio[853]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 04:56:27 hifi-berry volumio[853]: verbose: ControllerMpd::parseState Nov 22 04:56:27 hifi-berry volumio[853]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 04:56:27 hifi-berry volumio[853]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 22 04:56:27 hifi-berry volumio[853]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 22 04:56:27 hifi-berry volumio[853]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 22 04:56:27 hifi-berry volumio[853]: verbose: ControllerMpd::parseTrackInfo Nov 22 04:56:27 hifi-berry volumio[853]: verbose: ControllerMpd::parseTrackInfo Nov 22 04:56:27 hifi-berry volumio[853]: verbose: ControllerMpd::parseTrackInfo Nov 22 04:56:27 hifi-berry volumio[853]: info: ControllerMpd::pushState Nov 22 04:56:27 hifi-berry volumio[853]: info: CoreCommandRouter::servicePushState Nov 22 04:56:27 hifi-berry volumio[853]: info: CoreStateMachine::pushState Nov 22 04:56:27 hifi-berry volumio[853]: info: CorePlayQueue::getTrack 0 Nov 22 04:56:27 hifi-berry volumio[853]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 22 04:56:27 hifi-berry volumio[853]: info: CoreCommandRouter::volumioPushState Nov 22 04:56:27 hifi-berry volumio[853]: info: CorePlayQueue::getTrack 0 Nov 22 04:56:27 hifi-berry volumio[853]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Preludes in Parting","artist":"bvdub & James Bernard","album":"Departing In Descent","uri":"USB/TOSHIBA_EXT/Ambient-Electronic/bvdub/Departing In Descent/bvdub & James Bernard - Departing In Descent - 01 Preludes in Parting.flac","trackType":"flac"} Nov 22 04:56:27 hifi-berry volumio[853]: verbose: CURRENT POSITION 0 Nov 22 04:56:27 hifi-berry volumio[853]: info: CoreStateMachine::syncState stateService stop Nov 22 04:56:27 hifi-berry volumio[853]: info: CoreStateMachine::syncState currentStatus stop Nov 22 04:56:27 hifi-berry volumio[853]: info: CoreStateMachine::pushState Nov 22 04:56:27 hifi-berry volumio[853]: info: CorePlayQueue::getTrack 0 Nov 22 04:56:27 hifi-berry volumio[853]: info: CoreCommandRouter::volumioPushState Nov 22 04:56:27 hifi-berry volumio[853]: info: No code Nov 22 04:56:27 hifi-berry volumio[853]: info: CoreStateMachine::pushState Nov 22 04:56:27 hifi-berry volumio[853]: info: CorePlayQueue::getTrack 0 Nov 22 04:56:27 hifi-berry volumio[853]: info: CoreCommandRouter::volumioPushState Nov 22 04:56:27 hifi-berry volumio[853]: info: ControllerMpd::pushState Nov 22 04:56:27 hifi-berry volumio[853]: info: CoreCommandRouter::servicePushState Nov 22 04:56:27 hifi-berry volumio[853]: info: CoreStateMachine::pushState Nov 22 04:56:27 hifi-berry volumio[853]: info: CorePlayQueue::getTrack 0 Nov 22 04:56:27 hifi-berry volumio[853]: info: CoreCommandRouter::volumioPushState Nov 22 04:56:27 hifi-berry volumio[853]: info: CorePlayQueue::getTrack 0 Nov 22 04:56:27 hifi-berry volumio[853]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Preludes in Parting","artist":"bvdub & James Bernard","album":"Departing In Descent","uri":"USB/TOSHIBA_EXT/Ambient-Electronic/bvdub/Departing In Descent/bvdub & James Bernard - Departing In Descent - 01 Preludes in Parting.flac","trackType":"flac"} Nov 22 04:56:27 hifi-berry volumio[853]: verbose: CURRENT POSITION 0 Nov 22 04:56:27 hifi-berry volumio[853]: info: CoreStateMachine::syncState stateService stop Nov 22 04:56:27 hifi-berry volumio[853]: info: CoreStateMachine::syncState currentStatus stop Nov 22 04:56:27 hifi-berry volumio[853]: info: CoreStateMachine::pushState Nov 22 04:56:27 hifi-berry volumio[853]: info: CorePlayQueue::getTrack 0 Nov 22 04:56:27 hifi-berry volumio[853]: info: CoreCommandRouter::volumioPushState Nov 22 04:56:27 hifi-berry volumio[853]: info: No code Nov 22 04:56:27 hifi-berry volumio[853]: info: CoreStateMachine::pushState Nov 22 04:56:27 hifi-berry volumio[853]: info: CorePlayQueue::getTrack 0 Nov 22 04:56:27 hifi-berry volumio[853]: info: CoreCommandRouter::volumioPushState Nov 22 04:56:27 hifi-berry volumio[853]: info: ControllerMpd::pushState Nov 22 04:56:27 hifi-berry volumio[853]: info: CoreCommandRouter::servicePushState Nov 22 04:56:27 hifi-berry volumio[853]: info: CoreStateMachine::pushState Nov 22 04:56:27 hifi-berry volumio[853]: info: CorePlayQueue::getTrack 0 Nov 22 04:56:27 hifi-berry volumio[853]: info: CoreCommandRouter::volumioPushState Nov 22 04:56:27 hifi-berry volumio[853]: info: CorePlayQueue::getTrack 0 Nov 22 04:56:27 hifi-berry volumio[853]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Preludes in Parting","artist":"bvdub & James Bernard","album":"Departing In Descent","uri":"USB/TOSHIBA_EXT/Ambient-Electronic/bvdub/Departing In Descent/bvdub & James Bernard - Departing In Descent - 01 Preludes in Parting.flac","trackType":"flac"} Nov 22 04:56:27 hifi-berry volumio[853]: verbose: CURRENT POSITION 0 Nov 22 04:56:27 hifi-berry volumio[853]: info: CoreStateMachine::syncState stateService stop Nov 22 04:56:27 hifi-berry volumio[853]: info: CoreStateMachine::syncState currentStatus stop Nov 22 04:56:27 hifi-berry volumio[853]: info: CoreStateMachine::pushState Nov 22 04:56:27 hifi-berry volumio[853]: info: CorePlayQueue::getTrack 0 Nov 22 04:56:27 hifi-berry volumio[853]: info: CoreCommandRouter::volumioPushState Nov 22 04:56:27 hifi-berry volumio[853]: info: No code Nov 22 04:56:27 hifi-berry volumio[853]: info: CoreStateMachine::pushState Nov 22 04:56:27 hifi-berry volumio[853]: info: CorePlayQueue::getTrack 0 Nov 22 04:56:27 hifi-berry volumio[853]: info: CoreCommandRouter::volumioPushState Nov 22 04:56:27 hifi-berry volumio[853]: info: ------------------------------ 111ms Nov 22 04:56:27 hifi-berry volumio[853]: info: ------------------------------ 109ms Nov 22 04:56:27 hifi-berry volumio[853]: info: ------------------------------ 109ms Nov 22 04:56:29 hifi-berry sudo[1730]: pam_unix(sudo:session): session closed for user root Nov 22 04:56:29 hifi-berry wireless.js[1694]: Start wireless flow Nov 22 04:56:29 hifi-berry wireless.js[1694]: Stopped hotspot (if there).. Nov 22 04:56:29 hifi-berry sudo[1736]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Nov 22 04:56:29 hifi-berry sudo[1736]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 04:56:29 hifi-berry sudo[1736]: pam_unix(sudo:session): session closed for user root Nov 22 04:56:29 hifi-berry sudo[1738]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Nov 22 04:56:29 hifi-berry sudo[1738]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 04:56:29 hifi-berry volumio[853]: info: Discovery: Started advertising with name: Hifi-Berry Nov 22 04:56:29 hifi-berry sudo[1738]: pam_unix(sudo:session): session closed for user root Nov 22 04:56:29 hifi-berry wireless.js[1694]: DHCP IP Nov 22 04:56:29 hifi-berry wireless.js[1694]: Start ap Nov 22 04:56:29 hifi-berry wpa_supplicant[1741]: Successfully initialized wpa_supplicant Nov 22 04:56:29 hifi-berry sudo[1742]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Nov 22 04:56:29 hifi-berry sudo[1742]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 04:56:29 hifi-berry dhcpcd[1743]: main: control_open: Connection refused Nov 22 04:56:29 hifi-berry dhcpcd[1743]: dev: loaded udev Nov 22 04:56:29 hifi-berry kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Nov 22 04:56:29 hifi-berry dhcpcd-run-hooks[1761]: wlan0: starting wpa_supplicant Nov 22 04:56:30 hifi-berry dhcpcd-run-hooks[1766]: wlan0: failed to start wpa_supplicant Nov 22 04:56:30 hifi-berry dhcpcd-run-hooks[1767]: wlan0: Successfully initialized wpa_supplicant nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Ma Nov 22 04:56:30 hifi-berry dhcpcd[1743]: wlan0: connected to Access Point `' Nov 22 04:56:30 hifi-berry dhcpcd[1743]: no interfaces have a carrier Nov 22 04:56:30 hifi-berry dhcpcd[1743]: forked to background, child pid 1778 Nov 22 04:56:30 hifi-berry dhcpcd[1778]: eth0: waiting for carrier Nov 22 04:56:30 hifi-berry dhcpcd[1778]: wlan0: waiting for carrier Nov 22 04:56:30 hifi-berry sudo[1742]: pam_unix(sudo:session): session closed for user root Nov 22 04:56:30 hifi-berry wireless.js[1694]: trying... Nov 22 04:56:30 hifi-berry sudo[1780]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 22 04:56:30 hifi-berry sudo[1780]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 04:56:30 hifi-berry sudo[1780]: pam_unix(sudo:session): session closed for user root Nov 22 04:56:31 hifi-berry volumio[853]: info: CoreStateMachine::startPlaybackTimer Nov 22 04:56:31 hifi-berry volumio[853]: info: CorePlayQueue::getTrack 1 Nov 22 04:56:31 hifi-berry wireless.js[1694]: trying... Nov 22 04:56:31 hifi-berry sudo[1783]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 22 04:56:31 hifi-berry sudo[1783]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 04:56:31 hifi-berry sudo[1783]: pam_unix(sudo:session): session closed for user root Nov 22 04:56:32 hifi-berry wpa_supplicant[1765]: wlan0: Trying to associate with SSID 'Solar 7' Nov 22 04:56:32 hifi-berry wireless.js[1694]: trying... Nov 22 04:56:32 hifi-berry sudo[1786]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 22 04:56:32 hifi-berry sudo[1786]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 04:56:32 hifi-berry sudo[1786]: pam_unix(sudo:session): session closed for user root Nov 22 04:56:33 hifi-berry wireless.js[1694]: trying... Nov 22 04:56:33 hifi-berry sudo[1789]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 22 04:56:33 hifi-berry sudo[1789]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 04:56:33 hifi-berry sudo[1789]: pam_unix(sudo:session): session closed for user root Nov 22 04:56:34 hifi-berry wireless.js[1694]: trying... Nov 22 04:56:34 hifi-berry sudo[1792]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 22 04:56:34 hifi-berry sudo[1792]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 04:56:34 hifi-berry sudo[1792]: pam_unix(sudo:session): session closed for user root Nov 22 04:56:35 hifi-berry wireless.js[1694]: trying... Nov 22 04:56:35 hifi-berry sudo[1795]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 22 04:56:35 hifi-berry sudo[1795]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 04:56:35 hifi-berry sudo[1795]: pam_unix(sudo:session): session closed for user root Nov 22 04:56:36 hifi-berry dhcpcd[1778]: wlan0: carrier acquired Nov 22 04:56:36 hifi-berry dhcpcd[1778]: wlan0: connected to Access Point `Solar 7' Nov 22 04:56:36 hifi-berry wpa_supplicant[1765]: wlan0: Associated with 5c:e9:31:0f:7f:e0 Nov 22 04:56:36 hifi-berry wpa_supplicant[1765]: wlan0: CTRL-EVENT-CONNECTED - Connection to 5c:e9:31:0f:7f:e0 completed [id=0 id_str=] Nov 22 04:56:36 hifi-berry wpa_supplicant[1765]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Nov 22 04:56:36 hifi-berry dhcpcd[1778]: DUID 00:01:00:01:29:40:cc:97:b8:27:eb:4a:3a:59 Nov 22 04:56:36 hifi-berry dhcpcd[1778]: wlan0: IAID eb:1f:6f:0c Nov 22 04:56:36 hifi-berry dhcpcd[1778]: wlan0: adding address fe80::df4d:2bdb:83ae:75e3 Nov 22 04:56:36 hifi-berry wpa_supplicant[1765]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=US Nov 22 04:56:36 hifi-berry dhcpcd[1778]: ipv6_addaddr1: Permission denied Nov 22 04:56:36 hifi-berry dhcpcd[1778]: wlan0: carrier lost Nov 22 04:56:36 hifi-berry dhcpcd[1778]: wlan0: carrier acquired Nov 22 04:56:36 hifi-berry dhcpcd[1778]: wlan0: IAID eb:1f:6f:0c Nov 22 04:56:36 hifi-berry wireless.js[1694]: trying... Nov 22 04:56:36 hifi-berry sudo[1810]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 22 04:56:36 hifi-berry sudo[1810]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 04:56:36 hifi-berry sudo[1810]: pam_unix(sudo:session): session closed for user root Nov 22 04:56:37 hifi-berry dhcpcd[1778]: wlan0: soliciting an IPv6 router Nov 22 04:56:37 hifi-berry dhcpcd[1778]: wlan0: soliciting a DHCP lease Nov 22 04:56:37 hifi-berry dhcpcd[1778]: wlan0: offered 192.168.68.61 from 192.168.68.1 Nov 22 04:56:37 hifi-berry wireless.js[1694]: trying... Nov 22 04:56:37 hifi-berry sudo[1813]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 22 04:56:37 hifi-berry sudo[1813]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 04:56:37 hifi-berry sudo[1813]: pam_unix(sudo:session): session closed for user root Nov 22 04:56:37 hifi-berry dhcpcd[1778]: wlan0: probing address 192.168.68.61/22 Nov 22 04:56:38 hifi-berry wireless.js[1694]: trying... Nov 22 04:56:38 hifi-berry sudo[1816]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 22 04:56:38 hifi-berry sudo[1816]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 04:56:38 hifi-berry sudo[1816]: pam_unix(sudo:session): session closed for user root Nov 22 04:56:39 hifi-berry wireless.js[1694]: trying... Nov 22 04:56:39 hifi-berry sudo[1819]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 22 04:56:39 hifi-berry sudo[1819]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 04:56:39 hifi-berry sudo[1819]: pam_unix(sudo:session): session closed for user root Nov 22 04:56:40 hifi-berry wireless.js[1694]: trying... Nov 22 04:56:40 hifi-berry sudo[1822]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 22 04:56:40 hifi-berry sudo[1822]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 04:56:40 hifi-berry sudo[1822]: pam_unix(sudo:session): session closed for user root Nov 22 04:56:41 hifi-berry wireless.js[1694]: trying... Nov 22 04:56:41 hifi-berry sudo[1825]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 22 04:56:41 hifi-berry sudo[1825]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 04:56:41 hifi-berry sudo[1825]: pam_unix(sudo:session): session closed for user root Nov 22 04:56:42 hifi-berry dhcpcd[1778]: wlan0: leased 192.168.68.61 for 7200 seconds Nov 22 04:56:42 hifi-berry avahi-daemon[506]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.68.61. Nov 22 04:56:42 hifi-berry avahi-daemon[506]: New relevant interface wlan0.IPv4 for mDNS. Nov 22 04:56:42 hifi-berry dhcpcd[1778]: wlan0: adding route to 192.168.68.0/22 Nov 22 04:56:42 hifi-berry avahi-daemon[506]: Registering new address record for 192.168.68.61 on wlan0.IPv4. Nov 22 04:56:42 hifi-berry dhcpcd[1778]: wlan0: adding default route via 192.168.68.1 Nov 22 04:56:42 hifi-berry wireless.js[1694]: trying... Nov 22 04:56:42 hifi-berry sudo[1852]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 22 04:56:42 hifi-berry sudo[1852]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 04:56:42 hifi-berry sudo[1852]: pam_unix(sudo:session): session closed for user root Nov 22 04:56:42 hifi-berry wireless.js[1694]: Connected to: ----Solar 7 Nov 22 04:56:42 hifi-berry wireless.js[1694]: ---- Nov 22 04:56:42 hifi-berry sudo[1855]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 22 04:56:42 hifi-berry sudo[1855]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 04:56:42 hifi-berry sudo[1855]: pam_unix(sudo:session): session closed for user root Nov 22 04:56:42 hifi-berry wireless.js[1694]: ... joined AP, wlan0 IPv4 is 192.168.68.61, ipV6 is undefined Nov 22 04:56:42 hifi-berry wireless.js[1694]: It's done! AP Nov 22 04:56:42 hifi-berry systemd[1]: Started Wireless Services. Nov 22 04:56:42 hifi-berry sudo[1691]: pam_unix(sudo:session): session closed for user root Nov 22 04:56:43 hifi-berry volumio[853]: info: Discovery: adding 3ffe94f1-9f16-492f-8a8b-17a0035e5416 Nov 22 04:56:43 hifi-berry volumio[853]: info: Discovery: Found device Hifi-Berry Nov 22 04:56:43 hifi-berry volumio[853]: info: CoreCommandRouter::volumioGetState Nov 22 04:56:43 hifi-berry volumio[853]: info: CorePlayQueue::getTrack 1 Nov 22 04:56:43 hifi-berry volumio[853]: info: Discovery: this is already registered, 3ffe94f1-9f16-492f-8a8b-17a0035e5416 Nov 22 04:56:43 hifi-berry volumio[853]: info: Discovery: Found device Hifi-Berry Nov 22 04:56:43 hifi-berry volumio[853]: info: CoreCommandRouter::volumioGetState Nov 22 04:56:43 hifi-berry volumio[853]: info: CorePlayQueue::getTrack 1 Nov 22 04:56:44 hifi-berry ntpd[673]: Listen normally on 3 wlan0 192.168.68.61:123 Nov 22 04:56:44 hifi-berry ntpd[673]: new interface(s) found: waking up resolver Nov 22 04:56:47 hifi-berry volumio[853]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection Nov 22 04:56:47 hifi-berry volumio[853]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo Nov 22 04:56:47 hifi-berry sudo[1861]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 22 04:56:47 hifi-berry sudo[1861]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 04:56:47 hifi-berry sudo[1861]: pam_unix(sudo:session): session closed for user root Nov 22 04:56:48 hifi-berry sudo[1864]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Nov 22 04:56:48 hifi-berry sudo[1864]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 04:56:48 hifi-berry sudo[1864]: pam_unix(sudo:session): session closed for user root Nov 22 04:56:52 hifi-berry volumio[853]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Nov 22 04:56:52 hifi-berry volumio[853]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Nov 22 04:56:52 hifi-berry volumio[853]: info: Refreshing Cached IP Addresses Nov 22 04:56:52 hifi-berry sudo[1869]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Nov 22 04:56:52 hifi-berry sudo[1869]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 04:56:52 hifi-berry sudo[1871]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 22 04:56:52 hifi-berry sudo[1871]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 04:56:52 hifi-berry sudo[1871]: pam_unix(sudo:session): session closed for user root Nov 22 04:56:53 hifi-berry sudo[1869]: pam_unix(sudo:session): session closed for user root Nov 22 04:56:53 hifi-berry volumio[853]: error: Cannot kill upmpdcli Error: Command failed: /usr/bin/sudo /usr/bin/killall upmpdcli Nov 22 04:56:53 hifi-berry volumio[853]: upmpdcli: no process found Nov 22 04:56:53 hifi-berry sudo[1874]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 22 04:56:53 hifi-berry sudo[1874]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 04:56:53 hifi-berry sudo[1874]: pam_unix(sudo:session): session closed for user root Nov 22 04:56:54 hifi-berry volumio[853]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Total Clients: 4 Nov 22 04:56:54 hifi-berry volumio[853]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 22 04:56:55 hifi-berry volumio[853]: info: CoreCommandRouter::volumioGetVisibleSources Nov 22 04:56:55 hifi-berry volumio[853]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 22 04:56:55 hifi-berry volumio[853]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 22 04:56:55 hifi-berry volumio[853]: info: CoreCommandRouter::volumioGetState Nov 22 04:56:55 hifi-berry volumio[853]: info: CorePlayQueue::getTrack 1 Nov 22 04:56:55 hifi-berry volumio[853]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Nov 22 04:56:55 hifi-berry volumio[853]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 22 04:56:55 hifi-berry volumio[853]: info: Received Get System Info Nov 22 04:56:55 hifi-berry volumio[853]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 22 04:56:55 hifi-berry volumio[853]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 22 04:56:55 hifi-berry volumio[853]: info: Discovery: Getting this device information Nov 22 04:56:55 hifi-berry volumio[853]: info: CoreCommandRouter::volumioGetState Nov 22 04:56:55 hifi-berry volumio[853]: info: CorePlayQueue::getTrack 1 Nov 22 04:56:55 hifi-berry volumio[853]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 22 04:56:55 hifi-berry volumio[853]: info: CoreCommandRouter::volumioGetState Nov 22 04:56:55 hifi-berry volumio[853]: info: CorePlayQueue::getTrack 1 Nov 22 04:56:55 hifi-berry volumio[853]: info: Listing playlists Nov 22 04:56:55 hifi-berry volumio[853]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Nov 22 04:56:55 hifi-berry volumio[853]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 22 04:56:55 hifi-berry volumio[853]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 22 04:56:55 hifi-berry volumio[853]: Error: certificate is not yet valid Nov 22 04:56:55 hifi-berry volumio[853]: at TLSSocket.onConnectSecure (_tls_wrap.js:1497:34) Nov 22 04:56:55 hifi-berry volumio[853]: at TLSSocket.emit (events.js:315:20) Nov 22 04:56:55 hifi-berry volumio[853]: at TLSSocket._finishInit (_tls_wrap.js:932:8) Nov 22 04:56:55 hifi-berry volumio[853]: at TLSWrap.ssl.onhandshakedone (_tls_wrap.js:706:12) { Nov 22 04:56:55 hifi-berry volumio[853]: code: 'CERT_NOT_YET_VALID' Nov 22 04:56:55 hifi-berry volumio[853]: } Nov 22 04:56:55 hifi-berry volumio[853]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 22 04:56:56 hifi-berry sudo[1893]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2022-11-22 04:55 Nov 22 04:56:56 hifi-berry sudo[1893]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="91e88ea6c5c4ec4f00aff63f7c3a2944b14ec939" VOLUMIO_FE_VERSION="c433755e569617abac52264d17ad61be303ef463" VOLUMIO_FE3_VERSION="2419ddd8e895c5bdc456962163c9dc7c0f187aa7" VOLUMIO_BE_VERSION="85acf7bbbceb3e3dec447156a39a88033f921210" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 30 Sep 2022 10:43:40 AM CEST" VOLUMIO_VERSION="3.378" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4bc817297375b72097a1fade15c6518b"