-- Logs begin at Thu 2019-02-14 10:11:58 UTC, end at Mon 2025-09-01 20:47:58 UTC. -- Sep 01 20:46:22 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 01 20:46:22 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 01 20:46:22 volumio volumio[1004]: info: Discovery: Getting this device information Sep 01 20:46:22 volumio volumio[1004]: info: CoreCommandRouter::volumioGetState Sep 01 20:46:22 volumio volumio[1004]: info: CorePlayQueue::getTrack 0 Sep 01 20:46:22 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 01 20:46:22 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 01 20:46:22 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 01 20:46:22 volumio volumio[1004]: info: Discovery: Getting this device information Sep 01 20:46:22 volumio volumio[1004]: info: CoreCommandRouter::volumioGetState Sep 01 20:46:22 volumio volumio[1004]: info: CorePlayQueue::getTrack 0 Sep 01 20:46:22 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 01 20:46:22 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 01 20:46:22 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 01 20:46:22 volumio volumio[1004]: info: Discovery: Getting this device information Sep 01 20:46:22 volumio volumio[1004]: info: CoreCommandRouter::volumioGetState Sep 01 20:46:22 volumio volumio[1004]: info: CorePlayQueue::getTrack 0 Sep 01 20:46:22 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 01 20:46:23 volumio volumio[1004]: verbose: New Socket.io Connection to 192.168.100.8 from 192.168.100.25 UA: Mozilla/5.0 (Linux; Android 10; ELE-L29 Build/HUAWEIELE-L29; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/139.0.7258.143 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Sep 01 20:46:23 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 01 20:46:23 volumio volumio[1004]: info: CoreCommandRouter::volumioGetVisibleSources Sep 01 20:46:23 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 20:46:23 volumio volumio[1004]: info: CoreCommandRouter::volumioGetState Sep 01 20:46:23 volumio volumio[1004]: info: CorePlayQueue::getTrack 0 Sep 01 20:46:23 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 01 20:46:23 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 01 20:46:23 volumio volumio[1004]: info: Received Get System Info Sep 01 20:46:23 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 01 20:46:23 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 01 20:46:23 volumio volumio[1004]: info: Discovery: Getting this device information Sep 01 20:46:23 volumio volumio[1004]: info: CoreCommandRouter::volumioGetState Sep 01 20:46:23 volumio volumio[1004]: info: CorePlayQueue::getTrack 0 Sep 01 20:46:23 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 01 20:46:23 volumio volumio[1004]: info: CoreCommandRouter::volumioGetState Sep 01 20:46:23 volumio volumio[1004]: info: CorePlayQueue::getTrack 0 Sep 01 20:46:23 volumio volumio[1004]: info: Listing playlists Sep 01 20:46:23 volumio volumio[1004]: verbose: New Socket.io Connection to 192.168.100.8:3000 from 192.168.100.25 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Sep 01 20:46:23 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Sep 01 20:46:23 volumio volumio[1004]: verbose: New Socket.io Connection to 192.168.100.8:3000 from 192.168.100.25 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Sep 01 20:46:27 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 01 20:46:27 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 01 20:46:27 volumio volumio[1004]: info: Discovery: Getting this device information Sep 01 20:46:27 volumio volumio[1004]: info: CoreCommandRouter::volumioGetState Sep 01 20:46:27 volumio volumio[1004]: info: CorePlayQueue::getTrack 0 Sep 01 20:46:27 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 01 20:46:28 volumio volumio[1004]: info: CoreCommandRouter::volumioGetQueue Sep 01 20:46:28 volumio volumio[1004]: info: CoreStateMachine::getQueue Sep 01 20:46:28 volumio volumio[1004]: info: CorePlayQueue::getQueue Sep 01 20:46:34 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Sep 01 20:46:34 volumio volumio[1004]: info: In handleBrowseUri, curUri=spotify Sep 01 20:46:34 volumio volumio[1004]: info: Preload queue cleared Sep 01 20:46:34 volumio volumio[1004]: info: Preload queue cleared Sep 01 20:46:34 volumio volumio[1004]: info: Preload queue cleared Sep 01 20:46:34 volumio volumio[1004]: info: Preload queue cleared Sep 01 20:46:45 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 01 20:46:45 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Sep 01 20:46:57 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 01 20:46:57 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 01 20:46:57 volumio volumio[1004]: info: Discovery: Getting this device information Sep 01 20:46:57 volumio volumio[1004]: info: CoreCommandRouter::volumioGetState Sep 01 20:46:57 volumio volumio[1004]: info: CorePlayQueue::getTrack 0 Sep 01 20:46:57 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 01 20:47:11 volumio volumio[1004]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/spop/volumio/buster/armhf Sep 01 20:47:13 volumio volumio[1004]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/spop/volumio/buster/armhf Sep 01 20:47:13 volumio go-librespot[1269]: time="2025-09-01T20:47:13Z" level=error msg="websocket connection errored" error="failed to get reader: failed to read frame header: EOF" Sep 01 20:47:13 volumio volumio[1004]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSpotify Sep 01 20:47:13 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 20:47:13 volumio sudo[5437]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop go-librespot-daemon.service Sep 01 20:47:13 volumio sudo[5437]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 20:47:13 volumio systemd[1]: Stopping go-librespot Daemon... Sep 01 20:47:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Sep 01 20:47:13 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Sep 01 20:47:13 volumio systemd[1]: Stopped go-librespot Daemon. Sep 01 20:47:13 volumio volumio[1004]: info: Connection to go-librespot Websocket closed Sep 01 20:47:13 volumio sudo[5437]: pam_unix(sudo:session): session closed for user root Sep 01 20:47:13 volumio volumio[1004]: info: Check plugin dependencies Sep 01 20:47:13 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 01 20:47:13 volumio volumio[1004]: info: Rename folder Sep 01 20:47:13 volumio volumio[1004]: info: Folder /tmp/downloaded_plugin.zip removed Sep 01 20:47:13 volumio volumio[1004]: info: Move to category Sep 01 20:47:15 volumio volumio[1004]: info: Checking if install.sh is present Sep 01 20:47:15 volumio volumio[1004]: info: Executing install.sh Sep 01 20:47:15 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 01 20:47:15 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 01 20:47:15 volumio volumio[1004]: info: Discovery: Getting this device information Sep 01 20:47:15 volumio volumio[1004]: info: CoreCommandRouter::volumioGetState Sep 01 20:47:15 volumio volumio[1004]: info: CorePlayQueue::getTrack 0 Sep 01 20:47:15 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 01 20:47:15 volumio sudo[5467]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/music_service/spop/install.sh Sep 01 20:47:15 volumio sudo[5467]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 20:47:15 volumio volumio[1004]: info: Installing Go-librespot Sep 01 20:47:15 volumio volumio[1004]: info: Checking old vollibrespot installs Sep 01 20:47:15 volumio systemd[1]: Reloading. Sep 01 20:47:15 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Sep 01 20:47:16 volumio systemd[1]: getty@tty1.service: Current command vanished from the unit file, execution of the command list won't be resumed. Sep 01 20:47:16 volumio systemd[1]: Reloading. Sep 01 20:47:16 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Sep 01 20:47:16 volumio volumio[1004]: info: Downloading daemon Sep 01 20:47:19 volumio volumio[1004]: info: Creating directories Sep 01 20:47:19 volumio volumio[1004]: info: Creating data directory Sep 01 20:47:19 volumio volumio[1004]: info: Creating Start Script Sep 01 20:47:19 volumio systemd[1]: Reloading. Sep 01 20:47:19 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Sep 01 20:47:19 volumio sudo[5467]: pam_unix(sudo:session): session closed for user root Sep 01 20:47:19 volumio volumio[1004]: Plugin install end detected on script Sep 01 20:47:19 volumio volumio[1004]: info: Install script completed Sep 01 20:47:19 volumio volumio[1004]: info: Done installing plugin. Sep 01 20:47:19 volumio volumio[1004]: info: Enabling plugin spop Sep 01 20:47:19 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 01 20:47:19 volumio volumio[1004]: info: [{"prettyName":"Spotify","name":"spop","category":"music_service","version":"4.2.2","icon":"fa-spotify","isManuallyInstalled":false,"enabled":true,"active":false},{"prettyName":"Audiophonics ON/OFF","name":"audiophonicsonoff","category":"system_controller","version":"1.0.4","icon":"fa fa-cogs","isManuallyInstalled":false,"enabled":true,"active":false},{"prettyName":"System information","name":"Systeminfo","category":"user_interface","version":"3.0.5","icon":"fa-info-circle","isManuallyInstalled":false,"enabled":true,"active":true}] Sep 01 20:47:19 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Sep 01 20:47:19 volumio volumio[1004]: info: Folder /tmp/plugins removed Sep 01 20:47:19 volumio volumio[1004]: info: Folder /tmp/downloaded_plugin.zip removed Sep 01 20:47:19 volumio volumio[1004]: info: Folder /data/temp removed Sep 01 20:47:27 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 01 20:47:27 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 01 20:47:27 volumio volumio[1004]: info: Discovery: Getting this device information Sep 01 20:47:27 volumio volumio[1004]: info: CoreCommandRouter::volumioGetState Sep 01 20:47:27 volumio volumio[1004]: info: CorePlayQueue::getTrack 0 Sep 01 20:47:27 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 01 20:47:32 volumio volumio[1004]: info: CoreCommandRouter::volumioPlay Sep 01 20:47:32 volumio volumio[1004]: info: CoreStateMachine::play index undefined Sep 01 20:47:32 volumio volumio[1004]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 20:47:32 volumio volumio[1004]: info: CorePlayQueue::getTrack 0 Sep 01 20:47:32 volumio volumio[1004]: info: CoreStateMachine::startPlaybackTimer Sep 01 20:47:32 volumio volumio[1004]: info: CorePlayQueue::getTrack 0 Sep 01 20:47:32 volumio volumio[1004]: info: [1756759652464] ControllerSpotify::clearAddPlayTrack Sep 01 20:47:32 volumio volumio[1004]: info: Sending Spotify command with payload to local API: /player/play Sep 01 20:47:32 volumio volumio[1004]: error: Failed to send command to Spotify local API: /player/play: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 20:47:36 volumio volumio[1004]: info: CoreCommandRouter::volumioPlay Sep 01 20:47:36 volumio volumio[1004]: info: CoreStateMachine::play index undefined Sep 01 20:47:36 volumio volumio[1004]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 20:47:36 volumio volumio[1004]: info: CorePlayQueue::getTrack 0 Sep 01 20:47:36 volumio volumio[1004]: info: CoreStateMachine::startPlaybackTimer Sep 01 20:47:36 volumio volumio[1004]: info: CorePlayQueue::getTrack 0 Sep 01 20:47:36 volumio volumio[1004]: info: [1756759656905] ControllerSpotify::clearAddPlayTrack Sep 01 20:47:36 volumio volumio[1004]: info: Sending Spotify command with payload to local API: /player/play Sep 01 20:47:36 volumio volumio[1004]: error: Failed to send command to Spotify local API: /player/play: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 20:47:37 volumio volumio[1004]: info: CoreCommandRouter::volumioPlay Sep 01 20:47:37 volumio volumio[1004]: info: CoreStateMachine::play index undefined Sep 01 20:47:37 volumio volumio[1004]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 20:47:37 volumio volumio[1004]: info: CorePlayQueue::getTrack 0 Sep 01 20:47:37 volumio volumio[1004]: info: CoreStateMachine::startPlaybackTimer Sep 01 20:47:37 volumio volumio[1004]: info: CorePlayQueue::getTrack 0 Sep 01 20:47:37 volumio volumio[1004]: info: [1756759657561] ControllerSpotify::clearAddPlayTrack Sep 01 20:47:37 volumio volumio[1004]: info: Sending Spotify command with payload to local API: /player/play Sep 01 20:47:37 volumio volumio[1004]: error: Failed to send command to Spotify local API: /player/play: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 20:47:37 volumio volumio[1004]: info: CoreCommandRouter::volumioPlay Sep 01 20:47:37 volumio volumio[1004]: info: CoreStateMachine::play index undefined Sep 01 20:47:37 volumio volumio[1004]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 20:47:37 volumio volumio[1004]: info: CorePlayQueue::getTrack 0 Sep 01 20:47:37 volumio volumio[1004]: info: CoreStateMachine::startPlaybackTimer Sep 01 20:47:37 volumio volumio[1004]: info: CorePlayQueue::getTrack 0 Sep 01 20:47:37 volumio volumio[1004]: info: [1756759657844] ControllerSpotify::clearAddPlayTrack Sep 01 20:47:37 volumio volumio[1004]: info: Sending Spotify command with payload to local API: /player/play Sep 01 20:47:37 volumio volumio[1004]: error: Failed to send command to Spotify local API: /player/play: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 20:47:46 volumio volumio[1004]: info: CoreCommandRouter::volumioPlay Sep 01 20:47:46 volumio volumio[1004]: info: CoreStateMachine::play index undefined Sep 01 20:47:46 volumio volumio[1004]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 20:47:46 volumio volumio[1004]: info: CorePlayQueue::getTrack 0 Sep 01 20:47:46 volumio volumio[1004]: info: CoreStateMachine::startPlaybackTimer Sep 01 20:47:46 volumio volumio[1004]: info: CorePlayQueue::getTrack 0 Sep 01 20:47:46 volumio volumio[1004]: info: [1756759666544] ControllerSpotify::clearAddPlayTrack Sep 01 20:47:46 volumio volumio[1004]: info: Sending Spotify command with payload to local API: /player/play Sep 01 20:47:46 volumio volumio[1004]: error: Failed to send command to Spotify local API: /player/play: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 20:47:57 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 01 20:47:57 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 01 20:47:57 volumio volumio[1004]: info: Discovery: Getting this device information Sep 01 20:47:57 volumio volumio[1004]: info: CoreCommandRouter::volumioGetState Sep 01 20:47:57 volumio volumio[1004]: info: CorePlayQueue::getTrack 0 Sep 01 20:47:57 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 01 20:47:58 volumio volumio[1004]: info: ___________ PLUGINS: Run onVolumioReboot Tasks ___________ Sep 01 20:47:58 volumio volumio[1004]: info: PLUGIN onReboot : networkfs Sep 01 20:47:58 volumio volumio[1004]: info: PLUGIN onReboot : audiophonicsonoff Sep 01 20:47:58 volumio volumio[1004]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 20:47:58 volumio volumio[1004]: TypeError: Cannot read property 'writeSync' of undefined Sep 01 20:47:58 volumio volumio[1004]: at ControllerAudiophonicsOnOff.onVolumioReboot (/data/plugins/system_controller/audiophonicsonoff/index.js:35:25) Sep 01 20:47:58 volumio volumio[1004]: at PluginManager.onVolumioRebootPlugin (/volumio/app/pluginmanager.js:684:30) Sep 01 20:47:58 volumio volumio[1004]: at HashMap. (/volumio/app/pluginmanager.js:668:31) Sep 01 20:47:58 volumio volumio[1004]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Sep 01 20:47:58 volumio volumio[1004]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Sep 01 20:47:58 volumio volumio[1004]: at PluginManager.onVolumioReboot (/volumio/app/pluginmanager.js:666:20) Sep 01 20:47:58 volumio volumio[1004]: at CoreCommandRouter.reboot (/volumio/app/index.js:1350:22) Sep 01 20:47:58 volumio volumio[1004]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:870:33) Sep 01 20:47:58 volumio volumio[1004]: at Socket.emit (events.js:315:20) Sep 01 20:47:58 volumio volumio[1004]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Sep 01 20:47:58 volumio volumio[1004]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Sep 01 20:47:58 volumio volumio[1004]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 20:47:58 volumio sudo[5649]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-01 20:46 Sep 01 20:47:58 volumio sudo[5649]: 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="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"