-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Thu 2026-10-15 23:09:40 CEST. -- Oct 15 23:08:00 volumiopizero volumio[866]: info: Applying required configuration parameters for plugin volusonic Oct 15 23:08:00 volumiopizero volumio[866]: info: Loading plugin "pirateaudio"... Oct 15 23:08:00 volumiopizero volumio[866]: info: Applying required configuration parameters for plugin pirateaudio Oct 15 23:08:00 volumiopizero volumio[866]: info: Loading i18n strings for locale cs Oct 15 23:08:00 volumiopizero volumio[866]: info: Plugin plexamp has duplicated i18n key SAVE. It is ignored. Oct 15 23:08:00 volumiopizero volumio[866]: info: Plugin volusonic has duplicated i18n key PLUGIN_CONFIGURATION. It is ignored. Oct 15 23:08:00 volumiopizero volumio[866]: info: Plugin volusonic has duplicated i18n key PLUGIN_CRED_DESC. It is ignored. Oct 15 23:08:00 volumiopizero volumio[866]: info: Plugin volusonic has duplicated i18n key PLUGIN_OPTS_DESC. It is ignored. Oct 15 23:08:00 volumiopizero volumio[866]: info: Plugin volusonic has duplicated i18n key SAVE_CREDS. It is ignored. Oct 15 23:08:00 volumiopizero volumio[866]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Oct 15 23:08:00 volumiopizero volumio[866]: info: Plugin volusonic has duplicated i18n key TIME_OUT_DOC. It is ignored. Oct 15 23:08:00 volumiopizero volumio[866]: info: Plugin volusonic has duplicated i18n key TIME_OUT. It is ignored. Oct 15 23:08:00 volumiopizero volumio[866]: info: Plugin volusonic has duplicated i18n key CON_SUCCESS. It is ignored. Oct 15 23:08:00 volumiopizero volumio[866]: info: Plugin volusonic has duplicated i18n key CON_OK. It is ignored. Oct 15 23:08:00 volumiopizero volumio[866]: info: Plugin volusonic has duplicated i18n key CON_FAILED. It is ignored. Oct 15 23:08:00 volumiopizero volumio[866]: info: Plugin volusonic has duplicated i18n key CON_BAD_CREDS. It is ignored. Oct 15 23:08:00 volumiopizero volumio[866]: info: Plugin volusonic has duplicated i18n key CON_SERVER_UNREACHABLE. It is ignored. Oct 15 23:08:00 volumiopizero volumio[866]: info: Plugin volusonic has duplicated i18n key NEWEST_ALBUMS. It is ignored. Oct 15 23:08:00 volumiopizero volumio[866]: info: Plugin volusonic has duplicated i18n key GENRES. It is ignored. Oct 15 23:08:00 volumiopizero volumio[866]: info: Plugin volusonic has duplicated i18n key ARTISTS. It is ignored. Oct 15 23:08:00 volumiopizero volumio[866]: info: Plugin volusonic has duplicated i18n key PLAYLISTS. It is ignored. Oct 15 23:08:00 volumiopizero volumio[866]: info: Plugin volusonic has duplicated i18n key START_RADIO. It is ignored. Oct 15 23:08:00 volumiopizero volumio[866]: info: Plugin volusonic has duplicated i18n key TOP_SONGS. It is ignored. Oct 15 23:08:00 volumiopizero volumio[866]: info: Plugin volusonic has duplicated i18n key ALBUMS. It is ignored. Oct 15 23:08:00 volumiopizero volumio[866]: info: Plugin volusonic has duplicated i18n key SIMILAR_ARTISTS. It is ignored. Oct 15 23:08:00 volumiopizero volumio[866]: info: Plugin volusonic has duplicated i18n key TRACKS. It is ignored. Oct 15 23:08:00 volumiopizero volumio[866]: info: Plugin volusonic has duplicated i18n key SAVED. It is ignored. Oct 15 23:08:00 volumiopizero volumio[866]: info: Plugin volusonic has duplicated i18n key POP_ERROR. It is ignored. Oct 15 23:08:00 volumiopizero volumio[866]: Updating browse sources language Oct 15 23:08:00 volumiopizero volumio[866]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 15 23:08:01 volumiopizero volumio[866]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 23:08:01 volumiopizero volumio[866]: info: CoreCommandRouter::initPlayerControls Oct 15 23:08:01 volumiopizero volumio[866]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 23:08:01 volumiopizero volumio[866]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 23:08:01 volumiopizero volumio[866]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 23:08:01 volumiopizero volumio[866]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 23:08:01 volumiopizero volumio[866]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 23:08:01 volumiopizero volumio[866]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 23:08:01 volumiopizero volumio[866]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 23:08:01 volumiopizero volumio[866]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 23:08:01 volumiopizero volumio[866]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 23:08:01 volumiopizero volumio[866]: Express server listening on port 3000 Oct 15 23:08:01 volumiopizero volumio[866]: [Metrics] WebUI: 17s 237.25ms Oct 15 23:08:01 volumiopizero volumio[866]: info: CoreStateMachine::resetVolumioState Oct 15 23:08:01 volumiopizero volumio[866]: info: CoreStateMachine::getcurrentVolume Oct 15 23:08:01 volumiopizero volumio[866]: info: CoreCommandRouter::volumioRetrievevolume Oct 15 23:08:01 volumiopizero volumio[866]: info: Volumio Network Manager: Network status updated: 2 Oct 15 23:08:01 volumiopizero sudo[1070]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 15 23:08:01 volumiopizero sudo[1070]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 15 23:08:01 volumiopizero sudo[1072]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 15 23:08:01 volumiopizero sudo[1072]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 15 23:08:01 volumiopizero sudo[1072]: pam_unix(sudo:session): session closed for user root Oct 15 23:08:01 volumiopizero sudo[1070]: pam_unix(sudo:session): session closed for user root Oct 15 23:08:01 volumiopizero volumio[866]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 1 Oct 15 23:08:01 volumiopizero volumio[866]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 2 Oct 15 23:08:01 volumiopizero volumio[866]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 3 Oct 15 23:08:01 volumiopizero volumio[866]: info: CoreCommandRouter::volumioGetState Oct 15 23:08:01 volumiopizero volumio[866]: info: CorePlayQueue::getTrack 0 Oct 15 23:08:01 volumiopizero volumio[866]: info: CoreStateMachine::pushState Oct 15 23:08:01 volumiopizero volumio[866]: info: CorePlayQueue::getTrack 0 Oct 15 23:08:01 volumiopizero volumio[866]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 15 23:08:01 volumiopizero volumio[866]: info: CoreCommandRouter::volumioPushState Oct 15 23:08:01 volumiopizero volumio[866]: info: CoreStateMachine::updateTrackBlock Oct 15 23:08:01 volumiopizero volumio[866]: info: CorePlayQueue::getTrackBlock Oct 15 23:08:01 volumiopizero volumio[866]: info: CoreCommandRouter::volumioRetrievevolume Oct 15 23:08:01 volumiopizero volumio[866]: info: Reloading queue from file Oct 15 23:08:01 volumiopizero volumio[866]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 23:08:01 volumiopizero volumio[866]: info: CoreStateMachine::setRepeat false single undefined Oct 15 23:08:01 volumiopizero volumio[866]: info: CoreStateMachine::pushState Oct 15 23:08:01 volumiopizero volumio[866]: info: CorePlayQueue::getTrack 0 Oct 15 23:08:01 volumiopizero volumio[866]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 15 23:08:01 volumiopizero volumio[866]: info: CoreCommandRouter::volumioPushState Oct 15 23:08:01 volumiopizero volumio[866]: info: CoreStateMachine::setRandom false Oct 15 23:08:01 volumiopizero volumio[866]: info: CoreStateMachine::pushState Oct 15 23:08:01 volumiopizero volumio[866]: info: CorePlayQueue::getTrack 0 Oct 15 23:08:01 volumiopizero volumio[866]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 15 23:08:01 volumiopizero volumio[866]: info: CoreCommandRouter::volumioPushState Oct 15 23:08:01 volumiopizero volumio[866]: info: Listing playlists Oct 15 23:08:01 volumiopizero volumio[866]: info: Listing playlists Oct 15 23:08:01 volumiopizero volumio[866]: info: CoreCommandRouter::volumioGetState Oct 15 23:08:01 volumiopizero volumio[866]: info: CorePlayQueue::getTrack 0 Oct 15 23:08:01 volumiopizero volumio[866]: info: CoreCommandRouter::volumioGetQueue Oct 15 23:08:01 volumiopizero volumio[866]: info: CoreStateMachine::getQueue Oct 15 23:08:01 volumiopizero volumio[866]: info: CorePlayQueue::getQueue Oct 15 23:08:01 volumiopizero volumio[866]: info: CoreStateMachine::pushState Oct 15 23:08:01 volumiopizero volumio[866]: info: CorePlayQueue::getTrack 0 Oct 15 23:08:01 volumiopizero volumio[866]: info: CoreCommandRouter::volumioPushState Oct 15 23:08:01 volumiopizero volumio[866]: info: Setting Device type: Raspberry PI Oct 15 23:08:01 volumiopizero volumio[866]: info: Completed loading Core Plugins Oct 15 23:08:01 volumiopizero volumio[866]: info: Preparing to generate the ALSA configuration file Oct 15 23:08:01 volumiopizero volumio[866]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Oct 15 23:08:01 volumiopizero volumio[866]: info: USB Boot Capable - System SBC Revision found in cpuinfo: 902120 Oct 15 23:08:01 volumiopizero volumio[866]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Oct 15 23:08:01 volumiopizero volumio[866]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Oct 15 23:08:01 volumiopizero volumio[866]: info: Reading ALSA contributions from plugins. Oct 15 23:08:01 volumiopizero sudo[1098]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Oct 15 23:08:01 volumiopizero sudo[1098]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 15 23:08:01 volumiopizero volumio[866]: info: Discovery: adding 0cc9a581-5f8f-46a0-9083-dfcb19856c64 Oct 15 23:08:01 volumiopizero volumio[866]: info: Discovery: Found device VolumioPIZero Oct 15 23:08:01 volumiopizero volumio[866]: info: CoreCommandRouter::volumioGetState Oct 15 23:08:01 volumiopizero volumio[866]: info: CorePlayQueue::getTrack 0 Oct 15 23:08:01 volumiopizero volumio[866]: info: Asound.conf file unchanged, so no further update is needed Oct 15 23:08:02 volumiopizero volumio[866]: info: Output device has changed, restarting MPD Oct 15 23:08:02 volumiopizero volumio[866]: info: Output device has changed, restarting Shairport Sync Oct 15 23:08:02 volumiopizero volumio[866]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 23:08:02 volumiopizero volumio[866]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 23:08:02 volumiopizero sudo[1111]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 15 23:08:02 volumiopizero sudo[1111]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 15 23:08:02 volumiopizero sudo[1113]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 15 23:08:02 volumiopizero sudo[1113]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 15 23:08:02 volumiopizero sudo[1111]: pam_unix(sudo:session): session closed for user root Oct 15 23:08:02 volumiopizero systemd[1]: Started UPnP Renderer front-end to MPD. Oct 15 23:08:02 volumiopizero sudo[1098]: pam_unix(sudo:session): session closed for user root Oct 15 23:08:02 volumiopizero volumio[866]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 15 23:08:02 volumiopizero volumio[866]: info: ___________ START PLUGINS ___________ Oct 15 23:08:02 volumiopizero volumio[866]: info: ControllerMpd::onStart: Initializing MPD Oct 15 23:08:02 volumiopizero volumio[866]: info: Creating MPD Configuration file Oct 15 23:08:02 volumiopizero sudo[1120]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Oct 15 23:08:02 volumiopizero volumio[866]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 23:08:02 volumiopizero volumio[866]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 15 23:08:02 volumiopizero volumio[866]: info: [1792098482243] CoreMusicLibrary::Adding element Média servery Oct 15 23:08:02 volumiopizero volumio[866]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 15 23:08:02 volumiopizero sudo[1123]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 15 23:08:02 volumiopizero sudo[1123]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 15 23:08:02 volumiopizero volumio[866]: info: UPNP Browser: Client initialized successfully Oct 15 23:08:02 volumiopizero sudo[1120]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 15 23:08:02 volumiopizero sudo[1123]: pam_unix(sudo:session): session closed for user root Oct 15 23:08:02 volumiopizero systemd[1]: Listening on mpd.socket. Oct 15 23:08:02 volumiopizero systemd[1]: Starting Music Player Daemon... Oct 15 23:08:02 volumiopizero volumio[866]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 23:08:02 volumiopizero volumio[866]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 23:08:02 volumiopizero sudo[1125]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 15 23:08:02 volumiopizero sudo[1125]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 15 23:08:02 volumiopizero systemd[1]: Started Volumio5 Onboarding Server. Oct 15 23:08:02 volumiopizero systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Oct 15 23:08:02 volumiopizero systemd[1]: mpd.service: Succeeded. Oct 15 23:08:02 volumiopizero sudo[1120]: pam_unix(sudo:session): session closed for user root Oct 15 23:08:02 volumiopizero systemd[1]: Stopped Music Player Daemon. Oct 15 23:08:02 volumiopizero systemd[1]: Starting Music Player Daemon... Oct 15 23:08:02 volumiopizero volumio[866]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 15 23:08:02 volumiopizero volumio[866]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 23:08:02 volumiopizero volumio[866]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 23:08:02 volumiopizero volumio[866]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 15 23:08:02 volumiopizero volumio[866]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 15 23:08:02 volumiopizero volumio[866]: info: [1792098482537] CoreMusicLibrary::Adding element Last_100 Oct 15 23:08:02 volumiopizero volumio[866]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 15 23:08:02 volumiopizero volumio[866]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 15 23:08:02 volumiopizero volumio[866]: info: [1792098482542] CoreMusicLibrary::Adding element Webradio Oct 15 23:08:02 volumiopizero volumio[866]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 15 23:08:02 volumiopizero sudo[1134]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 15 23:08:02 volumiopizero sudo[1134]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 15 23:08:02 volumiopizero volumio[866]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 15 23:08:02 volumiopizero volumio[866]: info: Initializing BBC Radios Oct 15 23:08:02 volumiopizero sudo[1134]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Oct 15 23:08:02 volumiopizero sudo[1134]: pam_unix(sudo:session): session closed for user root Oct 15 23:08:02 volumiopizero volumio[866]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory Oct 15 23:08:02 volumiopizero volumio[866]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 23:08:02 volumiopizero volumio[866]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 15 23:08:02 volumiopizero volumio[866]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 23:08:02 volumiopizero volumio[866]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 15 23:08:02 volumiopizero volumio[866]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 15 23:08:02 volumiopizero volumio[866]: info: Discovery: Getting this device information Oct 15 23:08:02 volumiopizero volumio[866]: info: CoreCommandRouter::volumioGetState Oct 15 23:08:02 volumiopizero volumio[866]: info: CorePlayQueue::getTrack 0 Oct 15 23:08:02 volumiopizero volumio[866]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 15 23:08:02 volumiopizero volumio[866]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 15 23:08:02 volumiopizero volumio[866]: info: [1792098482713] CoreMusicLibrary::Adding element Jellyfin Oct 15 23:08:02 volumiopizero volumio[866]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 15 23:08:02 volumiopizero volumio[866]: Cannot find translation for source Jellyfin Oct 15 23:08:02 volumiopizero volumio[866]: info: [jellyfin] Initialized plugin with device info: {"id":"0cc9a581-5f8f-46a0-9083-dfcb19856c64","host":"http://192.168.20.68","name":"VolumioPIZero","type":"device","serviceName":"Volumio","state":{"status":"stop","volume":"94","mute":false,"artist":"The Thrillseekers","track":"Synaesthesia (Fly Away)","albumart":"https://i.scdn.co/image/ab67616d0000b273a6b3752e552899eb4a9f316c"}} Oct 15 23:08:02 volumiopizero volumio[866]: info: Creating Spotify config file Oct 15 23:08:02 volumiopizero volumio[866]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 23:08:02 volumiopizero volumio[866]: info: Loading i18n strings for locale cs Oct 15 23:08:02 volumiopizero volumio[866]: info: Plugin plexamp has duplicated i18n key SAVE. It is ignored. Oct 15 23:08:02 volumiopizero volumio[866]: info: Plugin volusonic has duplicated i18n key PLUGIN_CONFIGURATION. It is ignored. Oct 15 23:08:02 volumiopizero volumio[866]: info: Plugin volusonic has duplicated i18n key PLUGIN_CRED_DESC. It is ignored. Oct 15 23:08:02 volumiopizero volumio[866]: info: Plugin volusonic has duplicated i18n key PLUGIN_OPTS_DESC. It is ignored. Oct 15 23:08:02 volumiopizero volumio[866]: info: Plugin volusonic has duplicated i18n key SAVE_CREDS. It is ignored. Oct 15 23:08:02 volumiopizero volumio[866]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Oct 15 23:08:02 volumiopizero volumio[866]: info: Plugin volusonic has duplicated i18n key TIME_OUT_DOC. It is ignored. Oct 15 23:08:03 volumiopizero volumio[866]: info: Plugin volusonic has duplicated i18n key TIME_OUT. It is ignored. Oct 15 23:08:03 volumiopizero volumio[866]: info: Plugin volusonic has duplicated i18n key CON_SUCCESS. It is ignored. Oct 15 23:08:03 volumiopizero volumio[866]: info: Plugin volusonic has duplicated i18n key CON_OK. It is ignored. Oct 15 23:08:03 volumiopizero volumio[866]: info: Plugin volusonic has duplicated i18n key CON_FAILED. It is ignored. Oct 15 23:08:03 volumiopizero volumio[866]: info: Plugin volusonic has duplicated i18n key CON_BAD_CREDS. It is ignored. Oct 15 23:08:03 volumiopizero volumio[866]: info: Plugin volusonic has duplicated i18n key CON_SERVER_UNREACHABLE. It is ignored. Oct 15 23:08:03 volumiopizero volumio[866]: info: Plugin volusonic has duplicated i18n key NEWEST_ALBUMS. It is ignored. Oct 15 23:08:03 volumiopizero volumio[866]: info: Plugin volusonic has duplicated i18n key GENRES. It is ignored. Oct 15 23:08:03 volumiopizero volumio[866]: info: Plugin volusonic has duplicated i18n key ARTISTS. It is ignored. Oct 15 23:08:03 volumiopizero volumio[866]: info: Plugin volusonic has duplicated i18n key PLAYLISTS. It is ignored. Oct 15 23:08:03 volumiopizero volumio[866]: info: Plugin volusonic has duplicated i18n key START_RADIO. It is ignored. Oct 15 23:08:03 volumiopizero volumio[866]: info: Plugin volusonic has duplicated i18n key TOP_SONGS. It is ignored. Oct 15 23:08:03 volumiopizero volumio[866]: info: Plugin volusonic has duplicated i18n key ALBUMS. It is ignored. Oct 15 23:08:03 volumiopizero volumio[866]: info: Plugin volusonic has duplicated i18n key SIMILAR_ARTISTS. It is ignored. Oct 15 23:08:03 volumiopizero volumio[866]: info: Plugin volusonic has duplicated i18n key TRACKS. It is ignored. Oct 15 23:08:03 volumiopizero volumio[866]: info: Plugin volusonic has duplicated i18n key SAVED. It is ignored. Oct 15 23:08:03 volumiopizero volumio[866]: info: Plugin volusonic has duplicated i18n key POP_ERROR. It is ignored. Oct 15 23:08:03 volumiopizero volumio[866]: Updating browse sources language Oct 15 23:08:03 volumiopizero volumio[866]: Cannot find translation for source Jellyfin Oct 15 23:08:03 volumiopizero volumio[866]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 15 23:08:03 volumiopizero volumio[866]: Cannot find translation for source Jellyfin Oct 15 23:08:03 volumiopizero volumio[866]: info: Loading i18n strings for locale cs Oct 15 23:08:03 volumiopizero volumio[866]: info: Plugin plexamp has duplicated i18n key SAVE. It is ignored. Oct 15 23:08:03 volumiopizero volumio[866]: info: Plugin volusonic has duplicated i18n key PLUGIN_CONFIGURATION. It is ignored. Oct 15 23:08:03 volumiopizero volumio[866]: info: Plugin volusonic has duplicated i18n key PLUGIN_CRED_DESC. It is ignored. Oct 15 23:08:03 volumiopizero volumio[866]: info: Plugin volusonic has duplicated i18n key PLUGIN_OPTS_DESC. It is ignored. Oct 15 23:08:03 volumiopizero volumio[866]: info: Plugin volusonic has duplicated i18n key SAVE_CREDS. It is ignored. Oct 15 23:08:03 volumiopizero volumio[866]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Oct 15 23:08:03 volumiopizero volumio[866]: info: Plugin volusonic has duplicated i18n key TIME_OUT_DOC. It is ignored. Oct 15 23:08:03 volumiopizero volumio[866]: info: Plugin volusonic has duplicated i18n key TIME_OUT. It is ignored. Oct 15 23:08:03 volumiopizero volumio[866]: info: Plugin volusonic has duplicated i18n key CON_SUCCESS. It is ignored. Oct 15 23:08:03 volumiopizero volumio[866]: info: Plugin volusonic has duplicated i18n key CON_OK. It is ignored. Oct 15 23:08:03 volumiopizero volumio[866]: info: Plugin volusonic has duplicated i18n key CON_FAILED. It is ignored. Oct 15 23:08:03 volumiopizero volumio[866]: info: Plugin volusonic has duplicated i18n key CON_BAD_CREDS. It is ignored. Oct 15 23:08:03 volumiopizero volumio[866]: info: Plugin volusonic has duplicated i18n key CON_SERVER_UNREACHABLE. It is ignored. Oct 15 23:08:03 volumiopizero volumio[866]: info: Plugin volusonic has duplicated i18n key NEWEST_ALBUMS. It is ignored. Oct 15 23:08:03 volumiopizero volumio[866]: info: Plugin volusonic has duplicated i18n key GENRES. It is ignored. Oct 15 23:08:03 volumiopizero volumio[866]: info: Plugin volusonic has duplicated i18n key ARTISTS. It is ignored. Oct 15 23:08:03 volumiopizero volumio[866]: info: Plugin volusonic has duplicated i18n key PLAYLISTS. It is ignored. Oct 15 23:08:03 volumiopizero volumio[866]: info: Plugin volusonic has duplicated i18n key START_RADIO. It is ignored. Oct 15 23:08:03 volumiopizero volumio[866]: info: Plugin volusonic has duplicated i18n key TOP_SONGS. It is ignored. Oct 15 23:08:03 volumiopizero volumio[866]: info: Plugin volusonic has duplicated i18n key ALBUMS. It is ignored. Oct 15 23:08:03 volumiopizero volumio[866]: info: Plugin volusonic has duplicated i18n key SIMILAR_ARTISTS. It is ignored. Oct 15 23:08:03 volumiopizero volumio[866]: info: Plugin volusonic has duplicated i18n key TRACKS. It is ignored. Oct 15 23:08:03 volumiopizero volumio[866]: info: Plugin volusonic has duplicated i18n key SAVED. It is ignored. Oct 15 23:08:03 volumiopizero volumio[866]: info: Plugin volusonic has duplicated i18n key POP_ERROR. It is ignored. Oct 15 23:08:03 volumiopizero volumio[866]: Updating browse sources language Oct 15 23:08:03 volumiopizero volumio[866]: Cannot find translation for source Jellyfin Oct 15 23:08:03 volumiopizero volumio[866]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 15 23:08:03 volumiopizero volumio[866]: Cannot find translation for source Jellyfin Oct 15 23:08:03 volumiopizero volumio[866]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 15 23:08:03 volumiopizero volumio[866]: info: [1792098483244] CoreMusicLibrary::Adding element Volusonic Oct 15 23:08:03 volumiopizero volumio[866]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 15 23:08:03 volumiopizero volumio[866]: Cannot find translation for source Jellyfin Oct 15 23:08:03 volumiopizero volumio[866]: Cannot find translation for source Volusonic Oct 15 23:08:03 volumiopizero volumio[866]: info: Volumio Calling Home Oct 15 23:08:03 volumiopizero sudo[1156]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start pirateaudio.service Oct 15 23:08:03 volumiopizero sudo[1156]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 15 23:08:03 volumiopizero volumio5-onboarding[1133]: time=2026-10-15T23:08:03.341+02:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z Oct 15 23:08:03 volumiopizero sudo[1156]: pam_unix(sudo:session): session closed for user root Oct 15 23:08:03 volumiopizero volumio[866]: info: PlexAmp::Plex initialised[object Object] Oct 15 23:08:03 volumiopizero volumio[866]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 15 23:08:03 volumiopizero volumio[866]: info: [1792098483442] CoreMusicLibrary::Adding element Plex Oct 15 23:08:03 volumiopizero volumio[866]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 15 23:08:03 volumiopizero volumio[866]: Cannot find translation for source Jellyfin Oct 15 23:08:03 volumiopizero volumio[866]: Cannot find translation for source Volusonic Oct 15 23:08:03 volumiopizero volumio[866]: Cannot find translation for source Plex Oct 15 23:08:03 volumiopizero volumio[866]: info: Pirate audio service (daemon) started Oct 15 23:08:03 volumiopizero volumio[866]: info: MPD Permissions set Oct 15 23:08:03 volumiopizero volumio[866]: info: MPD Permissions set Oct 15 23:08:03 volumiopizero volumio[866]: info: Upmpdcli Daemon Started Oct 15 23:08:03 volumiopizero volumio[866]: info: [jellyfin-poller] Polled http://213.29.79.22:8096: online Oct 15 23:08:03 volumiopizero volumio[866]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 4 Oct 15 23:08:03 volumiopizero volumio[866]: info: Spotify config file written Oct 15 23:08:03 volumiopizero sudo[1166]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Oct 15 23:08:03 volumiopizero sudo[1166]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 15 23:08:03 volumiopizero volumio[866]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Oct 15 23:08:03 volumiopizero volumio[866]: info: Volumio called home Oct 15 23:08:03 volumiopizero volumio[866]: info: No need to fix Spotify hosts Oct 15 23:08:03 volumiopizero volumio[866]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 23:08:03 volumiopizero volumio[866]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 23:08:03 volumiopizero volumio[866]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 23:08:03 volumiopizero volumio[866]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 23:08:03 volumiopizero volumio[866]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 23:08:03 volumiopizero volumio[866]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 23:08:03 volumiopizero systemd[1]: Started go-librespot Daemon. Oct 15 23:08:03 volumiopizero go-librespot[1177]: go-librespot daemon starting... Oct 15 23:08:03 volumiopizero volumio[866]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 23:08:03 volumiopizero volumio[866]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 23:08:03 volumiopizero volumio[866]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 23:08:03 volumiopizero volumio[866]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 23:08:03 volumiopizero volumio[866]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 23:08:03 volumiopizero volumio[866]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 23:08:03 volumiopizero volumio[866]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 23:08:03 volumiopizero sudo[1166]: pam_unix(sudo:session): session closed for user root Oct 15 23:08:03 volumiopizero volumio[866]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 23:08:03 volumiopizero volumio[866]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 23:08:03 volumiopizero volumio[866]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 23:08:03 volumiopizero volumio[866]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 23:08:04 volumiopizero volumio[866]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Oct 15 23:08:04 volumiopizero volumio[866]: info: Received Get System Info Oct 15 23:08:04 volumiopizero volumio[866]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 15 23:08:04 volumiopizero volumio[866]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 15 23:08:04 volumiopizero volumio[866]: info: Discovery: Getting this device information Oct 15 23:08:04 volumiopizero volumio[866]: info: CoreCommandRouter::volumioGetState Oct 15 23:08:04 volumiopizero volumio[866]: info: CorePlayQueue::getTrack 0 Oct 15 23:08:04 volumiopizero volumio[866]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 15 23:08:04 volumiopizero volumio5-onboarding[1133]: time=2026-10-15T23:08:04.039+02:00 level=INFO msg="system info for 515cf0bbe62e81931d39c7734ddadb12" deviceName=VolumioPIZero deviceVariant=volumio deviceModel= softwareVersion=3.914 Oct 15 23:08:04 volumiopizero volumio5-onboarding[1133]: time=2026-10-15T23:08:04.072+02:00 level=INFO msg="bootstrapping state" hasInternet=true Oct 15 23:08:04 volumiopizero volumio[866]: info: Received Get System Info Oct 15 23:08:04 volumiopizero volumio[866]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 15 23:08:04 volumiopizero volumio[866]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 15 23:08:04 volumiopizero volumio[866]: info: Discovery: Getting this device information Oct 15 23:08:04 volumiopizero volumio[866]: info: CoreCommandRouter::volumioGetState Oct 15 23:08:04 volumiopizero volumio[866]: info: CorePlayQueue::getTrack 0 Oct 15 23:08:04 volumiopizero volumio[866]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 15 23:08:04 volumiopizero volumio[866]: info: Starting Shairport Sync Oct 15 23:08:04 volumiopizero volumio[866]: info: Starting Shairport Sync Oct 15 23:08:04 volumiopizero volumio[866]: info: Starting Shairport Sync Oct 15 23:08:04 volumiopizero sudo[1190]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 15 23:08:04 volumiopizero sudo[1192]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 15 23:08:04 volumiopizero sudo[1192]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 15 23:08:04 volumiopizero sudo[1190]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 15 23:08:04 volumiopizero volumio[866]: info: New Spotify access tokenBQCk1jcQlR... Oct 15 23:08:04 volumiopizero volumio[866]: info: Spotify credentials grant success - running version from March 24, 2019 Oct 15 23:08:04 volumiopizero sudo[1199]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 15 23:08:04 volumiopizero systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 15 23:08:04 volumiopizero sudo[1199]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 15 23:08:04 volumiopizero systemd[1]: shairport-sync.service: Succeeded. Oct 15 23:08:04 volumiopizero systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 15 23:08:04 volumiopizero systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 15 23:08:04 volumiopizero sudo[1192]: pam_unix(sudo:session): session closed for user root Oct 15 23:08:04 volumiopizero sudo[1199]: pam_unix(sudo:session): session closed for user root Oct 15 23:08:04 volumiopizero volumio[866]: info: CoreCommandRouter::volumioGetState Oct 15 23:08:04 volumiopizero volumio[866]: info: CorePlayQueue::getTrack 0 Oct 15 23:08:04 volumiopizero sudo[1190]: pam_unix(sudo:session): session closed for user root Oct 15 23:08:04 volumiopizero volumio[866]: info: Shairport-Sync Started Oct 15 23:08:04 volumiopizero volumio[866]: Error adding Membership: Error: addMembership EINVAL Oct 15 23:08:04 volumiopizero volumio[866]: info: Shairport-Sync Started Oct 15 23:08:04 volumiopizero volumio[866]: info: Shairport-Sync Started Oct 15 23:08:04 volumiopizero volumio[866]: info: Spotify Successfully logged in Oct 15 23:08:04 volumiopizero volumio[866]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 15 23:08:04 volumiopizero volumio[866]: info: [1792098484770] CoreMusicLibrary::Adding element Spotify Oct 15 23:08:04 volumiopizero volumio[866]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 15 23:08:04 volumiopizero volumio[866]: Cannot find translation for source Jellyfin Oct 15 23:08:04 volumiopizero volumio[866]: Cannot find translation for source Volusonic Oct 15 23:08:04 volumiopizero volumio[866]: Cannot find translation for source Plex Oct 15 23:08:04 volumiopizero volumio[866]: Cannot find translation for source Spotify Oct 15 23:08:05 volumiopizero go-librespot[1177]: time="2026-10-15T23:08:05+02:00" level=info msg="running go-librespot 0.7.1" Oct 15 23:08:05 volumiopizero go-librespot[1177]: time="2026-10-15T23:08:05+02:00" level=debug msg="app state loaded" Oct 15 23:08:05 volumiopizero go-librespot[1177]: time="2026-10-15T23:08:05+02:00" level=debug msg="stored credentials not found" Oct 15 23:08:05 volumiopizero go-librespot[1177]: time="2026-10-15T23:08:05+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 15 23:08:05 volumiopizero volumio[866]: info: CoreCommandRouter::volumioGetState Oct 15 23:08:05 volumiopizero volumio[866]: info: CorePlayQueue::getTrack 0 Oct 15 23:08:05 volumiopizero go-librespot[1177]: time="2026-10-15T23:08:05+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 15 23:08:05 volumiopizero go-librespot[1177]: time="2026-10-15T23:08:05+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 15 23:08:05 volumiopizero go-librespot[1177]: time="2026-10-15T23:08:05+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 15 23:08:05 volumiopizero go-librespot[1177]: time="2026-10-15T23:08:05+02:00" level=info msg="zeroconf server listening on port 35319" Oct 15 23:08:05 volumiopizero go-librespot[1177]: time="2026-10-15T23:08:05+02:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Oct 15 23:08:06 volumiopizero volumio-remote-updater[486]: [2026-10-15 23:08:06] [connect] Successful connection Oct 15 23:08:06 volumiopizero volumio-remote-updater[486]: [2026-10-15 23:08:06] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1792098486 101 Oct 15 23:08:06 volumiopizero volumio[866]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 6 Oct 15 23:08:07 volumiopizero volumio[866]: info: go-librespot daemon successfully initialized Oct 15 23:08:07 volumiopizero mpd[1140]: Oct 15 23:08 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 15 23:08:08 volumiopizero systemd[1]: Started Music Player Daemon. Oct 15 23:08:08 volumiopizero volumio[866]: Upnp client error: Error: This socket has been ended by the other party Oct 15 23:08:08 volumiopizero sudo[1125]: pam_unix(sudo:session): session closed for user root Oct 15 23:08:08 volumiopizero sudo[1113]: pam_unix(sudo:session): session closed for user root Oct 15 23:08:08 volumiopizero volumio[866]: info: Completed starting Core Plugins Oct 15 23:08:08 volumiopizero volumio[866]: info: ------------------------------------------- Oct 15 23:08:08 volumiopizero volumio[866]: info: ----- MyVolumio plugins startup ---- Oct 15 23:08:08 volumiopizero volumio[866]: info: ------------------------------------------- Oct 15 23:08:08 volumiopizero volumio[866]: info: [MyVolumio PluginManager] Fetching plans data.... Oct 15 23:08:09 volumiopizero volumio[866]: error: MPD error: The expression evaluated to a falsy value: Oct 15 23:08:09 volumiopizero volumio[866]: assert.ok(self.idling) Oct 15 23:08:09 volumiopizero volumio[866]: error: The expression evaluated to a falsy value: Oct 15 23:08:09 volumiopizero volumio[866]: assert.ok(self.idling) Oct 15 23:08:09 volumiopizero volumio[866]: info: MPD running with PID1140 Oct 15 23:08:09 volumiopizero volumio[866]: ,establishing connection Oct 15 23:08:09 volumiopizero volumio[866]: error: updateQueue error: null Oct 15 23:08:09 volumiopizero volumio[866]: error: updateQueue error: null Oct 15 23:08:10 volumiopizero volumio[866]: info: Initializing connection to go-librespot Websocket Oct 15 23:08:10 volumiopizero go-librespot[1177]: time="2026-10-15T23:08:10+02:00" level=debug msg="new websocket client" Oct 15 23:08:10 volumiopizero volumio[866]: info: Connection to go-librespot Websocket established Oct 15 23:08:12 volumiopizero volumio[1109]: Generating RSA private key, 4096 bit long modulus (2 primes) Oct 15 23:08:13 volumiopizero volumio[866]: info: Getting Spotify volume Oct 15 23:08:13 volumiopizero volumio[866]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Oct 15 23:08:13 volumiopizero volumio[866]: info: CoreCommandRouter::volumioGetState Oct 15 23:08:13 volumiopizero volumio[866]: info: CorePlayQueue::getTrack 0 Oct 15 23:08:13 volumiopizero volumio[866]: info: Aligning Spotify Volume to Volumio Volume Oct 15 23:08:13 volumiopizero volumio[866]: info: CoreCommandRouter::volumioGetState Oct 15 23:08:13 volumiopizero volumio[866]: info: CorePlayQueue::getTrack 0 Oct 15 23:08:13 volumiopizero volumio[866]: info: Setting Spotify Volume from Volumio: 94 Oct 15 23:08:13 volumiopizero volumio[1109]: ....++++ Oct 15 23:08:14 volumiopizero volumio[866]: info: Sending Spotify command with payload to local API: /player/volume Oct 15 23:08:14 volumiopizero volumio[866]: error: Failed to send command to Spotify local API: /player/volume: Error: Bad Request Oct 15 23:08:15 volumiopizero systemd[1]: systemd-timedated.service: Succeeded. Oct 15 23:08:15 volumiopizero volumio[866]: info: CoreCommandRouter::volumioGetState Oct 15 23:08:15 volumiopizero volumio[866]: info: CorePlayQueue::getTrack 0 Oct 15 23:08:15 volumiopizero volumio[866]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Oct 15 23:08:19 volumiopizero volumio[866]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Oct 15 23:08:19 volumiopizero volumio[866]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Oct 15 23:08:19 volumiopizero volumio[866]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Oct 15 23:08:19 volumiopizero volumio[866]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Oct 15 23:08:19 volumiopizero volumio[866]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Oct 15 23:08:19 volumiopizero volumio[866]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Oct 15 23:08:19 volumiopizero volumio[866]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Oct 15 23:08:19 volumiopizero volumio[866]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Oct 15 23:08:19 volumiopizero volumio[866]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Oct 15 23:08:19 volumiopizero volumio[866]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Oct 15 23:08:19 volumiopizero volumio[866]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Oct 15 23:08:19 volumiopizero volumio[866]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Oct 15 23:08:19 volumiopizero volumio[866]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Oct 15 23:08:19 volumiopizero volumio[866]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Oct 15 23:08:19 volumiopizero volumio[866]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Oct 15 23:08:19 volumiopizero volumio[866]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Oct 15 23:08:19 volumiopizero volumio[866]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Oct 15 23:08:19 volumiopizero volumio[866]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Oct 15 23:08:19 volumiopizero volumio[866]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Oct 15 23:08:19 volumiopizero volumio[866]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Oct 15 23:08:19 volumiopizero volumio[866]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Oct 15 23:08:19 volumiopizero volumio[866]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Oct 15 23:08:19 volumiopizero volumio[866]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Oct 15 23:08:19 volumiopizero volumio[866]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Oct 15 23:08:19 volumiopizero volumio[866]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Oct 15 23:08:19 volumiopizero volumio[866]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Oct 15 23:08:19 volumiopizero volumio[866]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Oct 15 23:08:19 volumiopizero volumio[866]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Oct 15 23:08:19 volumiopizero volumio[866]: info: Adding plugin bluetooth to MyMusic Plugins Oct 15 23:08:19 volumiopizero volumio[866]: info: Adding plugin multiroom to MyMusic Plugins Oct 15 23:08:19 volumiopizero volumio[866]: info: Adding plugin metavolumio to MyMusic Plugins Oct 15 23:08:19 volumiopizero volumio[866]: info: Adding plugin cd_controller to MyMusic Plugins Oct 15 23:08:19 volumiopizero volumio[866]: info: Adding plugin qobuzconnect to MyMusic Plugins Oct 15 23:08:19 volumiopizero volumio[866]: info: Adding plugin smart_inputs to MyMusic Plugins Oct 15 23:08:19 volumiopizero volumio[866]: info: Adding plugin tidalconnect to MyMusic Plugins Oct 15 23:08:19 volumiopizero volumio[866]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Oct 15 23:08:21 volumiopizero volumio[866]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Oct 15 23:08:21 volumiopizero volumio[866]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Oct 15 23:08:21 volumiopizero volumio[866]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 23:08:21 volumiopizero volumio[866]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 23:08:21 volumiopizero volumio[866]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 23:08:21 volumiopizero volumio[866]: info: Starting MyVolumio Remote Streaming Endpoints Oct 15 23:08:21 volumiopizero volumio[866]: info: MyVolumio login type: Token Oct 15 23:08:21 volumiopizero volumio[866]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Oct 15 23:08:21 volumiopizero volumio[866]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Oct 15 23:08:23 volumiopizero volumio[1109]: .........................................................++++ Oct 15 23:08:23 volumiopizero volumio[1109]: e is 65537 (0x010001) Oct 15 23:08:23 volumiopizero volumio[1109]: writing RSA key Oct 15 23:08:23 volumiopizero volumio[866]: info: Starting Streaming Service Transparent Proxy Oct 15 23:08:23 volumiopizero volumio[866]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Oct 15 23:08:23 volumiopizero volumio[866]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Oct 15 23:08:23 volumiopizero volumio[866]: info: Streaming services startup Oct 15 23:08:23 volumiopizero volumio[866]: info: Starting Streaming Daemon Oct 15 23:08:23 volumiopizero volumio[866]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Oct 15 23:08:23 volumiopizero sudo[1278]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 15 23:08:23 volumiopizero sudo[1278]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 15 23:08:23 volumiopizero sudo[1278]: pam_unix(sudo:session): session closed for user root Oct 15 23:08:24 volumiopizero volumio[866]: error: Cannot start Volumio Streaming Daemon Oct 15 23:08:24 volumiopizero volumio[866]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 15 23:08:24 volumiopizero volumio[866]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 15 23:08:24 volumiopizero volumio[866]: <--- Last few GCs ---> Oct 15 23:08:24 volumiopizero volumio[866]: [866:0x3f64788] 42813 ms: Scavenge 98.2 (106.3) -> 98.0 (106.3) MB, 1.5 / 0.0 ms (average mu = 0.957, current mu = 0.873) allocation failure Oct 15 23:08:24 volumiopizero volumio[866]: [866:0x3f64788] 42926 ms: Scavenge 107.8 (115.9) -> 107.6 (115.9) MB, 1.0 / 0.0 ms (average mu = 0.957, current mu = 0.873) allocation failure Oct 15 23:08:24 volumiopizero volumio[866]: <--- JS stacktrace ---> Oct 15 23:08:24 volumiopizero volumio[866]: FATAL ERROR: MarkCompactCollector: young object promotion failed Allocation failed - JavaScript heap out of memory Oct 15 23:08:24 volumiopizero systemd[1]: volumio.service: Main process exited, code=killed, status=11/SEGV Oct 15 23:08:24 volumiopizero go-librespot[1177]: time="2026-10-15T23:08:24+02:00" level=error msg="websocket connection errored" error="failed to get reader: failed to read frame header: EOF" Oct 15 23:08:24 volumiopizero systemd[1]: volumio.service: Failed with result 'signal'. Oct 15 23:08:25 volumiopizero volumio5-onboarding[1133]: time=2026-10-15T23:08:24.949+02:00 level=ERROR msg="failed reading message" error="websocket: close 1006 (abnormal closure): unexpected EOF" Oct 15 23:08:25 volumiopizero volumio5-onboarding[1133]: time=2026-10-15T23:08:25.124+02:00 level=WARN msg="reconnection attempt failed" error="dial tcp 127.0.0.1:3000: connect: connection refused" Oct 15 23:08:25 volumiopizero volumio-remote-updater[486]: [2026-10-15 23:08:25] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Oct 15 23:08:25 volumiopizero systemd[1]: Started dynamicswap service. Oct 15 23:08:25 volumiopizero volumio-remote-updater[486]: [2026-10-15 23:08:25] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Oct 15 23:08:25 volumiopizero systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Oct 15 23:08:25 volumiopizero systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Oct 15 23:08:25 volumiopizero systemd[1]: Stopped Volumio Backend Module. Oct 15 23:08:25 volumiopizero systemd[1]: Started Volumio Backend Module. Oct 15 23:08:25 volumiopizero systemd[1]: dynamicswap.service: Succeeded. Oct 15 23:08:26 volumiopizero volumio5-onboarding[1133]: time=2026-10-15T23:08:26.126+02:00 level=WARN msg="reconnection attempt failed" error="dial tcp 127.0.0.1:3000: connect: connection refused" Oct 15 23:08:27 volumiopizero volumio5-onboarding[1133]: time=2026-10-15T23:08:27.128+02:00 level=WARN msg="reconnection attempt failed" error="dial tcp 127.0.0.1:3000: connect: connection refused" Oct 15 23:08:28 volumiopizero volumio[1311]: info: ------------------------------------------- Oct 15 23:08:28 volumiopizero volumio[1311]: info: ----- Volumio3 ---- Oct 15 23:08:28 volumiopizero volumio[1311]: info: ------------------------------------------- Oct 15 23:08:28 volumiopizero volumio[1311]: info: ----- System startup ---- Oct 15 23:08:28 volumiopizero volumio[1311]: info: ------------------------------------------- Oct 15 23:08:29 volumiopizero volumio[1311]: info: MYVOLUMIO Environment detected Oct 15 23:08:29 volumiopizero volumio[1311]: info: Plugin folders cleanup Oct 15 23:08:29 volumiopizero volumio[1311]: info: Scanning into folder /volumio/app/plugins/ Oct 15 23:08:29 volumiopizero volumio[1311]: info: Scanning category audio_interface Oct 15 23:08:29 volumiopizero volumio[1311]: info: Scanning category miscellanea Oct 15 23:08:29 volumiopizero volumio[1311]: info: Scanning category music_service Oct 15 23:08:29 volumiopizero volumio[1311]: info: Scanning category plugins.json Oct 15 23:08:29 volumiopizero volumio[1311]: info: Scanning category system_controller Oct 15 23:08:29 volumiopizero volumio[1311]: info: Scanning category user_interface Oct 15 23:08:29 volumiopizero volumio[1311]: info: Scanning into folder /data/plugins/ Oct 15 23:08:29 volumiopizero volumio[1311]: info: Scanning category audio_interface Oct 15 23:08:29 volumiopizero volumio[1311]: info: Scanning category music_service Oct 15 23:08:29 volumiopizero volumio[1311]: info: Scanning category system_hardware Oct 15 23:08:29 volumiopizero volumio[1311]: info: Plugin folders cleanup completed Oct 15 23:08:29 volumiopizero volumio[1311]: info: ------------------------------------------- Oct 15 23:08:29 volumiopizero volumio[1311]: info: ----- Core plugins startup ---- Oct 15 23:08:29 volumiopizero volumio[1311]: info: ------------------------------------------- Oct 15 23:08:29 volumiopizero volumio[1311]: info: Loading plugins from folder /volumio/app/plugins/ Oct 15 23:08:29 volumiopizero volumio[1311]: info: Adding plugin upnp to MyMusic Plugins Oct 15 23:08:29 volumiopizero volumio[1311]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 15 23:08:29 volumiopizero volumio[1311]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 15 23:08:29 volumiopizero volumio[1311]: info: Loading plugins from folder /data/plugins/ Oct 15 23:08:29 volumiopizero volumio[1311]: info: Loading plugin "system"... Oct 15 23:08:30 volumiopizero volumio[1311]: info: Loading plugin "appearance"... Oct 15 23:08:30 volumiopizero volumio-remote-updater[486]: [2026-10-15 23:08:30] [connect] Successful connection Oct 15 23:08:31 volumiopizero volumio[1311]: info: Loading plugin "network"... Oct 15 23:08:31 volumiopizero volumio[1311]: info: Refreshing Cached IP Addresses Oct 15 23:08:32 volumiopizero sudo[1375]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 15 23:08:32 volumiopizero sudo[1375]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 15 23:08:32 volumiopizero sudo[1377]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 15 23:08:32 volumiopizero sudo[1377]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 15 23:08:32 volumiopizero sudo[1377]: pam_unix(sudo:session): session closed for user root Oct 15 23:08:32 volumiopizero volumio[1311]: info: Loading plugin "services"... Oct 15 23:08:32 volumiopizero volumio[1311]: info: Loading plugin "volumio5onboarding"... Oct 15 23:08:32 volumiopizero sudo[1388]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 15 23:08:32 volumiopizero sudo[1388]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 15 23:08:32 volumiopizero volumio[1311]: info: Loading plugin "alsa_controller"... Oct 15 23:08:32 volumiopizero sudo[1375]: pam_unix(sudo:session): session closed for user root Oct 15 23:08:32 volumiopizero volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 15 23:08:32 volumiopizero volumio[1311]: info: Loading plugin "wizard"... Oct 15 23:08:32 volumiopizero volumio[1311]: info: Loading plugin "networkfs"... Oct 15 23:08:32 volumiopizero volumio[1311]: info: Cannot mount NAS AllDisk at system boot, trial number 1 ,retrying in 5 seconds Oct 15 23:08:32 volumiopizero volumio[1311]: info: Starting Udev Watcher for removable devices Oct 15 23:08:32 volumiopizero sudo[1403]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=admin2,password=Klaban326326,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=3.11 //192.168.20.190/alldisk/MP3 Spatně zařazené /mnt/NAS/MP3_Spatně_zařazené Oct 15 23:08:32 volumiopizero sudo[1403]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 15 23:08:32 volumiopizero volumio[1311]: info: Ignoring mount for partition: boot Oct 15 23:08:32 volumiopizero volumio[1311]: info: Ignoring mount for partition: volumio Oct 15 23:08:32 volumiopizero volumio[1311]: info: Ignoring mount for partition: volumio_data Oct 15 23:08:32 volumiopizero volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 15 23:08:32 volumiopizero volumio[1311]: info: Loading plugin "volumio_command_line_client"... Oct 15 23:08:32 volumiopizero kernel: CIFS: Attempting to mount //192.168.20.190/alldisk/MP3 Spatně zařazené Oct 15 23:08:32 volumiopizero volumio[1311]: info: Loading plugin "upnp"... Oct 15 23:08:32 volumiopizero volumio[1311]: info: [1792098512837] Starting Upmpd Daemon Oct 15 23:08:32 volumiopizero volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 15 23:08:32 volumiopizero volumio[1311]: info: Loading plugin "my_music"... Oct 15 23:08:32 volumiopizero volumio[1311]: info: Loading plugin "mpd"... Oct 15 23:08:32 volumiopizero sudo[1403]: pam_unix(sudo:session): session closed for user root Oct 15 23:08:33 volumiopizero sudo[1388]: pam_unix(sudo:session): session closed for user root Oct 15 23:08:33 volumiopizero volumio[1311]: info: Loading plugin "upnp_browser"... Oct 15 23:08:33 volumiopizero volumio[1311]: info: Starting UPNP Browser Oct 15 23:08:33 volumiopizero volumio[1311]: info: Loading plugin "alarm-clock"... Oct 15 23:08:34 volumiopizero volumio5-onboarding[1133]: failed to bootstrap state: failed to check for software update: could not check for updates: context deadline exceeded Oct 15 23:08:34 volumiopizero systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Oct 15 23:08:34 volumiopizero systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Oct 15 23:08:34 volumiopizero systemd[1]: volumio5-onboarding.service: Service RestartSec=100ms expired, scheduling restart. Oct 15 23:08:34 volumiopizero systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 1. Oct 15 23:08:34 volumiopizero systemd[1]: Stopped Volumio5 Onboarding Server. Oct 15 23:08:34 volumiopizero volumio[1311]: info: Loading plugin "airplay_emulation"... Oct 15 23:08:34 volumiopizero volumio[1311]: info: Starting Shairport Sync Oct 15 23:08:34 volumiopizero systemd[1]: Started Volumio5 Onboarding Server. Oct 15 23:08:34 volumiopizero volumio[1311]: info: Loading plugin "last_100"... Oct 15 23:08:34 volumiopizero volumio[1311]: info: Loading plugin "webradio"... Oct 15 23:08:34 volumiopizero volumio5-onboarding[1407]: time=2026-10-15T23:08:34.421+02:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z Oct 15 23:08:34 volumiopizero volumio[1311]: info: Loading plugin "i2s_dacs"... Oct 15 23:08:34 volumiopizero volumio[1311]: info: Loading plugin "volumiodiscovery"... Oct 15 23:08:34 volumiopizero volumio[1311]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 15 23:08:34 volumiopizero volumio[1311]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 15 23:08:34 volumiopizero volumio[1311]: *** WARNING *** For more information see Oct 15 23:08:34 volumiopizero volumio[1311]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 15 23:08:34 volumiopizero volumio[1311]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 15 23:08:34 volumiopizero node[1311]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 15 23:08:34 volumiopizero volumio[1311]: *** WARNING *** For more information see Oct 15 23:08:34 volumiopizero node[1311]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 15 23:08:34 volumiopizero node[1311]: *** WARNING *** For more information see Oct 15 23:08:34 volumiopizero node[1311]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 15 23:08:34 volumiopizero node[1311]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 15 23:08:34 volumiopizero node[1311]: *** WARNING *** For more information see Oct 15 23:08:34 volumiopizero volumio[1311]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 15 23:08:34 volumiopizero volumio[1311]: info: Discovery: Started advertising with name: VolumioPIZero Oct 15 23:08:34 volumiopizero volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 15 23:08:34 volumiopizero volumio[1311]: info: Loading plugin "jellyfin"... Oct 15 23:08:35 volumiopizero ntpd[692]: Soliciting pool server 194.182.84.172 Oct 15 23:08:36 volumiopizero volumio[1311]: info: Loading plugin "spop"... Oct 15 23:08:36 volumiopizero ntpd[692]: Soliciting pool server 46.28.110.153 Oct 15 23:08:38 volumiopizero volumio[1311]: info: Plugin ytcr is not enabled Oct 15 23:08:38 volumiopizero volumio[1311]: info: Loading plugin "outputs"... Oct 15 23:08:38 volumiopizero volumio[1311]: info: Loading plugin "albumart"... Oct 15 23:08:38 volumiopizero volumio[1311]: info: Plugin example_plugin is not enabled Oct 15 23:08:38 volumiopizero volumio[1311]: info: Loading plugin "inputs"... Oct 15 23:08:38 volumiopizero volumio[1311]: info: Loading plugin "updater_comm"... Oct 15 23:08:38 volumiopizero volumio[1311]: info: Plugin mpdemulation is not enabled Oct 15 23:08:38 volumiopizero volumio[1311]: info: Loading plugin "rest_api"... Oct 15 23:08:38 volumiopizero volumio[1311]: info: Loading plugin "websocket"... Oct 15 23:08:38 volumiopizero volumio[1311]: info: Starting Socket.io Server version 2.3.0 Oct 15 23:08:38 volumiopizero volumio[1311]: info: Loading plugin "mpdoutput"... Oct 15 23:08:38 volumiopizero volumio[1311]: Forking 3 albumart workers Oct 15 23:08:38 volumiopizero volumio[1311]: info: Plugin lms is not enabled Oct 15 23:08:38 volumiopizero volumio[1311]: info: Plugin minidlna is not enabled Oct 15 23:08:38 volumiopizero volumio[1311]: info: Loading plugin "plexamp"... Oct 15 23:08:39 volumiopizero ntpd[692]: Soliciting pool server 78.108.102.237 Oct 15 23:08:39 volumiopizero volumio[1311]: Starting albumart workers Oct 15 23:08:39 volumiopizero volumio[1311]: Starting albumart workers Oct 15 23:08:39 volumiopizero volumio[1311]: Starting albumart workers Oct 15 23:08:40 volumiopizero ntpd[692]: Soliciting pool server 80.211.220.41 Oct 15 23:08:40 volumiopizero ntpd[692]: Soliciting pool server 188.124.59.142 Oct 15 23:08:41 volumiopizero ntpd[692]: Soliciting pool server 81.27.192.20 Oct 15 23:08:41 volumiopizero ntpd[692]: Soliciting pool server 93.99.104.53 Oct 15 23:08:41 volumiopizero ntpd[692]: Soliciting pool server 195.113.144.201 Oct 15 23:08:41 volumiopizero volumio[1311]: info: Applying required configuration parameters for plugin plexamp Oct 15 23:08:41 volumiopizero volumio[1311]: info: Loading plugin "volusonic"... Oct 15 23:08:42 volumiopizero ntpd[692]: Soliciting pool server 185.3.157.249 Oct 15 23:08:42 volumiopizero ntpd[692]: Soliciting pool server 109.224.72.175 Oct 15 23:08:42 volumiopizero ntpd[692]: Soliciting pool server 109.224.72.175 Oct 15 23:08:43 volumiopizero ntpd[692]: receive: Unexpected origin timestamp 0xee7bbf5a.7256bf20 does not match aorg 0000000000.00000000 from server@93.99.104.53 xmt 0xee7bbf5b.1199f9d0 Oct 15 23:08:43 volumiopizero volumio-remote-updater[486]: [2026-10-15 23:08:43] [connect] Successful connection Oct 15 23:08:43 volumiopizero volumio[1311]: info: Applying required configuration parameters for plugin volusonic Oct 15 23:08:43 volumiopizero volumio[1311]: info: Loading plugin "pirateaudio"... Oct 15 23:08:44 volumiopizero ntpd[692]: Soliciting pool server 78.80.37.86 Oct 15 23:08:44 volumiopizero ntpd[692]: Soliciting pool server 2a03:4000:5:e51::123 Oct 15 23:08:44 volumiopizero volumio[1311]: info: Applying required configuration parameters for plugin pirateaudio Oct 15 23:08:44 volumiopizero volumio[1311]: info: Loading i18n strings for locale cs Oct 15 23:08:44 volumiopizero volumio[1311]: info: Plugin plexamp has duplicated i18n key SAVE. It is ignored. Oct 15 23:08:44 volumiopizero volumio[1311]: info: Plugin volusonic has duplicated i18n key PLUGIN_CONFIGURATION. It is ignored. Oct 15 23:08:44 volumiopizero volumio[1311]: info: Plugin volusonic has duplicated i18n key PLUGIN_CRED_DESC. It is ignored. Oct 15 23:08:44 volumiopizero volumio[1311]: info: Plugin volusonic has duplicated i18n key PLUGIN_OPTS_DESC. It is ignored. Oct 15 23:08:44 volumiopizero volumio[1311]: info: Plugin volusonic has duplicated i18n key SAVE_CREDS. It is ignored. Oct 15 23:08:44 volumiopizero volumio[1311]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Oct 15 23:08:44 volumiopizero volumio[1311]: info: Plugin volusonic has duplicated i18n key TIME_OUT_DOC. It is ignored. Oct 15 23:08:44 volumiopizero volumio[1311]: info: Plugin volusonic has duplicated i18n key TIME_OUT. It is ignored. Oct 15 23:08:44 volumiopizero volumio[1311]: info: Plugin volusonic has duplicated i18n key CON_SUCCESS. It is ignored. Oct 15 23:08:44 volumiopizero volumio[1311]: info: Plugin volusonic has duplicated i18n key CON_OK. It is ignored. Oct 15 23:08:44 volumiopizero volumio[1311]: info: Plugin volusonic has duplicated i18n key CON_FAILED. It is ignored. Oct 15 23:08:44 volumiopizero volumio[1311]: info: Plugin volusonic has duplicated i18n key CON_BAD_CREDS. It is ignored. Oct 15 23:08:44 volumiopizero volumio[1311]: info: Plugin volusonic has duplicated i18n key CON_SERVER_UNREACHABLE. It is ignored. Oct 15 23:08:44 volumiopizero volumio[1311]: info: Plugin volusonic has duplicated i18n key NEWEST_ALBUMS. It is ignored. Oct 15 23:08:44 volumiopizero volumio[1311]: info: Plugin volusonic has duplicated i18n key GENRES. It is ignored. Oct 15 23:08:44 volumiopizero volumio[1311]: info: Plugin volusonic has duplicated i18n key ARTISTS. It is ignored. Oct 15 23:08:44 volumiopizero volumio[1311]: info: Plugin volusonic has duplicated i18n key PLAYLISTS. It is ignored. Oct 15 23:08:44 volumiopizero volumio[1311]: info: Plugin volusonic has duplicated i18n key START_RADIO. It is ignored. Oct 15 23:08:44 volumiopizero volumio[1311]: info: Plugin volusonic has duplicated i18n key TOP_SONGS. It is ignored. Oct 15 23:08:44 volumiopizero volumio[1311]: info: Plugin volusonic has duplicated i18n key ALBUMS. It is ignored. Oct 15 23:08:44 volumiopizero volumio[1311]: info: Plugin volusonic has duplicated i18n key SIMILAR_ARTISTS. It is ignored. Oct 15 23:08:44 volumiopizero volumio[1311]: info: Plugin volusonic has duplicated i18n key TRACKS. It is ignored. Oct 15 23:08:44 volumiopizero volumio[1311]: info: Plugin volusonic has duplicated i18n key SAVED. It is ignored. Oct 15 23:08:44 volumiopizero volumio[1311]: info: Plugin volusonic has duplicated i18n key POP_ERROR. It is ignored. Oct 15 23:08:44 volumiopizero volumio[1311]: Updating browse sources language Oct 15 23:08:44 volumiopizero volumio[1311]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 15 23:08:44 volumiopizero volumio[1311]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 23:08:44 volumiopizero volumio[1311]: info: CoreCommandRouter::initPlayerControls Oct 15 23:08:44 volumiopizero volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 23:08:44 volumiopizero volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 23:08:44 volumiopizero volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 23:08:44 volumiopizero volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 23:08:44 volumiopizero volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 23:08:44 volumiopizero volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 23:08:44 volumiopizero volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 23:08:44 volumiopizero volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 23:08:44 volumiopizero volumio[1311]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 23:08:44 volumiopizero volumio[1311]: Express server listening on port 3000 Oct 15 23:08:44 volumiopizero volumio[1311]: [Metrics] WebUI: 16s 652.50ms Oct 15 23:08:44 volumiopizero volumio[1311]: info: CoreStateMachine::resetVolumioState Oct 15 23:08:44 volumiopizero volumio[1311]: info: CoreStateMachine::getcurrentVolume Oct 15 23:08:44 volumiopizero volumio[1311]: info: CoreCommandRouter::volumioRetrievevolume Oct 15 23:08:44 volumiopizero volumio[1311]: info: Cannot mount NAS AllDisk at system boot, trial number 2 ,retrying in 5 seconds Oct 15 23:08:44 volumiopizero volumio[1311]: info: Volumio Network Manager: Network status updated: 2 Oct 15 23:08:44 volumiopizero volumio[1311]: info: Cannot mount NAS MP3_Spatně_zařazené at system boot, trial number 1 ,retrying in 5 seconds Oct 15 23:08:44 volumiopizero sudo[1495]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 15 23:08:44 volumiopizero sudo[1493]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 15 23:08:44 volumiopizero sudo[1493]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 15 23:08:44 volumiopizero sudo[1495]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 15 23:08:44 volumiopizero sudo[1495]: pam_unix(sudo:session): session closed for user root Oct 15 23:08:44 volumiopizero sudo[1493]: pam_unix(sudo:session): session closed for user root Oct 15 23:08:44 volumiopizero volumio[1311]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 1 Oct 15 23:08:45 volumiopizero volumio[1311]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 15 23:08:45 volumiopizero ntpd[692]: Soliciting pool server 81.0.208.219 Oct 15 23:08:45 volumiopizero volumio[1311]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 3 Oct 15 23:08:45 volumiopizero volumio-remote-updater[486]: [2026-10-15 23:08:45] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1792098523 101 Oct 15 23:08:45 volumiopizero volumio[1311]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 4 Oct 15 23:08:45 volumiopizero volumio[1311]: info: CoreStateMachine::pushState Oct 15 23:08:45 volumiopizero volumio[1311]: info: CorePlayQueue::getTrack 0 Oct 15 23:08:45 volumiopizero volumio[1311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 15 23:08:45 volumiopizero volumio[1311]: info: CoreCommandRouter::volumioPushState Oct 15 23:08:45 volumiopizero volumio[1311]: info: CoreStateMachine::updateTrackBlock Oct 15 23:08:45 volumiopizero volumio[1311]: info: CorePlayQueue::getTrackBlock Oct 15 23:08:45 volumiopizero volumio[1311]: info: CoreCommandRouter::volumioRetrievevolume Oct 15 23:08:45 volumiopizero volumio5-onboarding[1407]: time=2026-10-15T23:08:45.146+02:00 level=WARN msg="server is not set, cannot notify peers" component=state Oct 15 23:08:45 volumiopizero volumio[1311]: info: CoreCommandRouter::volumioGetState Oct 15 23:08:45 volumiopizero volumio[1311]: info: CorePlayQueue::getTrack 0 Oct 15 23:08:45 volumiopizero volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 15 23:08:45 volumiopizero volumio[1311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 15 23:08:45 volumiopizero volumio[1311]: info: Discovery: Getting this device information Oct 15 23:08:45 volumiopizero volumio[1311]: info: CoreCommandRouter::volumioGetState Oct 15 23:08:45 volumiopizero volumio[1311]: info: CorePlayQueue::getTrack 0 Oct 15 23:08:45 volumiopizero volumio[1311]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 15 23:08:45 volumiopizero volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 15 23:08:45 volumiopizero volumio[1311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 15 23:08:45 volumiopizero volumio[1311]: info: Discovery: Getting this device information Oct 15 23:08:45 volumiopizero volumio[1311]: info: CoreCommandRouter::volumioGetState Oct 15 23:08:45 volumiopizero volumio[1311]: info: CorePlayQueue::getTrack 0 Oct 15 23:08:45 volumiopizero volumio[1311]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 15 23:08:45 volumiopizero volumio[1311]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 4 Oct 15 23:08:45 volumiopizero volumio[1311]: info: Received Get System Info Oct 15 23:08:45 volumiopizero volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 15 23:08:45 volumiopizero volumio[1311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 15 23:08:45 volumiopizero volumio[1311]: info: Discovery: Getting this device information Oct 15 23:08:45 volumiopizero volumio[1311]: info: CoreCommandRouter::volumioGetState Oct 15 23:08:45 volumiopizero volumio[1311]: info: CorePlayQueue::getTrack 0 Oct 15 23:08:45 volumiopizero volumio[1311]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 15 23:08:45 volumiopizero volumio5-onboarding[1407]: time=2026-10-15T23:08:45.257+02:00 level=INFO msg="system info for 515cf0bbe62e81931d39c7734ddadb12" deviceName=VolumioPIZero deviceVariant=volumio deviceModel= softwareVersion=3.914 Oct 15 23:08:45 volumiopizero volumio5-onboarding[1407]: time=2026-10-15T23:08:45.269+02:00 level=INFO msg="bootstrapping state" hasInternet=true Oct 15 23:08:45 volumiopizero volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 23:08:45 volumiopizero volumio[1311]: info: Reloading queue from file Oct 15 23:08:45 volumiopizero volumio[1311]: info: CoreStateMachine::pushState Oct 15 23:08:45 volumiopizero volumio[1311]: info: CorePlayQueue::getTrack 0 Oct 15 23:08:45 volumiopizero volumio[1311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 15 23:08:45 volumiopizero volumio[1311]: info: CoreCommandRouter::volumioPushState Oct 15 23:08:45 volumiopizero volumio[1311]: info: Setting Device type: Raspberry PI Oct 15 23:08:45 volumiopizero volumio[1311]: info: CoreStateMachine::setRepeat false single undefined Oct 15 23:08:45 volumiopizero volumio[1311]: info: CoreStateMachine::pushState Oct 15 23:08:45 volumiopizero volumio[1311]: info: CorePlayQueue::getTrack 0 Oct 15 23:08:45 volumiopizero volumio[1311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 15 23:08:45 volumiopizero volumio[1311]: info: CoreCommandRouter::volumioPushState Oct 15 23:08:45 volumiopizero volumio[1311]: info: CoreStateMachine::setRandom false Oct 15 23:08:45 volumiopizero volumio[1311]: info: CoreStateMachine::pushState Oct 15 23:08:45 volumiopizero volumio[1311]: info: CorePlayQueue::getTrack 0 Oct 15 23:08:45 volumiopizero volumio[1311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 15 23:08:45 volumiopizero volumio[1311]: info: CoreCommandRouter::volumioPushState Oct 15 23:08:45 volumiopizero volumio[1311]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Oct 15 23:08:45 volumiopizero volumio[1311]: info: USB Boot Capable - System SBC Revision found in cpuinfo: 902120 Oct 15 23:08:45 volumiopizero volumio[1311]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Oct 15 23:08:45 volumiopizero volumio[1311]: info: Listing playlists Oct 15 23:08:45 volumiopizero volumio[1311]: info: Listing playlists Oct 15 23:08:45 volumiopizero volumio[1311]: info: Received Get System Info Oct 15 23:08:45 volumiopizero volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 15 23:08:45 volumiopizero volumio[1311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 15 23:08:45 volumiopizero volumio[1311]: info: Discovery: Getting this device information Oct 15 23:08:45 volumiopizero volumio[1311]: info: CoreCommandRouter::volumioGetState Oct 15 23:08:45 volumiopizero volumio[1311]: info: CorePlayQueue::getTrack 0 Oct 15 23:08:45 volumiopizero volumio[1311]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 15 23:08:45 volumiopizero volumio[1311]: verbose: New Socket.io Connection to 192.168.20.68:3000 from 192.168.20.241 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Oct 15 23:08:45 volumiopizero volumio[1311]: info: Completed loading Core Plugins Oct 15 23:08:45 volumiopizero volumio[1311]: info: Preparing to generate the ALSA configuration file Oct 15 23:08:45 volumiopizero volumio[1311]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Oct 15 23:08:45 volumiopizero volumio[1311]: info: Reading ALSA contributions from plugins. Oct 15 23:08:45 volumiopizero volumio-remote-updater[486]: Test mode enabled Oct 15 23:08:45 volumiopizero volumio-remote-updater[486]: No alpha test mode Oct 15 23:08:45 volumiopizero volumio[1311]: info: Asound.conf file unchanged, so no further update is needed Oct 15 23:08:45 volumiopizero volumio[1311]: info: Output device has changed, restarting MPD Oct 15 23:08:45 volumiopizero sudo[1513]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Oct 15 23:08:45 volumiopizero sudo[1513]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 15 23:08:45 volumiopizero volumio[1311]: info: Output device has changed, restarting Shairport Sync Oct 15 23:08:45 volumiopizero volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 23:08:45 volumiopizero volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 23:08:45 volumiopizero sudo[1518]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 15 23:08:45 volumiopizero sudo[1518]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 15 23:08:45 volumiopizero sudo[1516]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 15 23:08:45 volumiopizero sudo[1516]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 15 23:08:45 volumiopizero sudo[1516]: pam_unix(sudo:session): session closed for user root Oct 15 23:08:45 volumiopizero sudo[1513]: pam_unix(sudo:session): session closed for user root Oct 15 23:08:45 volumiopizero volumio[1311]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 15 23:08:45 volumiopizero systemd[1]: Stopping Music Player Daemon... Oct 15 23:08:45 volumiopizero volumio[1311]: info: ___________ START PLUGINS ___________ Oct 15 23:08:45 volumiopizero volumio[1311]: info: ControllerMpd::onStart: Initializing MPD Oct 15 23:08:45 volumiopizero volumio[1311]: info: Creating MPD Configuration file Oct 15 23:08:45 volumiopizero sudo[1525]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Oct 15 23:08:45 volumiopizero sudo[1525]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 15 23:08:45 volumiopizero volumio[1311]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 23:08:45 volumiopizero volumio[1311]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 15 23:08:45 volumiopizero volumio[1311]: info: [1792098525782] CoreMusicLibrary::Adding element Média servery Oct 15 23:08:45 volumiopizero volumio[1311]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 15 23:08:45 volumiopizero sudo[1528]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 15 23:08:45 volumiopizero sudo[1528]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 15 23:08:45 volumiopizero sudo[1528]: pam_unix(sudo:session): session closed for user root Oct 15 23:08:45 volumiopizero volumio[1311]: info: UPNP Browser: Client initialized successfully Oct 15 23:08:45 volumiopizero sudo[1525]: pam_unix(sudo:session): session closed for user root Oct 15 23:08:45 volumiopizero volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 23:08:45 volumiopizero volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 23:08:45 volumiopizero sudo[1530]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 15 23:08:45 volumiopizero sudo[1530]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 15 23:08:45 volumiopizero volumio[1311]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 15 23:08:45 volumiopizero volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 23:08:45 volumiopizero volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 23:08:45 volumiopizero volumio[1311]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 15 23:08:45 volumiopizero volumio[1311]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 15 23:08:45 volumiopizero volumio[1311]: info: [1792098525992] CoreMusicLibrary::Adding element Last_100 Oct 15 23:08:45 volumiopizero volumio[1311]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 15 23:08:45 volumiopizero volumio[1311]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 15 23:08:45 volumiopizero volumio[1311]: info: [1792098525995] CoreMusicLibrary::Adding element Webradio Oct 15 23:08:45 volumiopizero volumio[1311]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 15 23:08:46 volumiopizero volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 15 23:08:46 volumiopizero volumio[1311]: info: Initializing BBC Radios Oct 15 23:08:46 volumiopizero volumio[1311]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory Oct 15 23:08:46 volumiopizero volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 23:08:46 volumiopizero volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 15 23:08:46 volumiopizero volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 23:08:46 volumiopizero volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 15 23:08:46 volumiopizero volumio[1311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 15 23:08:46 volumiopizero volumio[1311]: info: Discovery: Getting this device information Oct 15 23:08:46 volumiopizero volumio[1311]: info: CoreCommandRouter::volumioGetState Oct 15 23:08:46 volumiopizero volumio[1311]: info: CorePlayQueue::getTrack 0 Oct 15 23:08:46 volumiopizero volumio[1311]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 15 23:08:46 volumiopizero volumio[1311]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 15 23:08:46 volumiopizero volumio[1311]: info: [1792098526151] CoreMusicLibrary::Adding element Jellyfin Oct 15 23:08:46 volumiopizero volumio[1311]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 15 23:08:46 volumiopizero volumio[1311]: Cannot find translation for source Jellyfin Oct 15 23:08:46 volumiopizero volumio[1311]: info: [jellyfin] Initialized plugin with device info: {"id":"0cc9a581-5f8f-46a0-9083-dfcb19856c64","host":"http://192.168.20.68","name":"VolumioPIZero","type":"device","serviceName":"Volumio","state":{"status":"stop","volume":"94","mute":false,"artist":"The Thrillseekers","track":"Synaesthesia (Fly Away)","albumart":"https://i.scdn.co/image/ab67616d0000b273a6b3752e552899eb4a9f316c"}} Oct 15 23:08:46 volumiopizero volumio[1311]: info: Creating Spotify config file Oct 15 23:08:46 volumiopizero volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 23:08:47 volumiopizero volumio[1311]: info: Loading i18n strings for locale cs Oct 15 23:08:47 volumiopizero volumio[1311]: info: Plugin plexamp has duplicated i18n key SAVE. It is ignored. Oct 15 23:08:47 volumiopizero volumio[1311]: info: Plugin volusonic has duplicated i18n key PLUGIN_CONFIGURATION. It is ignored. Oct 15 23:08:47 volumiopizero volumio[1311]: info: Plugin volusonic has duplicated i18n key PLUGIN_CRED_DESC. It is ignored. Oct 15 23:08:47 volumiopizero volumio[1311]: info: Plugin volusonic has duplicated i18n key PLUGIN_OPTS_DESC. It is ignored. Oct 15 23:08:47 volumiopizero volumio[1311]: info: Plugin volusonic has duplicated i18n key SAVE_CREDS. It is ignored. Oct 15 23:08:47 volumiopizero volumio[1311]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Oct 15 23:08:47 volumiopizero volumio[1311]: info: Plugin volusonic has duplicated i18n key TIME_OUT_DOC. It is ignored. Oct 15 23:08:47 volumiopizero volumio[1311]: info: Plugin volusonic has duplicated i18n key TIME_OUT. It is ignored. Oct 15 23:08:47 volumiopizero volumio[1311]: info: Plugin volusonic has duplicated i18n key CON_SUCCESS. It is ignored. Oct 15 23:08:47 volumiopizero volumio[1311]: info: Plugin volusonic has duplicated i18n key CON_OK. It is ignored. Oct 15 23:08:47 volumiopizero volumio[1311]: info: Plugin volusonic has duplicated i18n key CON_FAILED. It is ignored. Oct 15 23:08:47 volumiopizero volumio[1311]: info: Plugin volusonic has duplicated i18n key CON_BAD_CREDS. It is ignored. Oct 15 23:08:47 volumiopizero volumio[1311]: info: Plugin volusonic has duplicated i18n key CON_SERVER_UNREACHABLE. It is ignored. Oct 15 23:08:47 volumiopizero volumio[1311]: info: Plugin volusonic has duplicated i18n key NEWEST_ALBUMS. It is ignored. Oct 15 23:08:47 volumiopizero volumio[1311]: info: Plugin volusonic has duplicated i18n key GENRES. It is ignored. Oct 15 23:08:47 volumiopizero volumio[1311]: info: Plugin volusonic has duplicated i18n key ARTISTS. It is ignored. Oct 15 23:08:47 volumiopizero volumio[1311]: info: Plugin volusonic has duplicated i18n key PLAYLISTS. It is ignored. Oct 15 23:08:47 volumiopizero volumio[1311]: info: Plugin volusonic has duplicated i18n key START_RADIO. It is ignored. Oct 15 23:08:47 volumiopizero volumio[1311]: info: Plugin volusonic has duplicated i18n key TOP_SONGS. It is ignored. Oct 15 23:08:47 volumiopizero volumio[1311]: info: Plugin volusonic has duplicated i18n key ALBUMS. It is ignored. Oct 15 23:08:47 volumiopizero volumio[1311]: info: Plugin volusonic has duplicated i18n key SIMILAR_ARTISTS. It is ignored. Oct 15 23:08:47 volumiopizero volumio[1311]: info: Plugin volusonic has duplicated i18n key TRACKS. It is ignored. Oct 15 23:08:47 volumiopizero volumio[1311]: info: Plugin volusonic has duplicated i18n key SAVED. It is ignored. Oct 15 23:08:47 volumiopizero volumio[1311]: info: Plugin volusonic has duplicated i18n key POP_ERROR. It is ignored. Oct 15 23:08:47 volumiopizero volumio[1311]: Updating browse sources language Oct 15 23:08:47 volumiopizero volumio[1311]: Cannot find translation for source Jellyfin Oct 15 23:08:47 volumiopizero volumio[1311]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 15 23:08:47 volumiopizero volumio[1311]: Cannot find translation for source Jellyfin Oct 15 23:08:47 volumiopizero volumio[1311]: info: Loading i18n strings for locale cs Oct 15 23:08:47 volumiopizero volumio[1311]: info: Plugin plexamp has duplicated i18n key SAVE. It is ignored. Oct 15 23:08:47 volumiopizero volumio[1311]: info: Plugin volusonic has duplicated i18n key PLUGIN_CONFIGURATION. It is ignored. Oct 15 23:08:47 volumiopizero volumio[1311]: info: Plugin volusonic has duplicated i18n key PLUGIN_CRED_DESC. It is ignored. Oct 15 23:08:47 volumiopizero volumio[1311]: info: Plugin volusonic has duplicated i18n key PLUGIN_OPTS_DESC. It is ignored. Oct 15 23:08:47 volumiopizero volumio[1311]: info: Plugin volusonic has duplicated i18n key SAVE_CREDS. It is ignored. Oct 15 23:08:47 volumiopizero volumio[1311]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Oct 15 23:08:47 volumiopizero volumio[1311]: info: Plugin volusonic has duplicated i18n key TIME_OUT_DOC. It is ignored. Oct 15 23:08:47 volumiopizero volumio[1311]: info: Plugin volusonic has duplicated i18n key TIME_OUT. It is ignored. Oct 15 23:08:47 volumiopizero volumio[1311]: info: Plugin volusonic has duplicated i18n key CON_SUCCESS. It is ignored. Oct 15 23:08:47 volumiopizero volumio[1311]: info: Plugin volusonic has duplicated i18n key CON_OK. It is ignored. Oct 15 23:08:47 volumiopizero volumio[1311]: info: Plugin volusonic has duplicated i18n key CON_FAILED. It is ignored. Oct 15 23:08:47 volumiopizero volumio[1311]: info: Plugin volusonic has duplicated i18n key CON_BAD_CREDS. It is ignored. Oct 15 23:08:47 volumiopizero volumio[1311]: info: Plugin volusonic has duplicated i18n key CON_SERVER_UNREACHABLE. It is ignored. Oct 15 23:08:47 volumiopizero volumio[1311]: info: Plugin volusonic has duplicated i18n key NEWEST_ALBUMS. It is ignored. Oct 15 23:08:47 volumiopizero volumio[1311]: info: Plugin volusonic has duplicated i18n key GENRES. It is ignored. Oct 15 23:08:47 volumiopizero volumio[1311]: info: Plugin volusonic has duplicated i18n key ARTISTS. It is ignored. Oct 15 23:08:47 volumiopizero volumio[1311]: info: Plugin volusonic has duplicated i18n key PLAYLISTS. It is ignored. Oct 15 23:08:47 volumiopizero volumio[1311]: info: Plugin volusonic has duplicated i18n key START_RADIO. It is ignored. Oct 15 23:08:47 volumiopizero volumio[1311]: info: Plugin volusonic has duplicated i18n key TOP_SONGS. It is ignored. Oct 15 23:08:47 volumiopizero volumio[1311]: info: Plugin volusonic has duplicated i18n key ALBUMS. It is ignored. Oct 15 23:08:47 volumiopizero volumio[1311]: info: Plugin volusonic has duplicated i18n key SIMILAR_ARTISTS. It is ignored. Oct 15 23:08:47 volumiopizero volumio[1311]: info: Plugin volusonic has duplicated i18n key TRACKS. It is ignored. Oct 15 23:08:47 volumiopizero volumio[1311]: info: Plugin volusonic has duplicated i18n key SAVED. It is ignored. Oct 15 23:08:47 volumiopizero volumio[1311]: info: Plugin volusonic has duplicated i18n key POP_ERROR. It is ignored. Oct 15 23:08:47 volumiopizero volumio[1311]: Updating browse sources language Oct 15 23:08:47 volumiopizero volumio[1311]: Cannot find translation for source Jellyfin Oct 15 23:08:47 volumiopizero volumio[1311]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 15 23:08:47 volumiopizero volumio[1311]: Cannot find translation for source Jellyfin Oct 15 23:08:47 volumiopizero volumio[1311]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 15 23:08:47 volumiopizero volumio[1311]: info: [1792098527896] CoreMusicLibrary::Adding element Volusonic Oct 15 23:08:47 volumiopizero volumio[1311]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 15 23:08:47 volumiopizero volumio[1311]: Cannot find translation for source Jellyfin Oct 15 23:08:47 volumiopizero volumio[1311]: Cannot find translation for source Volusonic Oct 15 23:08:47 volumiopizero volumio[1311]: info: Volumio Calling Home Oct 15 23:08:47 volumiopizero sudo[1552]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start pirateaudio.service Oct 15 23:08:47 volumiopizero sudo[1552]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 15 23:08:48 volumiopizero sudo[1552]: pam_unix(sudo:session): session closed for user root Oct 15 23:08:48 volumiopizero volumio[1311]: info: PlexAmp::Plex initialised[object Object] Oct 15 23:08:48 volumiopizero volumio[1311]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 15 23:08:48 volumiopizero volumio[1311]: info: [1792098528092] CoreMusicLibrary::Adding element Plex Oct 15 23:08:48 volumiopizero volumio[1311]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 15 23:08:48 volumiopizero volumio[1311]: Cannot find translation for source Jellyfin Oct 15 23:08:48 volumiopizero volumio[1311]: Cannot find translation for source Volusonic Oct 15 23:08:48 volumiopizero volumio[1311]: Cannot find translation for source Plex Oct 15 23:08:48 volumiopizero volumio[1311]: info: Discovery: adding 0cc9a581-5f8f-46a0-9083-dfcb19856c64 Oct 15 23:08:48 volumiopizero volumio[1311]: info: Discovery: Found device VolumioPIZero Oct 15 23:08:48 volumiopizero volumio[1311]: info: CoreCommandRouter::volumioGetState Oct 15 23:08:48 volumiopizero volumio[1311]: info: CorePlayQueue::getTrack 0 Oct 15 23:08:48 volumiopizero volumio[1311]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Oct 15 23:08:48 volumiopizero volumio[1311]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 15 23:08:48 volumiopizero volumio[1311]: info: Pirate audio service (daemon) started Oct 15 23:08:48 volumiopizero volumio[1311]: info: MPD Permissions set Oct 15 23:08:48 volumiopizero volumio[1311]: info: MPD Permissions set Oct 15 23:08:48 volumiopizero volumio[1311]: info: Upmpdcli Daemon Started Oct 15 23:08:48 volumiopizero volumio[1311]: info: CoreCommandRouter::volumioGetState Oct 15 23:08:48 volumiopizero volumio[1311]: info: CorePlayQueue::getTrack 0 Oct 15 23:08:48 volumiopizero volumio[1311]: info: [jellyfin-poller] Polled http://213.29.79.22:8096: online Oct 15 23:08:48 volumiopizero volumio[1311]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 15 23:08:48 volumiopizero volumio5-onboarding[1407]: time=2026-10-15T23:08:48.363+02:00 level=WARN msg="could not read OAuth data for music provider" component=volumio provider=tidal error="could not open plugin config file for \"tidal\": open /data/configuration/music_service/tidal/config.json: no such file or directory" Oct 15 23:08:48 volumiopizero volumio[1311]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 5 Oct 15 23:08:48 volumiopizero volumio[1311]: info: Spotify config file written Oct 15 23:08:48 volumiopizero volumio5-onboarding[1407]: time=2026-10-15T23:08:48.445+02:00 level=WARN msg="could not read OAuth data for music provider" component=volumio provider=qobuz error="could not open plugin config file for \"qobuz\": open /data/configuration/music_service/qobuz/config.json: no such file or directory" Oct 15 23:08:48 volumiopizero volumio5-onboarding[1407]: time=2026-10-15T23:08:48.446+02:00 level=WARN msg="could not read username/password data for music provider" component=volumio provider=hi_res_audio error="could not open plugin config file for \"hi_res_audio\": open /data/configuration/music_service/hi_res_audio/config.json: no such file or directory" Oct 15 23:08:48 volumiopizero volumio[1311]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Oct 15 23:08:48 volumiopizero volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 23:08:48 volumiopizero volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 23:08:48 volumiopizero sudo[1558]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Oct 15 23:08:48 volumiopizero sudo[1558]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 15 23:08:48 volumiopizero volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 23:08:48 volumiopizero systemd[1]: Stopping go-librespot Daemon... Oct 15 23:08:48 volumiopizero systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Oct 15 23:08:48 volumiopizero volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 23:08:48 volumiopizero volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 23:08:48 volumiopizero volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 23:08:48 volumiopizero volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 23:08:48 volumiopizero volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 23:08:48 volumiopizero volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 23:08:48 volumiopizero volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 23:08:48 volumiopizero systemd[1]: go-librespot-daemon.service: Succeeded. Oct 15 23:08:48 volumiopizero systemd[1]: Stopped go-librespot Daemon. Oct 15 23:08:48 volumiopizero volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 23:08:48 volumiopizero volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 23:08:48 volumiopizero volumio[1311]: info: No need to fix Spotify hosts Oct 15 23:08:48 volumiopizero systemd[1]: Started go-librespot Daemon. Oct 15 23:08:48 volumiopizero sudo[1558]: pam_unix(sudo:session): session closed for user root Oct 15 23:08:48 volumiopizero go-librespot[1566]: go-librespot daemon starting... Oct 15 23:08:48 volumiopizero volumio[1311]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 15 23:08:48 volumiopizero volumio[1311]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 15 23:08:48 volumiopizero go-librespot[1566]: time="2026-10-15T23:08:48+02:00" level=info msg="running go-librespot 0.7.1" Oct 15 23:08:48 volumiopizero go-librespot[1566]: time="2026-10-15T23:08:48+02:00" level=debug msg="app state loaded" Oct 15 23:08:48 volumiopizero go-librespot[1566]: time="2026-10-15T23:08:48+02:00" level=debug msg="stored credentials not found" Oct 15 23:08:48 volumiopizero volumio[1311]: info: Volumio called home Oct 15 23:08:48 volumiopizero volumio[1311]: info: Received Get System Info Oct 15 23:08:48 volumiopizero volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 15 23:08:48 volumiopizero volumio[1311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 15 23:08:48 volumiopizero volumio[1311]: info: Discovery: Getting this device information Oct 15 23:08:48 volumiopizero volumio[1311]: info: CoreCommandRouter::volumioGetState Oct 15 23:08:48 volumiopizero volumio[1311]: info: CorePlayQueue::getTrack 0 Oct 15 23:08:48 volumiopizero volumio[1311]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 15 23:08:48 volumiopizero volumio[1311]: info: Starting Shairport Sync Oct 15 23:08:48 volumiopizero volumio[1311]: info: Starting Shairport Sync Oct 15 23:08:48 volumiopizero go-librespot[1566]: time="2026-10-15T23:08:48+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 15 23:08:48 volumiopizero volumio[1311]: info: Starting Shairport Sync Oct 15 23:08:48 volumiopizero sudo[1579]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 15 23:08:48 volumiopizero sudo[1579]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 15 23:08:49 volumiopizero systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 15 23:08:49 volumiopizero volumio[1311]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 15 23:08:49 volumiopizero volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 15 23:08:49 volumiopizero volumio5-onboarding[1407]: time=2026-10-15T23:08:49.039+02:00 level=INFO msg="enabling local network discovery" Oct 15 23:08:49 volumiopizero sudo[1582]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 15 23:08:49 volumiopizero sudo[1585]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 15 23:08:49 volumiopizero sudo[1585]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 15 23:08:49 volumiopizero sudo[1582]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 15 23:08:49 volumiopizero volumio5-onboarding[1407]: time=2026-10-15T23:08:49.079+02:00 level=INFO msg="enabling BLE discovery" Oct 15 23:08:49 volumiopizero volumio[1311]: info: CoreCommandRouter::volumioGetState Oct 15 23:08:49 volumiopizero volumio[1311]: info: CorePlayQueue::getTrack 0 Oct 15 23:08:49 volumiopizero volumio[1311]: info: New Spotify access tokenBQA47wr0Vh... Oct 15 23:08:49 volumiopizero volumio[1311]: info: Spotify credentials grant success - running version from March 24, 2019 Oct 15 23:08:49 volumiopizero volumio[1311]: info: Spotify Successfully logged in Oct 15 23:08:49 volumiopizero volumio[1311]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 15 23:08:49 volumiopizero volumio[1311]: info: [1792098529510] CoreMusicLibrary::Adding element Spotify Oct 15 23:08:49 volumiopizero volumio[1311]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 15 23:08:49 volumiopizero volumio[1311]: Cannot find translation for source Jellyfin Oct 15 23:08:49 volumiopizero volumio[1311]: Cannot find translation for source Volusonic Oct 15 23:08:49 volumiopizero volumio[1311]: Cannot find translation for source Plex Oct 15 23:08:49 volumiopizero volumio[1311]: Cannot find translation for source Spotify Oct 15 23:08:49 volumiopizero bluetoothd[659]: src/adapter.c:store_adapter_info() Unable set contents for /usr/var/lib/bluetooth/2C:CF:67:EA:A4:9C/settings: (Failed to create file “/usr/var/lib/bluetooth/2C:CF:67:EA:A4:9C/settings.V2SKX3”: No such file or directory) Oct 15 23:08:49 volumiopizero systemd[1]: shairport-sync.service: Succeeded. Oct 15 23:08:49 volumiopizero systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 15 23:08:49 volumiopizero go-librespot[1566]: time="2026-10-15T23:08:49+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 15 23:08:49 volumiopizero go-librespot[1566]: time="2026-10-15T23:08:49+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 15 23:08:49 volumiopizero go-librespot[1566]: time="2026-10-15T23:08:49+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 15 23:08:49 volumiopizero go-librespot[1566]: time="2026-10-15T23:08:49+02:00" level=info msg="zeroconf server listening on port 34951" Oct 15 23:08:49 volumiopizero volumio[1311]: info: CoreCommandRouter::volumioGetState Oct 15 23:08:49 volumiopizero go-librespot[1566]: time="2026-10-15T23:08:49+02:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Oct 15 23:08:49 volumiopizero volumio[1311]: info: CorePlayQueue::getTrack 0 Oct 15 23:08:49 volumiopizero volumio[1311]: info: CoreCommandRouter::volumioGetQueue Oct 15 23:08:49 volumiopizero volumio[1311]: info: CoreStateMachine::getQueue Oct 15 23:08:49 volumiopizero volumio[1311]: info: CorePlayQueue::getQueue Oct 15 23:08:49 volumiopizero volumio[1311]: info: Cannot mount NAS AllDisk at system boot, trial number 3 ,retrying in 5 seconds Oct 15 23:08:49 volumiopizero systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 15 23:08:49 volumiopizero sudo[1582]: pam_unix(sudo:session): session closed for user root Oct 15 23:08:49 volumiopizero volumio[1311]: info: Shairport-Sync Started Oct 15 23:08:49 volumiopizero sudo[1585]: pam_unix(sudo:session): session closed for user root Oct 15 23:08:49 volumiopizero sudo[1579]: pam_unix(sudo:session): session closed for user root Oct 15 23:08:49 volumiopizero volumio[1311]: Error adding Membership: Error: addMembership EINVAL Oct 15 23:08:49 volumiopizero volumio[1311]: info: Shairport-Sync Started Oct 15 23:08:49 volumiopizero volumio[1311]: info: Shairport-Sync Started Oct 15 23:08:49 volumiopizero sudo[1606]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=admin2,password=Klaban326326,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=3.11 //192.168.20.190/alldisk/MP3 Spatně zařazené /mnt/NAS/MP3_Spatně_zařazené Oct 15 23:08:49 volumiopizero sudo[1606]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 15 23:08:49 volumiopizero kernel: CIFS: Attempting to mount //192.168.20.190/alldisk/MP3 Spatně zařazené Oct 15 23:08:50 volumiopizero sudo[1606]: pam_unix(sudo:session): session closed for user root Oct 15 23:08:50 volumiopizero volumio[1311]: info: Cannot mount NAS MP3_Spatně_zařazené at system boot, trial number 2 ,retrying in 5 seconds Oct 15 23:08:50 volumiopizero volumio5-onboarding[1407]: time=2026-10-15T23:08:50.364+02:00 level=INFO msg="service successfully established" component=discovery/localnet Oct 15 23:08:50 volumiopizero systemd[1]: mpd.service: Succeeded. Oct 15 23:08:50 volumiopizero systemd[1]: Stopped Music Player Daemon. Oct 15 23:08:50 volumiopizero systemd[1]: Starting Music Player Daemon... Oct 15 23:08:51 volumiopizero sudo[1613]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 15 23:08:51 volumiopizero sudo[1613]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 15 23:08:51 volumiopizero sudo[1613]: pam_unix(sudo:session): session closed for user root Oct 15 23:08:51 volumiopizero volumio[1311]: info: go-librespot daemon successfully initialized Oct 15 23:08:53 volumiopizero mpd[1615]: Oct 15 23:08 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 15 23:08:54 volumiopizero systemd[1]: Started Music Player Daemon. Oct 15 23:08:54 volumiopizero sudo[1518]: pam_unix(sudo:session): session closed for user root Oct 15 23:08:54 volumiopizero sudo[1530]: pam_unix(sudo:session): session closed for user root Oct 15 23:08:54 volumiopizero volumio[1311]: info: Completed starting Core Plugins Oct 15 23:08:54 volumiopizero volumio[1311]: info: ------------------------------------------- Oct 15 23:08:54 volumiopizero volumio[1311]: info: ----- MyVolumio plugins startup ---- Oct 15 23:08:54 volumiopizero volumio[1311]: info: ------------------------------------------- Oct 15 23:08:54 volumiopizero volumio[1311]: info: [MyVolumio PluginManager] Fetching plans data.... Oct 15 23:08:54 volumiopizero volumio[1311]: error: MPD error: The expression evaluated to a falsy value: Oct 15 23:08:54 volumiopizero volumio[1311]: assert.ok(self.idling) Oct 15 23:08:54 volumiopizero volumio[1311]: error: The expression evaluated to a falsy value: Oct 15 23:08:54 volumiopizero volumio[1311]: assert.ok(self.idling) Oct 15 23:08:54 volumiopizero volumio[1311]: error: updateQueue error: null Oct 15 23:08:54 volumiopizero volumio[1311]: info: MPD running with PID1615 Oct 15 23:08:54 volumiopizero volumio[1311]: ,establishing connection Oct 15 23:08:54 volumiopizero volumio[1311]: error: updateQueue error: null Oct 15 23:08:54 volumiopizero volumio[1311]: info: Cannot mount NAS AllDisk at system boot, trial number 4 ,retrying in 5 seconds Oct 15 23:08:54 volumiopizero volumio[1311]: info: Initializing connection to go-librespot Websocket Oct 15 23:08:54 volumiopizero go-librespot[1566]: time="2026-10-15T23:08:54+02:00" level=debug msg="new websocket client" Oct 15 23:08:54 volumiopizero volumio[1311]: info: Connection to go-librespot Websocket established Oct 15 23:08:55 volumiopizero sudo[1636]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=admin2,password=Klaban326326,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=3.11 //192.168.20.190/alldisk/MP3 Spatně zařazené /mnt/NAS/MP3_Spatně_zařazené Oct 15 23:08:55 volumiopizero sudo[1636]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 15 23:08:55 volumiopizero kernel: CIFS: Attempting to mount //192.168.20.190/alldisk/MP3 Spatně zařazené Oct 15 23:08:55 volumiopizero sudo[1636]: pam_unix(sudo:session): session closed for user root Oct 15 23:08:55 volumiopizero volumio[1311]: info: Cannot mount NAS MP3_Spatně_zařazené at system boot, trial number 3 ,retrying in 5 seconds Oct 15 23:08:55 volumiopizero volumio[1311]: info: CoreCommandRouter::volumioGetState Oct 15 23:08:55 volumiopizero volumio[1311]: info: CorePlayQueue::getTrack 0 Oct 15 23:08:57 volumiopizero volumio[1311]: info: Getting Spotify volume Oct 15 23:08:57 volumiopizero volumio[1311]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Oct 15 23:08:58 volumiopizero volumio[1311]: info: CoreCommandRouter::volumioGetState Oct 15 23:08:58 volumiopizero volumio[1311]: info: CorePlayQueue::getTrack 0 Oct 15 23:08:58 volumiopizero volumio[1311]: info: Aligning Spotify Volume to Volumio Volume Oct 15 23:08:58 volumiopizero volumio[1311]: info: CoreCommandRouter::volumioGetState Oct 15 23:08:58 volumiopizero volumio[1311]: info: CorePlayQueue::getTrack 0 Oct 15 23:08:58 volumiopizero volumio[1311]: info: Setting Spotify Volume from Volumio: 94 Oct 15 23:08:59 volumiopizero volumio[1311]: info: Sending Spotify command with payload to local API: /player/volume Oct 15 23:08:59 volumiopizero volumio[1311]: error: Failed to send command to Spotify local API: /player/volume: Error: Bad Request Oct 15 23:08:59 volumiopizero volumio[1311]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Oct 15 23:09:00 volumiopizero sudo[1642]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=admin2,password=Klaban326326,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=3.11 //192.168.20.190/alldisk/MP3 Spatně zařazené /mnt/NAS/MP3_Spatně_zařazené Oct 15 23:09:00 volumiopizero sudo[1642]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 15 23:09:00 volumiopizero kernel: CIFS: Attempting to mount //192.168.20.190/alldisk/MP3 Spatně zařazené Oct 15 23:09:00 volumiopizero sudo[1642]: pam_unix(sudo:session): session closed for user root Oct 15 23:09:00 volumiopizero volumio[1311]: info: Cannot mount NAS MP3_Spatně_zařazené at system boot, trial number 4 ,retrying in 5 seconds Oct 15 23:09:00 volumiopizero volumio[1311]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Oct 15 23:09:03 volumiopizero volumio[1311]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Oct 15 23:09:03 volumiopizero volumio[1311]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Oct 15 23:09:03 volumiopizero volumio[1311]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Oct 15 23:09:03 volumiopizero volumio[1311]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Oct 15 23:09:03 volumiopizero volumio[1311]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Oct 15 23:09:03 volumiopizero volumio[1311]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Oct 15 23:09:03 volumiopizero volumio[1311]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Oct 15 23:09:03 volumiopizero volumio[1311]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Oct 15 23:09:03 volumiopizero volumio[1311]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Oct 15 23:09:03 volumiopizero volumio[1311]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Oct 15 23:09:03 volumiopizero volumio[1311]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Oct 15 23:09:03 volumiopizero volumio[1311]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Oct 15 23:09:03 volumiopizero volumio[1311]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Oct 15 23:09:03 volumiopizero volumio[1311]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Oct 15 23:09:03 volumiopizero volumio[1311]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Oct 15 23:09:03 volumiopizero volumio[1311]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Oct 15 23:09:03 volumiopizero volumio[1311]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Oct 15 23:09:03 volumiopizero volumio[1311]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Oct 15 23:09:03 volumiopizero volumio[1311]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Oct 15 23:09:03 volumiopizero volumio[1311]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Oct 15 23:09:03 volumiopizero volumio[1311]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Oct 15 23:09:03 volumiopizero volumio[1311]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Oct 15 23:09:03 volumiopizero volumio[1311]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Oct 15 23:09:03 volumiopizero volumio[1311]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Oct 15 23:09:03 volumiopizero volumio[1311]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Oct 15 23:09:03 volumiopizero volumio[1311]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Oct 15 23:09:03 volumiopizero volumio[1311]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Oct 15 23:09:03 volumiopizero volumio[1311]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Oct 15 23:09:03 volumiopizero volumio[1311]: info: Adding plugin bluetooth to MyMusic Plugins Oct 15 23:09:03 volumiopizero volumio[1311]: info: Adding plugin multiroom to MyMusic Plugins Oct 15 23:09:03 volumiopizero volumio[1311]: info: Adding plugin metavolumio to MyMusic Plugins Oct 15 23:09:03 volumiopizero volumio[1311]: info: Adding plugin cd_controller to MyMusic Plugins Oct 15 23:09:03 volumiopizero volumio[1311]: info: Adding plugin qobuzconnect to MyMusic Plugins Oct 15 23:09:03 volumiopizero volumio[1311]: info: Adding plugin smart_inputs to MyMusic Plugins Oct 15 23:09:03 volumiopizero volumio[1311]: info: Adding plugin tidalconnect to MyMusic Plugins Oct 15 23:09:03 volumiopizero volumio[1311]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Oct 15 23:09:05 volumiopizero volumio[1311]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Oct 15 23:09:05 volumiopizero volumio[1311]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Oct 15 23:09:05 volumiopizero volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 23:09:05 volumiopizero volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 23:09:05 volumiopizero volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 23:09:05 volumiopizero volumio[1311]: info: Starting MyVolumio Remote Streaming Endpoints Oct 15 23:09:05 volumiopizero volumio[1311]: info: MyVolumio login type: Token Oct 15 23:09:05 volumiopizero volumio[1311]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Oct 15 23:09:05 volumiopizero volumio[1311]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Oct 15 23:09:07 volumiopizero volumio[1311]: info: Starting Streaming Service Transparent Proxy Oct 15 23:09:07 volumiopizero volumio[1311]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Oct 15 23:09:07 volumiopizero volumio[1311]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Oct 15 23:09:07 volumiopizero volumio[1311]: info: Streaming services startup Oct 15 23:09:07 volumiopizero volumio[1311]: info: Starting Streaming Daemon Oct 15 23:09:07 volumiopizero volumio[1311]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Oct 15 23:09:07 volumiopizero sudo[1667]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 15 23:09:07 volumiopizero sudo[1667]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 15 23:09:07 volumiopizero sudo[1675]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=admin2,password=Klaban326326,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=3.11 //192.168.20.190/alldisk/MP3 Spatně zařazené /mnt/NAS/MP3_Spatně_zařazené Oct 15 23:09:07 volumiopizero sudo[1675]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 15 23:09:07 volumiopizero kernel: CIFS: Attempting to mount //192.168.20.190/alldisk/MP3 Spatně zařazené Oct 15 23:09:08 volumiopizero sudo[1675]: pam_unix(sudo:session): session closed for user root Oct 15 23:09:08 volumiopizero sudo[1667]: pam_unix(sudo:session): session closed for user root Oct 15 23:09:08 volumiopizero volumio[1311]: error: Cannot start Volumio Streaming Daemon Oct 15 23:09:08 volumiopizero volumio[1311]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 15 23:09:08 volumiopizero volumio[1311]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 15 23:09:08 volumiopizero volumio[1311]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Oct 15 23:09:08 volumiopizero volumio[1311]: info: CoreCommandRouter::volumioGetState Oct 15 23:09:08 volumiopizero volumio[1311]: info: CorePlayQueue::getTrack 0 Oct 15 23:09:09 volumiopizero volumio[1311]: info: Listing playlists Oct 15 23:09:09 volumiopizero volumio[1311]: info: Listing playlists Oct 15 23:09:09 volumiopizero volumio[1311]: error: MyVolumio Custom Token format not valid, refreshing it Oct 15 23:09:10 volumiopizero volumio[1311]: STREAMING PROXY: Starting server on port 3245 Oct 15 23:09:10 volumiopizero volumio[1311]: Node JS runtime: 14 Oct 15 23:09:10 volumiopizero volumio[1311]: <--- Last few GCs ---> Oct 15 23:09:10 volumiopizero volumio[1311]: [1311:0x56b1788] 44626 ms: Scavenge 97.7 (106.0) -> 97.6 (106.0) MB, 0.8 / 0.0 ms (average mu = 0.903, current mu = 0.895) allocation failure Oct 15 23:09:10 volumiopizero volumio[1311]: [1311:0x56b1788] 44704 ms: Scavenge 109.0 (117.3) -> 108.9 (117.3) MB, 0.9 / 0.0 ms (average mu = 0.903, current mu = 0.895) allocation failure Oct 15 23:09:10 volumiopizero volumio[1311]: <--- JS stacktrace ---> Oct 15 23:09:10 volumiopizero volumio[1311]: FATAL ERROR: MarkCompactCollector: young object promotion failed Allocation failed - JavaScript heap out of memory Oct 15 23:09:11 volumiopizero volumio-remote-updater[486]: [2026-10-15 23:09:11] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Oct 15 23:09:11 volumiopizero volumio-remote-updater[486]: [2026-10-15 23:09:11] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Oct 15 23:09:11 volumiopizero go-librespot[1566]: time="2026-10-15T23:09:11+02:00" level=error msg="websocket connection errored" error="failed to get reader: failed to read frame header: EOF" Oct 15 23:09:11 volumiopizero systemd[1]: volumio.service: Main process exited, code=killed, status=6/ABRT Oct 15 23:09:11 volumiopizero systemd[1]: volumio.service: Failed with result 'signal'. Oct 15 23:09:11 volumiopizero systemd[1]: Started dynamicswap service. Oct 15 23:09:11 volumiopizero volumio5-onboarding[1407]: time=2026-10-15T23:09:11.309+02:00 level=ERROR msg="failed reading message" error="websocket: close 1006 (abnormal closure): unexpected EOF" Oct 15 23:09:11 volumiopizero systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Oct 15 23:09:11 volumiopizero systemd[1]: volumio.service: Scheduled restart job, restart counter is at 2. Oct 15 23:09:11 volumiopizero systemd[1]: dynamicswap.service: Succeeded. Oct 15 23:09:11 volumiopizero systemd[1]: Stopped Volumio Backend Module. Oct 15 23:09:11 volumiopizero systemd[1]: Started Volumio Backend Module. Oct 15 23:09:12 volumiopizero volumio5-onboarding[1407]: time=2026-10-15T23:09:12.031+02:00 level=WARN msg="reconnection attempt failed" error="dial tcp 127.0.0.1:3000: connect: connection refused" Oct 15 23:09:13 volumiopizero volumio5-onboarding[1407]: time=2026-10-15T23:09:13.053+02:00 level=WARN msg="reconnection attempt failed" error="dial tcp 127.0.0.1:3000: connect: connection refused" Oct 15 23:09:14 volumiopizero volumio[1687]: info: ------------------------------------------- Oct 15 23:09:14 volumiopizero volumio[1687]: info: ----- Volumio3 ---- Oct 15 23:09:14 volumiopizero volumio[1687]: info: ------------------------------------------- Oct 15 23:09:14 volumiopizero volumio[1687]: info: ----- System startup ---- Oct 15 23:09:14 volumiopizero volumio[1687]: info: ------------------------------------------- Oct 15 23:09:15 volumiopizero volumio[1687]: info: MYVOLUMIO Environment detected Oct 15 23:09:15 volumiopizero volumio[1687]: info: Plugin folders cleanup Oct 15 23:09:15 volumiopizero volumio[1687]: info: Scanning into folder /volumio/app/plugins/ Oct 15 23:09:15 volumiopizero volumio[1687]: info: Scanning category audio_interface Oct 15 23:09:15 volumiopizero volumio[1687]: info: Scanning category miscellanea Oct 15 23:09:15 volumiopizero volumio[1687]: info: Scanning category music_service Oct 15 23:09:15 volumiopizero volumio[1687]: info: Scanning category plugins.json Oct 15 23:09:15 volumiopizero volumio[1687]: info: Scanning category system_controller Oct 15 23:09:15 volumiopizero volumio[1687]: info: Scanning category user_interface Oct 15 23:09:15 volumiopizero volumio[1687]: info: Scanning into folder /data/plugins/ Oct 15 23:09:15 volumiopizero volumio[1687]: info: Scanning category audio_interface Oct 15 23:09:15 volumiopizero volumio[1687]: info: Scanning category music_service Oct 15 23:09:15 volumiopizero volumio[1687]: info: Scanning category system_hardware Oct 15 23:09:15 volumiopizero volumio[1687]: info: Plugin folders cleanup completed Oct 15 23:09:15 volumiopizero volumio[1687]: info: ------------------------------------------- Oct 15 23:09:15 volumiopizero volumio[1687]: info: ----- Core plugins startup ---- Oct 15 23:09:15 volumiopizero volumio[1687]: info: ------------------------------------------- Oct 15 23:09:15 volumiopizero volumio[1687]: info: Loading plugins from folder /volumio/app/plugins/ Oct 15 23:09:15 volumiopizero volumio[1687]: info: Adding plugin upnp to MyMusic Plugins Oct 15 23:09:16 volumiopizero volumio[1687]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 15 23:09:16 volumiopizero volumio[1687]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 15 23:09:16 volumiopizero volumio-remote-updater[486]: [2026-10-15 23:09:16] [connect] Successful connection Oct 15 23:09:16 volumiopizero volumio[1687]: info: Loading plugins from folder /data/plugins/ Oct 15 23:09:16 volumiopizero volumio[1687]: info: Loading plugin "system"... Oct 15 23:09:16 volumiopizero volumio[1687]: info: Loading plugin "appearance"... Oct 15 23:09:17 volumiopizero volumio5-onboarding[1407]: time=2026-10-15T23:09:17.395+02:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.20.241:34880 Oct 15 23:09:17 volumiopizero volumio5-onboarding[1407]: time=2026-10-15T23:09:17.656+02:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.20.241:34880 @ 0x3410420" latency=306.25198ms platform=PLATFORM_ANDROID version=6.260930.0 Oct 15 23:09:17 volumiopizero volumio5-onboarding[1407]: time=2026-10-15T23:09:17.658+02:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.20.241:34880 @ 0x3410420" latency=306.070005ms timeout=20s Oct 15 23:09:17 volumiopizero volumio5-onboarding[1407]: time=2026-10-15T23:09:17.659+02:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.20.241:34880 @ 0x3410420" Oct 15 23:09:17 volumiopizero volumio[1687]: info: Loading plugin "network"... Oct 15 23:09:17 volumiopizero volumio[1687]: info: Refreshing Cached IP Addresses Oct 15 23:09:17 volumiopizero volumio[1687]: info: Loading plugin "services"... Oct 15 23:09:17 volumiopizero volumio[1687]: info: Loading plugin "volumio5onboarding"... Oct 15 23:09:17 volumiopizero volumio[1687]: info: Loading plugin "alsa_controller"... Oct 15 23:09:17 volumiopizero sudo[1715]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 15 23:09:17 volumiopizero sudo[1708]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 15 23:09:17 volumiopizero sudo[1708]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 15 23:09:17 volumiopizero sudo[1715]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 15 23:09:17 volumiopizero sudo[1710]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 15 23:09:17 volumiopizero sudo[1710]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 15 23:09:17 volumiopizero sudo[1710]: pam_unix(sudo:session): session closed for user root Oct 15 23:09:18 volumiopizero sudo[1708]: pam_unix(sudo:session): session closed for user root Oct 15 23:09:18 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 15 23:09:18 volumiopizero volumio[1687]: info: Loading plugin "wizard"... Oct 15 23:09:18 volumiopizero volumio[1687]: info: Loading plugin "networkfs"... Oct 15 23:09:18 volumiopizero volumio[1687]: info: Cannot mount NAS AllDisk at system boot, trial number 1 ,retrying in 5 seconds Oct 15 23:09:18 volumiopizero volumio5-onboarding[1407]: time=2026-10-15T23:09:18.511+02:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.20.241:34880 @ 0x3410420" latency=170.4936ms timeout=20s Oct 15 23:09:18 volumiopizero volumio5-onboarding[1407]: time=2026-10-15T23:09:18.511+02:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.20.241:34880 @ 0x3410420" latency=172.388758ms platform=PLATFORM_ANDROID version=6.260930.0 Oct 15 23:09:18 volumiopizero volumio5-onboarding[1407]: time=2026-10-15T23:09:18.511+02:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.20.241:34880 @ 0x3410420" Oct 15 23:09:18 volumiopizero volumio[1687]: info: Starting Udev Watcher for removable devices Oct 15 23:09:18 volumiopizero sudo[1736]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=admin2,password=Klaban326326,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=3.11 //192.168.20.190/alldisk/MP3 Spatně zařazené /mnt/NAS/MP3_Spatně_zařazené Oct 15 23:09:18 volumiopizero sudo[1736]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 15 23:09:18 volumiopizero volumio[1687]: info: Ignoring mount for partition: boot Oct 15 23:09:18 volumiopizero volumio[1687]: info: Ignoring mount for partition: volumio Oct 15 23:09:18 volumiopizero volumio[1687]: info: Ignoring mount for partition: volumio_data Oct 15 23:09:18 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 15 23:09:18 volumiopizero volumio[1687]: info: Loading plugin "volumio_command_line_client"... Oct 15 23:09:18 volumiopizero volumio[1687]: info: Loading plugin "upnp"... Oct 15 23:09:18 volumiopizero volumio[1687]: info: [1792098558604] Starting Upmpd Daemon Oct 15 23:09:18 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 15 23:09:18 volumiopizero kernel: CIFS: Attempting to mount //192.168.20.190/alldisk/MP3 Spatně zařazené Oct 15 23:09:18 volumiopizero volumio[1687]: info: Loading plugin "my_music"... Oct 15 23:09:18 volumiopizero volumio[1687]: info: Loading plugin "mpd"... Oct 15 23:09:18 volumiopizero sudo[1736]: pam_unix(sudo:session): session closed for user root Oct 15 23:09:18 volumiopizero sudo[1715]: pam_unix(sudo:session): session closed for user root Oct 15 23:09:19 volumiopizero volumio[1687]: info: Loading plugin "upnp_browser"... Oct 15 23:09:19 volumiopizero volumio5-onboarding[1407]: time=2026-10-15T23:09:19.409+02:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.20.241:34880 @ 0x3410420" available=true connected=true macAddress=2c:cf:67:ea:a4:9b ip4Address=192.168.20.68/24 ip6Address= ssid=MaKaMaTo2 Oct 15 23:09:19 volumiopizero volumio[1687]: info: Starting UPNP Browser Oct 15 23:09:19 volumiopizero volumio[1687]: info: Loading plugin "alarm-clock"... Oct 15 23:09:20 volumiopizero volumio[1687]: info: Loading plugin "airplay_emulation"... Oct 15 23:09:20 volumiopizero volumio[1687]: info: Starting Shairport Sync Oct 15 23:09:20 volumiopizero volumio[1687]: info: Loading plugin "last_100"... Oct 15 23:09:20 volumiopizero volumio[1687]: info: Loading plugin "webradio"... Oct 15 23:09:20 volumiopizero volumio[1687]: info: Loading plugin "i2s_dacs"... Oct 15 23:09:20 volumiopizero volumio[1687]: info: Loading plugin "volumiodiscovery"... Oct 15 23:09:20 volumiopizero volumio[1687]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 15 23:09:20 volumiopizero node[1687]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 15 23:09:20 volumiopizero volumio[1687]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 15 23:09:20 volumiopizero volumio[1687]: *** WARNING *** For more information see Oct 15 23:09:20 volumiopizero volumio[1687]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 15 23:09:20 volumiopizero volumio[1687]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 15 23:09:20 volumiopizero volumio[1687]: *** WARNING *** For more information see Oct 15 23:09:20 volumiopizero node[1687]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 15 23:09:20 volumiopizero node[1687]: *** WARNING *** For more information see Oct 15 23:09:20 volumiopizero node[1687]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 15 23:09:20 volumiopizero node[1687]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 15 23:09:20 volumiopizero node[1687]: *** WARNING *** For more information see Oct 15 23:09:20 volumiopizero volumio[1687]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 15 23:09:20 volumiopizero volumio[1687]: info: Discovery: Started advertising with name: VolumioPIZero Oct 15 23:09:20 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 15 23:09:20 volumiopizero volumio[1687]: info: Loading plugin "jellyfin"... Oct 15 23:09:22 volumiopizero volumio[1687]: info: Loading plugin "spop"... Oct 15 23:09:24 volumiopizero volumio[1687]: info: Plugin ytcr is not enabled Oct 15 23:09:24 volumiopizero volumio[1687]: info: Loading plugin "outputs"... Oct 15 23:09:24 volumiopizero volumio5-onboarding[1407]: time=2026-10-15T23:09:24.057+02:00 level=WARN msg="reconnection attempt failed" error="read tcp 127.0.0.1:49578->127.0.0.1:3000: i/o timeout" Oct 15 23:09:24 volumiopizero volumio[1687]: info: Loading plugin "albumart"... Oct 15 23:09:24 volumiopizero volumio[1687]: info: Plugin example_plugin is not enabled Oct 15 23:09:24 volumiopizero volumio[1687]: info: Loading plugin "inputs"... Oct 15 23:09:24 volumiopizero volumio[1687]: info: Loading plugin "updater_comm"... Oct 15 23:09:24 volumiopizero volumio[1687]: info: Plugin mpdemulation is not enabled Oct 15 23:09:24 volumiopizero volumio[1687]: info: Loading plugin "rest_api"... Oct 15 23:09:24 volumiopizero volumio[1687]: info: Loading plugin "websocket"... Oct 15 23:09:24 volumiopizero volumio[1687]: info: Starting Socket.io Server version 2.3.0 Oct 15 23:09:24 volumiopizero volumio[1687]: info: Loading plugin "mpdoutput"... Oct 15 23:09:24 volumiopizero volumio[1687]: Forking 3 albumart workers Oct 15 23:09:24 volumiopizero volumio[1687]: info: Plugin lms is not enabled Oct 15 23:09:24 volumiopizero volumio[1687]: info: Plugin minidlna is not enabled Oct 15 23:09:24 volumiopizero volumio[1687]: info: Loading plugin "plexamp"... Oct 15 23:09:25 volumiopizero volumio[1687]: Starting albumart workers Oct 15 23:09:25 volumiopizero volumio[1687]: Starting albumart workers Oct 15 23:09:25 volumiopizero volumio[1687]: Starting albumart workers Oct 15 23:09:27 volumiopizero volumio[1687]: info: Applying required configuration parameters for plugin plexamp Oct 15 23:09:27 volumiopizero volumio[1687]: info: Loading plugin "volusonic"... Oct 15 23:09:28 volumiopizero volumio-remote-updater[486]: [2026-10-15 23:09:28] [connect] Successful connection Oct 15 23:09:29 volumiopizero volumio5-onboarding[1407]: time=2026-10-15T23:09:29.401+02:00 level=ERROR msg="failed to update discovery on Wi-Fi info change" error="failed to get system info: could not get system info: context deadline exceeded" Oct 15 23:09:29 volumiopizero volumio[1687]: info: Applying required configuration parameters for plugin volusonic Oct 15 23:09:29 volumiopizero volumio[1687]: info: Loading plugin "pirateaudio"... Oct 15 23:09:29 volumiopizero volumio[1687]: info: Applying required configuration parameters for plugin pirateaudio Oct 15 23:09:29 volumiopizero volumio[1687]: info: Loading i18n strings for locale cs Oct 15 23:09:29 volumiopizero volumio[1687]: info: Plugin plexamp has duplicated i18n key SAVE. It is ignored. Oct 15 23:09:29 volumiopizero volumio[1687]: info: Plugin volusonic has duplicated i18n key PLUGIN_CONFIGURATION. It is ignored. Oct 15 23:09:29 volumiopizero volumio[1687]: info: Plugin volusonic has duplicated i18n key PLUGIN_CRED_DESC. It is ignored. Oct 15 23:09:29 volumiopizero volumio[1687]: info: Plugin volusonic has duplicated i18n key PLUGIN_OPTS_DESC. It is ignored. Oct 15 23:09:29 volumiopizero volumio[1687]: info: Plugin volusonic has duplicated i18n key SAVE_CREDS. It is ignored. Oct 15 23:09:29 volumiopizero volumio[1687]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Oct 15 23:09:29 volumiopizero volumio[1687]: info: Plugin volusonic has duplicated i18n key TIME_OUT_DOC. It is ignored. Oct 15 23:09:29 volumiopizero volumio[1687]: info: Plugin volusonic has duplicated i18n key TIME_OUT. It is ignored. Oct 15 23:09:29 volumiopizero volumio[1687]: info: Plugin volusonic has duplicated i18n key CON_SUCCESS. It is ignored. Oct 15 23:09:29 volumiopizero volumio[1687]: info: Plugin volusonic has duplicated i18n key CON_OK. It is ignored. Oct 15 23:09:29 volumiopizero volumio[1687]: info: Plugin volusonic has duplicated i18n key CON_FAILED. It is ignored. Oct 15 23:09:29 volumiopizero volumio[1687]: info: Plugin volusonic has duplicated i18n key CON_BAD_CREDS. It is ignored. Oct 15 23:09:29 volumiopizero volumio[1687]: info: Plugin volusonic has duplicated i18n key CON_SERVER_UNREACHABLE. It is ignored. Oct 15 23:09:29 volumiopizero volumio[1687]: info: Plugin volusonic has duplicated i18n key NEWEST_ALBUMS. It is ignored. Oct 15 23:09:29 volumiopizero volumio[1687]: info: Plugin volusonic has duplicated i18n key GENRES. It is ignored. Oct 15 23:09:29 volumiopizero volumio[1687]: info: Plugin volusonic has duplicated i18n key ARTISTS. It is ignored. Oct 15 23:09:29 volumiopizero volumio[1687]: info: Plugin volusonic has duplicated i18n key PLAYLISTS. It is ignored. Oct 15 23:09:29 volumiopizero volumio[1687]: info: Plugin volusonic has duplicated i18n key START_RADIO. It is ignored. Oct 15 23:09:29 volumiopizero volumio[1687]: info: Plugin volusonic has duplicated i18n key TOP_SONGS. It is ignored. Oct 15 23:09:29 volumiopizero volumio[1687]: info: Plugin volusonic has duplicated i18n key ALBUMS. It is ignored. Oct 15 23:09:29 volumiopizero volumio[1687]: info: Plugin volusonic has duplicated i18n key SIMILAR_ARTISTS. It is ignored. Oct 15 23:09:29 volumiopizero volumio[1687]: info: Plugin volusonic has duplicated i18n key TRACKS. It is ignored. Oct 15 23:09:29 volumiopizero volumio[1687]: info: Plugin volusonic has duplicated i18n key SAVED. It is ignored. Oct 15 23:09:29 volumiopizero volumio[1687]: info: Plugin volusonic has duplicated i18n key POP_ERROR. It is ignored. Oct 15 23:09:29 volumiopizero volumio[1687]: Updating browse sources language Oct 15 23:09:29 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 15 23:09:30 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 23:09:30 volumiopizero volumio[1687]: info: CoreCommandRouter::initPlayerControls Oct 15 23:09:30 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 23:09:30 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 23:09:30 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 23:09:30 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 23:09:30 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 23:09:30 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 23:09:30 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 23:09:30 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 23:09:30 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 23:09:30 volumiopizero volumio[1687]: Express server listening on port 3000 Oct 15 23:09:30 volumiopizero volumio[1687]: [Metrics] WebUI: 16s 365.28ms Oct 15 23:09:30 volumiopizero volumio[1687]: info: CoreStateMachine::resetVolumioState Oct 15 23:09:30 volumiopizero volumio[1687]: info: CoreStateMachine::getcurrentVolume Oct 15 23:09:30 volumiopizero volumio[1687]: info: CoreCommandRouter::volumioRetrievevolume Oct 15 23:09:30 volumiopizero volumio[1687]: info: Cannot mount NAS AllDisk at system boot, trial number 2 ,retrying in 5 seconds Oct 15 23:09:30 volumiopizero sudo[1858]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 15 23:09:30 volumiopizero sudo[1858]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 15 23:09:30 volumiopizero sudo[1860]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 15 23:09:30 volumiopizero sudo[1860]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 15 23:09:30 volumiopizero sudo[1860]: pam_unix(sudo:session): session closed for user root Oct 15 23:09:30 volumiopizero sudo[1858]: pam_unix(sudo:session): session closed for user root Oct 15 23:09:30 volumiopizero volumio[1687]: info: Volumio Network Manager: Network status updated: 2 Oct 15 23:09:30 volumiopizero volumio[1687]: info: CoreStateMachine::pushState Oct 15 23:09:30 volumiopizero volumio[1687]: info: CorePlayQueue::getTrack 0 Oct 15 23:09:30 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 15 23:09:30 volumiopizero volumio[1687]: info: CoreCommandRouter::volumioPushState Oct 15 23:09:30 volumiopizero volumio[1687]: info: CoreStateMachine::updateTrackBlock Oct 15 23:09:30 volumiopizero volumio[1687]: info: CorePlayQueue::getTrackBlock Oct 15 23:09:30 volumiopizero volumio[1687]: info: CoreCommandRouter::volumioRetrievevolume Oct 15 23:09:30 volumiopizero volumio[1687]: info: Cannot mount NAS MP3_Spatně_zařazené at system boot, trial number 1 ,retrying in 5 seconds Oct 15 23:09:30 volumiopizero volumio[1687]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 1 Oct 15 23:09:30 volumiopizero volumio[1687]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 2 Oct 15 23:09:30 volumiopizero volumio[1687]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 3 Oct 15 23:09:30 volumiopizero volumio-remote-updater[486]: [2026-10-15 23:09:30] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1792098568 101 Oct 15 23:09:30 volumiopizero volumio[1687]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 4 Oct 15 23:09:30 volumiopizero volumio[1687]: info: CoreCommandRouter::volumioGetState Oct 15 23:09:30 volumiopizero volumio[1687]: info: CorePlayQueue::getTrack 0 Oct 15 23:09:30 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 15 23:09:30 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 15 23:09:30 volumiopizero volumio[1687]: info: Discovery: Getting this device information Oct 15 23:09:30 volumiopizero volumio[1687]: info: CoreCommandRouter::volumioGetState Oct 15 23:09:30 volumiopizero volumio[1687]: info: CorePlayQueue::getTrack 0 Oct 15 23:09:30 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 15 23:09:30 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 15 23:09:30 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 15 23:09:30 volumiopizero volumio[1687]: info: Discovery: Getting this device information Oct 15 23:09:30 volumiopizero volumio[1687]: info: CoreCommandRouter::volumioGetState Oct 15 23:09:30 volumiopizero volumio[1687]: info: CorePlayQueue::getTrack 0 Oct 15 23:09:30 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 15 23:09:30 volumiopizero volumio[1687]: info: CoreStateMachine::pushState Oct 15 23:09:30 volumiopizero volumio[1687]: info: CorePlayQueue::getTrack 0 Oct 15 23:09:30 volumiopizero volumio[1687]: info: CoreCommandRouter::volumioPushState Oct 15 23:09:30 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 23:09:30 volumiopizero volumio[1687]: info: Reloading queue from file Oct 15 23:09:30 volumiopizero volumio[1687]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 4 Oct 15 23:09:30 volumiopizero volumio[1687]: info: CoreStateMachine::setRepeat false single undefined Oct 15 23:09:30 volumiopizero volumio[1687]: info: CoreStateMachine::pushState Oct 15 23:09:30 volumiopizero volumio[1687]: info: CorePlayQueue::getTrack 0 Oct 15 23:09:30 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 15 23:09:30 volumiopizero volumio[1687]: info: CoreCommandRouter::volumioPushState Oct 15 23:09:30 volumiopizero volumio[1687]: info: CoreStateMachine::setRandom false Oct 15 23:09:30 volumiopizero volumio[1687]: info: CoreStateMachine::pushState Oct 15 23:09:30 volumiopizero volumio[1687]: info: CorePlayQueue::getTrack 0 Oct 15 23:09:30 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 15 23:09:30 volumiopizero volumio[1687]: info: CoreCommandRouter::volumioPushState Oct 15 23:09:30 volumiopizero volumio[1687]: info: Setting Device type: Raspberry PI Oct 15 23:09:30 volumiopizero volumio[1687]: info: Listing playlists Oct 15 23:09:30 volumiopizero volumio[1687]: info: Listing playlists Oct 15 23:09:30 volumiopizero volumio[1687]: verbose: New Socket.io Connection to 192.168.20.68:3000 from 192.168.20.241 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 4 Oct 15 23:09:30 volumiopizero volumio[1687]: info: Completed loading Core Plugins Oct 15 23:09:30 volumiopizero volumio[1687]: info: Preparing to generate the ALSA configuration file Oct 15 23:09:30 volumiopizero volumio[1687]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Oct 15 23:09:30 volumiopizero volumio[1687]: info: USB Boot Capable - System SBC Revision found in cpuinfo: 902120 Oct 15 23:09:30 volumiopizero volumio[1687]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Oct 15 23:09:30 volumiopizero volumio[1687]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Oct 15 23:09:30 volumiopizero volumio[1687]: info: Reading ALSA contributions from plugins. Oct 15 23:09:30 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 15 23:09:30 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 15 23:09:30 volumiopizero volumio[1687]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Oct 15 23:09:30 volumiopizero volumio[1687]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 4 Oct 15 23:09:30 volumiopizero sudo[1877]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Oct 15 23:09:30 volumiopizero sudo[1877]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 15 23:09:30 volumiopizero volumio[1687]: info: Discovery: adding 0cc9a581-5f8f-46a0-9083-dfcb19856c64 Oct 15 23:09:30 volumiopizero volumio[1687]: info: Discovery: Found device VolumioPIZero Oct 15 23:09:30 volumiopizero volumio[1687]: info: CoreCommandRouter::volumioGetState Oct 15 23:09:30 volumiopizero volumio[1687]: info: CorePlayQueue::getTrack 0 Oct 15 23:09:31 volumiopizero volumio[1687]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 4 Oct 15 23:09:31 volumiopizero volumio[1687]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 4 Oct 15 23:09:31 volumiopizero volumio[1687]: info: Asound.conf file unchanged, so no further update is needed Oct 15 23:09:31 volumiopizero sudo[1877]: pam_unix(sudo:session): session closed for user root Oct 15 23:09:31 volumiopizero volumio[1687]: info: Output device has changed, restarting MPD Oct 15 23:09:31 volumiopizero sudo[1880]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 15 23:09:31 volumiopizero volumio[1687]: info: Output device has changed, restarting Shairport Sync Oct 15 23:09:31 volumiopizero sudo[1880]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 15 23:09:31 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 23:09:31 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 23:09:31 volumiopizero sudo[1880]: pam_unix(sudo:session): session closed for user root Oct 15 23:09:31 volumiopizero sudo[1882]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 15 23:09:31 volumiopizero sudo[1882]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 15 23:09:31 volumiopizero systemd[1]: Stopping Music Player Daemon... Oct 15 23:09:31 volumiopizero volumio[1687]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 15 23:09:31 volumiopizero volumio[1687]: info: ___________ START PLUGINS ___________ Oct 15 23:09:31 volumiopizero volumio[1687]: info: ControllerMpd::onStart: Initializing MPD Oct 15 23:09:31 volumiopizero volumio[1687]: info: Creating MPD Configuration file Oct 15 23:09:31 volumiopizero sudo[1889]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Oct 15 23:09:31 volumiopizero sudo[1889]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 15 23:09:31 volumiopizero sudo[1892]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 15 23:09:31 volumiopizero sudo[1892]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 15 23:09:31 volumiopizero sudo[1889]: pam_unix(sudo:session): session closed for user root Oct 15 23:09:31 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 23:09:31 volumiopizero volumio[1687]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 15 23:09:31 volumiopizero volumio[1687]: info: [1792098571273] CoreMusicLibrary::Adding element Média servery Oct 15 23:09:31 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 15 23:09:31 volumiopizero sudo[1892]: pam_unix(sudo:session): session closed for user root Oct 15 23:09:31 volumiopizero volumio[1687]: info: UPNP Browser: Client initialized successfully Oct 15 23:09:31 volumiopizero sudo[1894]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 15 23:09:31 volumiopizero sudo[1894]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 15 23:09:31 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 23:09:31 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 23:09:31 volumiopizero volumio[1687]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 15 23:09:31 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 23:09:31 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 23:09:31 volumiopizero volumio[1687]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 15 23:09:31 volumiopizero volumio[1687]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 15 23:09:31 volumiopizero volumio[1687]: info: [1792098571456] CoreMusicLibrary::Adding element Last_100 Oct 15 23:09:31 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 15 23:09:31 volumiopizero volumio[1687]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 15 23:09:31 volumiopizero volumio[1687]: info: [1792098571460] CoreMusicLibrary::Adding element Webradio Oct 15 23:09:31 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 15 23:09:31 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 15 23:09:31 volumiopizero volumio[1687]: info: Initializing BBC Radios Oct 15 23:09:31 volumiopizero volumio[1687]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory Oct 15 23:09:31 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 23:09:31 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 15 23:09:31 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 23:09:31 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 15 23:09:31 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 15 23:09:31 volumiopizero volumio[1687]: info: Discovery: Getting this device information Oct 15 23:09:31 volumiopizero volumio[1687]: info: CoreCommandRouter::volumioGetState Oct 15 23:09:31 volumiopizero volumio[1687]: info: CorePlayQueue::getTrack 0 Oct 15 23:09:31 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 15 23:09:31 volumiopizero volumio[1687]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 15 23:09:31 volumiopizero volumio[1687]: info: [1792098571615] CoreMusicLibrary::Adding element Jellyfin Oct 15 23:09:31 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 15 23:09:31 volumiopizero volumio[1687]: Cannot find translation for source Jellyfin Oct 15 23:09:31 volumiopizero volumio[1687]: info: [jellyfin] Initialized plugin with device info: {"id":"0cc9a581-5f8f-46a0-9083-dfcb19856c64","host":"http://192.168.20.68","name":"VolumioPIZero","type":"device","serviceName":"Volumio","state":{"status":"stop","volume":"94","mute":false,"artist":"The Thrillseekers","track":"Synaesthesia (Fly Away)","albumart":"https://i.scdn.co/image/ab67616d0000b273a6b3752e552899eb4a9f316c"}} Oct 15 23:09:31 volumiopizero volumio[1687]: info: Creating Spotify config file Oct 15 23:09:31 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 23:09:31 volumiopizero volumio[1687]: info: Loading i18n strings for locale cs Oct 15 23:09:31 volumiopizero volumio[1687]: info: Plugin plexamp has duplicated i18n key SAVE. It is ignored. Oct 15 23:09:31 volumiopizero volumio[1687]: info: Plugin volusonic has duplicated i18n key PLUGIN_CONFIGURATION. It is ignored. Oct 15 23:09:31 volumiopizero volumio[1687]: info: Plugin volusonic has duplicated i18n key PLUGIN_CRED_DESC. It is ignored. Oct 15 23:09:31 volumiopizero volumio[1687]: info: Plugin volusonic has duplicated i18n key PLUGIN_OPTS_DESC. It is ignored. Oct 15 23:09:31 volumiopizero volumio[1687]: info: Plugin volusonic has duplicated i18n key SAVE_CREDS. It is ignored. Oct 15 23:09:31 volumiopizero volumio[1687]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Oct 15 23:09:31 volumiopizero volumio[1687]: info: Plugin volusonic has duplicated i18n key TIME_OUT_DOC. It is ignored. Oct 15 23:09:31 volumiopizero volumio[1687]: info: Plugin volusonic has duplicated i18n key TIME_OUT. It is ignored. Oct 15 23:09:31 volumiopizero volumio[1687]: info: Plugin volusonic has duplicated i18n key CON_SUCCESS. It is ignored. Oct 15 23:09:31 volumiopizero volumio[1687]: info: Plugin volusonic has duplicated i18n key CON_OK. It is ignored. Oct 15 23:09:31 volumiopizero volumio[1687]: info: Plugin volusonic has duplicated i18n key CON_FAILED. It is ignored. Oct 15 23:09:31 volumiopizero volumio[1687]: info: Plugin volusonic has duplicated i18n key CON_BAD_CREDS. It is ignored. Oct 15 23:09:31 volumiopizero volumio[1687]: info: Plugin volusonic has duplicated i18n key CON_SERVER_UNREACHABLE. It is ignored. Oct 15 23:09:31 volumiopizero volumio[1687]: info: Plugin volusonic has duplicated i18n key NEWEST_ALBUMS. It is ignored. Oct 15 23:09:31 volumiopizero volumio[1687]: info: Plugin volusonic has duplicated i18n key GENRES. It is ignored. Oct 15 23:09:31 volumiopizero volumio[1687]: info: Plugin volusonic has duplicated i18n key ARTISTS. It is ignored. Oct 15 23:09:31 volumiopizero volumio[1687]: info: Plugin volusonic has duplicated i18n key PLAYLISTS. It is ignored. Oct 15 23:09:31 volumiopizero volumio[1687]: info: Plugin volusonic has duplicated i18n key START_RADIO. It is ignored. Oct 15 23:09:31 volumiopizero volumio[1687]: info: Plugin volusonic has duplicated i18n key TOP_SONGS. It is ignored. Oct 15 23:09:31 volumiopizero volumio[1687]: info: Plugin volusonic has duplicated i18n key ALBUMS. It is ignored. Oct 15 23:09:31 volumiopizero volumio[1687]: info: Plugin volusonic has duplicated i18n key SIMILAR_ARTISTS. It is ignored. Oct 15 23:09:31 volumiopizero volumio[1687]: info: Plugin volusonic has duplicated i18n key TRACKS. It is ignored. Oct 15 23:09:31 volumiopizero volumio[1687]: info: Plugin volusonic has duplicated i18n key SAVED. It is ignored. Oct 15 23:09:31 volumiopizero volumio[1687]: info: Plugin volusonic has duplicated i18n key POP_ERROR. It is ignored. Oct 15 23:09:31 volumiopizero volumio[1687]: Updating browse sources language Oct 15 23:09:31 volumiopizero volumio[1687]: Cannot find translation for source Jellyfin Oct 15 23:09:31 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 15 23:09:31 volumiopizero volumio[1687]: Cannot find translation for source Jellyfin Oct 15 23:09:31 volumiopizero volumio[1687]: info: Loading i18n strings for locale cs Oct 15 23:09:31 volumiopizero volumio[1687]: info: Plugin plexamp has duplicated i18n key SAVE. It is ignored. Oct 15 23:09:31 volumiopizero volumio[1687]: info: Plugin volusonic has duplicated i18n key PLUGIN_CONFIGURATION. It is ignored. Oct 15 23:09:31 volumiopizero volumio[1687]: info: Plugin volusonic has duplicated i18n key PLUGIN_CRED_DESC. It is ignored. Oct 15 23:09:31 volumiopizero volumio[1687]: info: Plugin volusonic has duplicated i18n key PLUGIN_OPTS_DESC. It is ignored. Oct 15 23:09:31 volumiopizero volumio[1687]: info: Plugin volusonic has duplicated i18n key SAVE_CREDS. It is ignored. Oct 15 23:09:31 volumiopizero volumio[1687]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Oct 15 23:09:31 volumiopizero volumio[1687]: info: Plugin volusonic has duplicated i18n key TIME_OUT_DOC. It is ignored. Oct 15 23:09:31 volumiopizero volumio[1687]: info: Plugin volusonic has duplicated i18n key TIME_OUT. It is ignored. Oct 15 23:09:31 volumiopizero volumio[1687]: info: Plugin volusonic has duplicated i18n key CON_SUCCESS. It is ignored. Oct 15 23:09:31 volumiopizero volumio[1687]: info: Plugin volusonic has duplicated i18n key CON_OK. It is ignored. Oct 15 23:09:31 volumiopizero volumio[1687]: info: Plugin volusonic has duplicated i18n key CON_FAILED. It is ignored. Oct 15 23:09:31 volumiopizero volumio[1687]: info: Plugin volusonic has duplicated i18n key CON_BAD_CREDS. It is ignored. Oct 15 23:09:31 volumiopizero volumio[1687]: info: Plugin volusonic has duplicated i18n key CON_SERVER_UNREACHABLE. It is ignored. Oct 15 23:09:31 volumiopizero volumio[1687]: info: Plugin volusonic has duplicated i18n key NEWEST_ALBUMS. It is ignored. Oct 15 23:09:31 volumiopizero volumio[1687]: info: Plugin volusonic has duplicated i18n key GENRES. It is ignored. Oct 15 23:09:32 volumiopizero volumio[1687]: info: Plugin volusonic has duplicated i18n key ARTISTS. It is ignored. Oct 15 23:09:32 volumiopizero volumio[1687]: info: Plugin volusonic has duplicated i18n key PLAYLISTS. It is ignored. Oct 15 23:09:32 volumiopizero volumio[1687]: info: Plugin volusonic has duplicated i18n key START_RADIO. It is ignored. Oct 15 23:09:32 volumiopizero volumio[1687]: info: Plugin volusonic has duplicated i18n key TOP_SONGS. It is ignored. Oct 15 23:09:32 volumiopizero volumio[1687]: info: Plugin volusonic has duplicated i18n key ALBUMS. It is ignored. Oct 15 23:09:32 volumiopizero volumio[1687]: info: Plugin volusonic has duplicated i18n key SIMILAR_ARTISTS. It is ignored. Oct 15 23:09:32 volumiopizero volumio[1687]: info: Plugin volusonic has duplicated i18n key TRACKS. It is ignored. Oct 15 23:09:32 volumiopizero volumio[1687]: info: Plugin volusonic has duplicated i18n key SAVED. It is ignored. Oct 15 23:09:32 volumiopizero volumio[1687]: info: Plugin volusonic has duplicated i18n key POP_ERROR. It is ignored. Oct 15 23:09:32 volumiopizero volumio[1687]: Updating browse sources language Oct 15 23:09:32 volumiopizero volumio[1687]: Cannot find translation for source Jellyfin Oct 15 23:09:32 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 15 23:09:32 volumiopizero volumio[1687]: Cannot find translation for source Jellyfin Oct 15 23:09:32 volumiopizero volumio[1687]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 15 23:09:32 volumiopizero volumio[1687]: info: [1792098572006] CoreMusicLibrary::Adding element Volusonic Oct 15 23:09:32 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 15 23:09:32 volumiopizero volumio[1687]: Cannot find translation for source Jellyfin Oct 15 23:09:32 volumiopizero volumio[1687]: Cannot find translation for source Volusonic Oct 15 23:09:32 volumiopizero volumio[1687]: info: Volumio Calling Home Oct 15 23:09:32 volumiopizero sudo[1915]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start pirateaudio.service Oct 15 23:09:32 volumiopizero sudo[1915]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 15 23:09:32 volumiopizero sudo[1915]: pam_unix(sudo:session): session closed for user root Oct 15 23:09:32 volumiopizero volumio[1687]: info: PlexAmp::Plex initialised[object Object] Oct 15 23:09:32 volumiopizero volumio[1687]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 15 23:09:32 volumiopizero volumio[1687]: info: [1792098572166] CoreMusicLibrary::Adding element Plex Oct 15 23:09:32 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 15 23:09:32 volumiopizero volumio[1687]: Cannot find translation for source Jellyfin Oct 15 23:09:32 volumiopizero volumio[1687]: Cannot find translation for source Volusonic Oct 15 23:09:32 volumiopizero volumio[1687]: Cannot find translation for source Plex Oct 15 23:09:32 volumiopizero volumio[1687]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 4 Oct 15 23:09:32 volumiopizero volumio[1687]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 4 Oct 15 23:09:32 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 15 23:09:32 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 15 23:09:32 volumiopizero volumio[1687]: info: Received Get System Info Oct 15 23:09:32 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 15 23:09:32 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 15 23:09:32 volumiopizero volumio[1687]: info: Discovery: Getting this device information Oct 15 23:09:32 volumiopizero volumio[1687]: info: CoreCommandRouter::volumioGetState Oct 15 23:09:32 volumiopizero volumio[1687]: info: CorePlayQueue::getTrack 0 Oct 15 23:09:32 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 15 23:09:32 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 15 23:09:32 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 15 23:09:32 volumiopizero volumio[1687]: info: Received Get System Info Oct 15 23:09:32 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 15 23:09:32 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 15 23:09:32 volumiopizero volumio[1687]: info: Discovery: Getting this device information Oct 15 23:09:32 volumiopizero volumio[1687]: info: CoreCommandRouter::volumioGetState Oct 15 23:09:32 volumiopizero volumio[1687]: info: CorePlayQueue::getTrack 0 Oct 15 23:09:32 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 15 23:09:32 volumiopizero volumio5-onboarding[1407]: time=2026-10-15T23:09:32.308+02:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.20.241:34880 @ 0x3410420" name=VolumioPIZero Oct 15 23:09:32 volumiopizero volumio5-onboarding[1407]: time=2026-10-15T23:09:32.311+02:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.20.241:34880 @ 0x3410420" name=VolumioPIZero Oct 15 23:09:32 volumiopizero volumio[1687]: info: Pirate audio service (daemon) started Oct 15 23:09:32 volumiopizero volumio[1687]: info: MPD Permissions set Oct 15 23:09:32 volumiopizero volumio[1687]: info: MPD Permissions set Oct 15 23:09:32 volumiopizero volumio[1687]: info: Upmpdcli Daemon Started Oct 15 23:09:32 volumiopizero volumio[1687]: info: [jellyfin-poller] Polled http://213.29.79.22:8096: online Oct 15 23:09:32 volumiopizero volumio[1687]: info: Spotify config file written Oct 15 23:09:32 volumiopizero volumio5-onboarding[1407]: time=2026-10-15T23:09:32.395+02:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.20.241:34880 @ 0x3410420" language=cs Oct 15 23:09:32 volumiopizero volumio5-onboarding[1407]: time=2026-10-15T23:09:32.398+02:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.20.241:34880 @ 0x3410420" language=cs Oct 15 23:09:32 volumiopizero volumio[1687]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Oct 15 23:09:32 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 23:09:32 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 23:09:32 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 23:09:32 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 23:09:32 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 23:09:32 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 23:09:32 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 23:09:32 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 23:09:32 volumiopizero sudo[1920]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Oct 15 23:09:32 volumiopizero sudo[1920]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 15 23:09:32 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 23:09:32 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 23:09:32 volumiopizero systemd[1]: Stopping go-librespot Daemon... Oct 15 23:09:32 volumiopizero systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Oct 15 23:09:32 volumiopizero volumio[1687]: info: No need to fix Spotify hosts Oct 15 23:09:32 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 23:09:32 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 23:09:32 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 23:09:32 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 23:09:32 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 23:09:32 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 23:09:32 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 23:09:32 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 23:09:32 volumiopizero systemd[1]: go-librespot-daemon.service: Succeeded. Oct 15 23:09:32 volumiopizero systemd[1]: Stopped go-librespot Daemon. Oct 15 23:09:32 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 23:09:32 volumiopizero systemd[1]: Started go-librespot Daemon. Oct 15 23:09:32 volumiopizero sudo[1920]: pam_unix(sudo:session): session closed for user root Oct 15 23:09:32 volumiopizero go-librespot[1930]: go-librespot daemon starting... Oct 15 23:09:32 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Oct 15 23:09:32 volumiopizero volumio5-onboarding[1407]: time=2026-10-15T23:09:32.714+02:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.20.241:34880 @ 0x3410420" timezone=Europe/Prague Oct 15 23:09:32 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Oct 15 23:09:32 volumiopizero volumio5-onboarding[1407]: time=2026-10-15T23:09:32.714+02:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.20.241:34880 @ 0x3410420" available=false connected=false macAddress= ip4Address= ip6Address= Oct 15 23:09:32 volumiopizero volumio5-onboarding[1407]: time=2026-10-15T23:09:32.715+02:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.20.241:34880 @ 0x3410420" timezone=Europe/Prague Oct 15 23:09:32 volumiopizero volumio5-onboarding[1407]: time=2026-10-15T23:09:32.716+02:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.20.241:34880 @ 0x3410420" available=false connected=false macAddress= ip4Address= ip6Address= Oct 15 23:09:32 volumiopizero volumio5-onboarding[1407]: time=2026-10-15T23:09:32.729+02:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.20.241:34880 @ 0x3410420" available=true connected=true macAddress=2c:cf:67:ea:a4:9b ip4Address=192.168.20.68/24 ip6Address= ssid=MaKaMaTo2 Oct 15 23:09:32 volumiopizero volumio5-onboarding[1407]: time=2026-10-15T23:09:32.731+02:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.20.241:34880 @ 0x3410420" setupComplete=true Oct 15 23:09:32 volumiopizero volumio5-onboarding[1407]: time=2026-10-15T23:09:32.733+02:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.20.241:34880 @ 0x3410420" available=true connected=true macAddress=2c:cf:67:ea:a4:9b ip4Address=192.168.20.68/24 ip6Address= ssid=MaKaMaTo2 Oct 15 23:09:32 volumiopizero volumio5-onboarding[1407]: time=2026-10-15T23:09:32.738+02:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.20.241:34880 @ 0x3410420" setupComplete=true Oct 15 23:09:32 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Oct 15 23:09:32 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 15 23:09:32 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 23:09:32 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 15 23:09:32 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Oct 15 23:09:32 volumiopizero systemd[1]: mpd.service: Succeeded. Oct 15 23:09:32 volumiopizero systemd[1]: Stopped Music Player Daemon. Oct 15 23:09:33 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 15 23:09:33 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 23:09:33 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 15 23:09:33 volumiopizero systemd[1]: Starting Music Player Daemon... Oct 15 23:09:33 volumiopizero volumio[1687]: amixer -c 0 info | grep "bcm2835 ALSA" Oct 15 23:09:33 volumiopizero volumio[1687]: amixer -c 1 info | grep "bcm2835 Headphones" Oct 15 23:09:33 volumiopizero volumio[1687]: Card hw:1 'Headphones'/'bcm2835 Headphones' Oct 15 23:09:33 volumiopizero volumio[1687]: amixer -c 2 info | grep "snd_rpi_hifiberry_dac" Oct 15 23:09:33 volumiopizero volumio[1687]: Card hw:2 'sndrpihifiberry'/'snd_rpi_hifiberry_dac' Oct 15 23:09:33 volumiopizero volumio[1687]: amixer -c 0 info | grep "bcm2835 ALSA" Oct 15 23:09:33 volumiopizero sudo[1941]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 15 23:09:33 volumiopizero sudo[1941]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 15 23:09:33 volumiopizero sudo[1941]: pam_unix(sudo:session): session closed for user root Oct 15 23:09:33 volumiopizero volumio[1687]: amixer -c 1 info | grep "bcm2835 Headphones" Oct 15 23:09:33 volumiopizero volumio[1687]: Card hw:1 'Headphones'/'bcm2835 Headphones' Oct 15 23:09:33 volumiopizero volumio[1687]: amixer -c 2 info | grep "snd_rpi_hifiberry_dac" Oct 15 23:09:33 volumiopizero go-librespot[1930]: time="2026-10-15T23:09:33+02:00" level=info msg="running go-librespot 0.7.1" Oct 15 23:09:33 volumiopizero volumio[1687]: Card hw:2 'sndrpihifiberry'/'snd_rpi_hifiberry_dac' Oct 15 23:09:33 volumiopizero go-librespot[1930]: time="2026-10-15T23:09:33+02:00" level=debug msg="app state loaded" Oct 15 23:09:33 volumiopizero go-librespot[1930]: time="2026-10-15T23:09:33+02:00" level=debug msg="stored credentials not found" Oct 15 23:09:33 volumiopizero go-librespot[1930]: time="2026-10-15T23:09:33+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 15 23:09:33 volumiopizero volumio[1687]: info: Starting Shairport Sync Oct 15 23:09:33 volumiopizero volumio[1687]: info: Starting Shairport Sync Oct 15 23:09:33 volumiopizero volumio[1687]: info: Starting Shairport Sync Oct 15 23:09:33 volumiopizero sudo[1971]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 15 23:09:33 volumiopizero sudo[1971]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 15 23:09:33 volumiopizero volumio[1687]: info: New Spotify access tokenBQD6at4awa... Oct 15 23:09:33 volumiopizero volumio[1687]: info: Spotify credentials grant success - running version from March 24, 2019 Oct 15 23:09:33 volumiopizero sudo[1973]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 15 23:09:33 volumiopizero sudo[1973]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 15 23:09:33 volumiopizero systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 15 23:09:33 volumiopizero systemd[1]: shairport-sync.service: Succeeded. Oct 15 23:09:33 volumiopizero systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 15 23:09:33 volumiopizero sudo[1976]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 15 23:09:33 volumiopizero sudo[1976]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 15 23:09:33 volumiopizero systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 15 23:09:33 volumiopizero volumio[1687]: info: CoreCommandRouter::volumioGetState Oct 15 23:09:33 volumiopizero volumio[1687]: info: CorePlayQueue::getTrack 0 Oct 15 23:09:33 volumiopizero sudo[1971]: pam_unix(sudo:session): session closed for user root Oct 15 23:09:33 volumiopizero systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 15 23:09:33 volumiopizero systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Oct 15 23:09:33 volumiopizero systemd[1]: shairport-sync.service: Succeeded. Oct 15 23:09:33 volumiopizero systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 15 23:09:33 volumiopizero volumio[1687]: amixer -c 2 info | grep "HiFiBerry DAC" Oct 15 23:09:33 volumiopizero systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 15 23:09:33 volumiopizero sudo[1973]: pam_unix(sudo:session): session closed for user root Oct 15 23:09:33 volumiopizero sudo[1976]: pam_unix(sudo:session): session closed for user root Oct 15 23:09:34 volumiopizero volumio5-onboarding[1407]: time=2026-10-15T23:09:34.076+02:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.20.241:34880 @ 0x3410420" selectedOutputId=2 Oct 15 23:09:34 volumiopizero volumio5-onboarding[1407]: time=2026-10-15T23:09:34.076+02:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.20.241:34880 @ 0x3410420" selectedOutputId=2 Oct 15 23:09:34 volumiopizero volumio[1687]: amixer -c 2 info | grep "HiFiBerry DAC" Oct 15 23:09:34 volumiopizero volumio[1687]: info: Shairport-Sync Started Oct 15 23:09:34 volumiopizero volumio[1687]: Error adding Membership: Error: addMembership EINVAL Oct 15 23:09:34 volumiopizero volumio[1687]: info: Shairport-Sync Started Oct 15 23:09:34 volumiopizero volumio[1687]: info: Shairport-Sync Started Oct 15 23:09:34 volumiopizero volumio[1687]: info: Volumio called home Oct 15 23:09:34 volumiopizero go-librespot[1930]: time="2026-10-15T23:09:34+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 15 23:09:34 volumiopizero go-librespot[1930]: time="2026-10-15T23:09:34+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 15 23:09:34 volumiopizero go-librespot[1930]: time="2026-10-15T23:09:34+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 15 23:09:34 volumiopizero go-librespot[1930]: time="2026-10-15T23:09:34+02:00" level=info msg="zeroconf server listening on port 36575" Oct 15 23:09:34 volumiopizero volumio[1687]: info: Received Get System Info Oct 15 23:09:34 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 15 23:09:34 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 15 23:09:34 volumiopizero volumio[1687]: info: Discovery: Getting this device information Oct 15 23:09:34 volumiopizero volumio[1687]: info: CoreCommandRouter::volumioGetState Oct 15 23:09:34 volumiopizero volumio[1687]: info: CorePlayQueue::getTrack 0 Oct 15 23:09:34 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 15 23:09:34 volumiopizero volumio[1687]: info: Received Get System Info Oct 15 23:09:34 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 15 23:09:34 volumiopizero go-librespot[1930]: time="2026-10-15T23:09:34+02:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Oct 15 23:09:34 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 15 23:09:34 volumiopizero volumio[1687]: info: Discovery: Getting this device information Oct 15 23:09:34 volumiopizero volumio[1687]: info: CoreCommandRouter::volumioGetState Oct 15 23:09:34 volumiopizero volumio[1687]: info: CorePlayQueue::getTrack 0 Oct 15 23:09:34 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 15 23:09:34 volumiopizero volumio5-onboarding[1407]: time=2026-10-15T23:09:34.390+02:00 level=INFO msg="emitting software info changed event" component=server peer="192.168.20.241:34880 @ 0x3410420" currentVersion=3.914 latestVersion=3.914 Oct 15 23:09:34 volumiopizero volumio5-onboarding[1407]: time=2026-10-15T23:09:34.391+02:00 level=INFO msg="emitting software info changed event" component=server peer="192.168.20.241:34880 @ 0x3410420" currentVersion=3.914 latestVersion=3.914 Oct 15 23:09:34 volumiopizero volumio5-onboarding[1407]: time=2026-10-15T23:09:34.403+02:00 level=INFO msg="emitting software update progress event" component=server peer="192.168.20.241:34880 @ 0x3410420" status=UPDATE_STATUS_NONE progress=0 Oct 15 23:09:34 volumiopizero volumio5-onboarding[1407]: time=2026-10-15T23:09:34.404+02:00 level=INFO msg="emitting user changed event" component=server peer="192.168.20.241:34880 @ 0x3410420" userId= Oct 15 23:09:34 volumiopizero volumio5-onboarding[1407]: time=2026-10-15T23:09:34.404+02:00 level=INFO msg="emitting software update progress event" component=server peer="192.168.20.241:34880 @ 0x3410420" status=UPDATE_STATUS_NONE progress=0 Oct 15 23:09:34 volumiopizero volumio5-onboarding[1407]: time=2026-10-15T23:09:34.404+02:00 level=INFO msg="emitting user changed event" component=server peer="192.168.20.241:34880 @ 0x3410420" userId= Oct 15 23:09:34 volumiopizero volumio[1687]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 6 Oct 15 23:09:34 volumiopizero volumio5-onboarding[1407]: time=2026-10-15T23:09:34.458+02:00 level=INFO msg="emitting music providers changed event" component=server peer="192.168.20.241:34880 @ 0x3410420" providers=3 Oct 15 23:09:34 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 15 23:09:34 volumiopizero volumio5-onboarding[1407]: time=2026-10-15T23:09:34.521+02:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.20.241:34880 @ 0x3410420" plugins=0 Oct 15 23:09:34 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 15 23:09:34 volumiopizero volumio[1687]: info: CoreCommandRouter::volumioGetState Oct 15 23:09:34 volumiopizero volumio[1687]: info: CorePlayQueue::getTrack 0 Oct 15 23:09:34 volumiopizero volumio5-onboarding[1407]: time=2026-10-15T23:09:34.534+02:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.20.241:34880 @ 0x3410420" plugins=0 Oct 15 23:09:34 volumiopizero volumio[1687]: info: CoreCommandRouter::volumioGetState Oct 15 23:09:34 volumiopizero volumio[1687]: info: CorePlayQueue::getTrack 0 Oct 15 23:09:34 volumiopizero volumio5-onboarding[1407]: time=2026-10-15T23:09:34.537+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.20.241:34880 @ 0x3410420" state=STATUS_STOPPED positionMs=0 volume=94 Oct 15 23:09:34 volumiopizero volumio5-onboarding[1407]: time=2026-10-15T23:09:34.560+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.241:34880 @ 0x3410420" id=spotify:track:5QdgpcfAw8EbsupH0B6BpI title="Synaesthesia (Fly Away)" Oct 15 23:09:34 volumiopizero volumio5-onboarding[1407]: time=2026-10-15T23:09:34.544+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.20.241:34880 @ 0x3410420" state=STATUS_STOPPED positionMs=0 volume=94 Oct 15 23:09:34 volumiopizero volumio5-onboarding[1407]: time=2026-10-15T23:09:34.536+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.20.241:34880 @ 0x3410420" state=STATUS_STOPPED positionMs=0 volume=94 Oct 15 23:09:34 volumiopizero volumio5-onboarding[1407]: time=2026-10-15T23:09:34.562+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.241:34880 @ 0x3410420" id=spotify:track:5QdgpcfAw8EbsupH0B6BpI title="Synaesthesia (Fly Away)" Oct 15 23:09:34 volumiopizero volumio[1687]: info: Spotify Successfully logged in Oct 15 23:09:34 volumiopizero volumio[1687]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 15 23:09:34 volumiopizero volumio[1687]: info: [1792098574571] CoreMusicLibrary::Adding element Spotify Oct 15 23:09:34 volumiopizero volumio[1687]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 15 23:09:34 volumiopizero volumio[1687]: Cannot find translation for source Jellyfin Oct 15 23:09:34 volumiopizero volumio[1687]: Cannot find translation for source Volusonic Oct 15 23:09:34 volumiopizero volumio[1687]: Cannot find translation for source Plex Oct 15 23:09:34 volumiopizero volumio[1687]: Cannot find translation for source Spotify Oct 15 23:09:34 volumiopizero volumio5-onboarding[1407]: time=2026-10-15T23:09:34.599+02:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.20.241:34880 @ 0x3410420" latency=114.959523ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Oct 15 23:09:34 volumiopizero volumio[1687]: info: CoreCommandRouter::volumioGetState Oct 15 23:09:34 volumiopizero volumio[1687]: info: CorePlayQueue::getTrack 0 Oct 15 23:09:34 volumiopizero volumio[1687]: info: CoreCommandRouter::volumioGetQueue Oct 15 23:09:34 volumiopizero volumio[1687]: info: CoreStateMachine::getQueue Oct 15 23:09:34 volumiopizero volumio[1687]: info: CorePlayQueue::getQueue Oct 15 23:09:35 volumiopizero volumio[1687]: info: Cannot mount NAS AllDisk at system boot, trial number 3 ,retrying in 5 seconds Oct 15 23:09:35 volumiopizero sudo[2010]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=admin2,password=Klaban326326,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=3.11 //192.168.20.190/alldisk/MP3 Spatně zařazené /mnt/NAS/MP3_Spatně_zařazené Oct 15 23:09:35 volumiopizero sudo[2010]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 15 23:09:35 volumiopizero kernel: CIFS: Attempting to mount //192.168.20.190/alldisk/MP3 Spatně zařazené Oct 15 23:09:35 volumiopizero sudo[2010]: pam_unix(sudo:session): session closed for user root Oct 15 23:09:35 volumiopizero volumio[1687]: info: Cannot mount NAS MP3_Spatně_zařazené at system boot, trial number 2 ,retrying in 5 seconds Oct 15 23:09:35 volumiopizero volumio[1687]: info: go-librespot daemon successfully initialized Oct 15 23:09:35 volumiopizero volumio[1687]: info: CoreCommandRouter::volumioGetState Oct 15 23:09:35 volumiopizero volumio[1687]: info: CorePlayQueue::getTrack 0 Oct 15 23:09:37 volumiopizero mpd[1960]: Oct 15 23:09 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 15 23:09:37 volumiopizero go-librespot[1930]: time="2026-10-15T23:09:37+02:00" level=debug msg="obtained new client token: AAHJOrXPmo0vOFInFXAlvqQ641ubT4MF32vLFbHX4yE70rrkhFFblrpD3tBRvnwPW5sBSvB9rVMO9QJ5LD4aqIWVc/QmntGwe8CkVJrPOhpsEE7Hondx6f18oAFS+o2vaU3ULxbQVyn8Iqx0M8gC20yO76PcOWUcXoQodzVMvaTpZCUlIiuiD8mJqT4toU+8DbYgheEoGvAJIxuoGggr5ASIynx2f5hqQzmTK4GbeTnwHhmCPYOrsbY=" Oct 15 23:09:37 volumiopizero go-librespot[1930]: time="2026-10-15T23:09:37+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 15 23:09:37 volumiopizero go-librespot[1930]: time="2026-10-15T23:09:37+02:00" level=debug msg="completed keyexchange" Oct 15 23:09:37 volumiopizero go-librespot[1930]: time="2026-10-15T23:09:37+02:00" level=debug msg="completed challenge" Oct 15 23:09:37 volumiopizero go-librespot[1930]: time="2026-10-15T23:09:37+02:00" level=info msg="authenticated AP" username="31************************2q" Oct 15 23:09:37 volumiopizero go-librespot[1930]: time="2026-10-15T23:09:37+02:00" level=info msg="authenticated Login5" username="31************************2q" Oct 15 23:09:37 volumiopizero go-librespot[1930]: time="2026-10-15T23:09:37+02:00" level=info msg="accepted zeroconf from Zařízení S25 uživatele Martin" username="31************************2q" Oct 15 23:09:37 volumiopizero go-librespot[1930]: time="2026-10-15T23:09:37+02:00" level=debug msg="dealer connection opened" Oct 15 23:09:37 volumiopizero go-librespot[1930]: time="2026-10-15T23:09:37+02:00" level=trace msg="starting accesspoint recv loop" Oct 15 23:09:37 volumiopizero go-librespot[1930]: time="2026-10-15T23:09:37+02:00" level=trace msg="received accesspoint ping" Oct 15 23:09:37 volumiopizero go-librespot[1930]: time="2026-10-15T23:09:37+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Oct 15 23:09:37 volumiopizero go-librespot[1930]: time="2026-10-15T23:09:37+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Oct 15 23:09:37 volumiopizero go-librespot[1930]: time="2026-10-15T23:09:37+02:00" level=trace msg="starting dealer recv loop" Oct 15 23:09:37 volumiopizero go-librespot[1930]: time="2026-10-15T23:09:37+02:00" level=debug msg="received connection id: YTljYTc2MWUtNmEx...QUU2NjcwMDFBRQ==" Oct 15 23:09:38 volumiopizero go-librespot[1930]: time="2026-10-15T23:09:38+02:00" level=debug msg="put connect state because NEW_DEVICE" Oct 15 23:09:38 volumiopizero go-librespot[1930]: time="2026-10-15T23:09:38+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Oct 15 23:09:38 volumiopizero go-librespot[1930]: time="2026-10-15T23:09:38+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Oct 15 23:09:38 volumiopizero go-librespot[1930]: time="2026-10-15T23:09:38+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Oct 15 23:09:38 volumiopizero go-librespot[1930]: time="2026-10-15T23:09:38+02:00" level=trace msg="received accesspoint pong ack" Oct 15 23:09:38 volumiopizero go-librespot[1930]: time="2026-10-15T23:09:38+02:00" level=debug msg="handling transfer player command from c2fad6acc5544520e1208581e53cd2f6a84f9097" Oct 15 23:09:38 volumiopizero go-librespot[1930]: time="2026-10-15T23:09:38+02:00" level=debug msg="resolved context of track" uri="spotify:playlist:6UqbfyIU9JEP9vSxmN5Ou6" Oct 15 23:09:38 volumiopizero go-librespot[1930]: time="2026-10-15T23:09:38+02:00" level=trace msg="fetched new page 0 with 149 items (list: 149)" uri="spotify:playlist:6UqbfyIU9JEP9vSxmN5Ou6" Oct 15 23:09:38 volumiopizero go-librespot[1930]: time="2026-10-15T23:09:38+02:00" level=debug msg="loading track (paused: false, position: 31594ms)" uri="spotify:track:0TslIrrN4msR1roJKdxWXI" Oct 15 23:09:38 volumiopizero go-librespot[1930]: time="2026-10-15T23:09:38+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 15 23:09:38 volumiopizero go-librespot[1930]: time="2026-10-15T23:09:38+02:00" level=trace msg="emitting websocket event: will_play" Oct 15 23:09:38 volumiopizero go-librespot[1930]: time="2026-10-15T23:09:38+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 395" Oct 15 23:09:38 volumiopizero go-librespot[1930]: time="2026-10-15T23:09:38+02:00" level=debug msg="selected format OGG_VORBIS_320 (c5ddcfe0838007a32ecaf564482e9989a9fb58c4)" uri="spotify:track:0TslIrrN4msR1roJKdxWXI" Oct 15 23:09:38 volumiopizero go-librespot[1930]: time="2026-10-15T23:09:38+02:00" level=debug msg="requested aes key for file c5ddcfe0838007a32ecaf564482e9989a9fb58c4, gid: 0TslIrrN4msR1roJKdxWXI" Oct 15 23:09:38 volumiopizero systemd[1]: Started Music Player Daemon. Oct 15 23:09:38 volumiopizero sudo[1882]: pam_unix(sudo:session): session closed for user root Oct 15 23:09:38 volumiopizero sudo[1894]: pam_unix(sudo:session): session closed for user root Oct 15 23:09:38 volumiopizero go-librespot[1930]: time="2026-10-15T23:09:38+02:00" level=trace msg="found 2 cdn urls" uri="spotify:track:0TslIrrN4msR1roJKdxWXI" Oct 15 23:09:38 volumiopizero volumio[1687]: info: Completed starting Core Plugins Oct 15 23:09:38 volumiopizero volumio[1687]: info: ------------------------------------------- Oct 15 23:09:38 volumiopizero volumio[1687]: info: ----- MyVolumio plugins startup ---- Oct 15 23:09:38 volumiopizero volumio[1687]: info: ------------------------------------------- Oct 15 23:09:38 volumiopizero volumio[1687]: info: [MyVolumio PluginManager] Fetching plans data.... Oct 15 23:09:38 volumiopizero volumio[1687]: info: MPD running with PID1960 Oct 15 23:09:38 volumiopizero volumio[1687]: ,establishing connection Oct 15 23:09:38 volumiopizero volumio[1687]: error: MPD error: The expression evaluated to a falsy value: Oct 15 23:09:38 volumiopizero volumio[1687]: assert.ok(self.idling) Oct 15 23:09:38 volumiopizero volumio[1687]: error: The expression evaluated to a falsy value: Oct 15 23:09:38 volumiopizero volumio[1687]: assert.ok(self.idling) Oct 15 23:09:38 volumiopizero volumio[1687]: error: MPD error: The expression evaluated to a falsy value: Oct 15 23:09:38 volumiopizero volumio[1687]: assert.ok(self.idling) Oct 15 23:09:38 volumiopizero volumio[1687]: error: The expression evaluated to a falsy value: Oct 15 23:09:38 volumiopizero volumio[1687]: assert.ok(self.idling) Oct 15 23:09:38 volumiopizero volumio[1687]: error: updateQueue error: null Oct 15 23:09:38 volumiopizero go-librespot[1930]: time="2026-10-15T23:09:38+02:00" level=debug msg="fetched first chunk of 18, total size is 9148344 bytes" uri="spotify:track:0TslIrrN4msR1roJKdxWXI" Oct 15 23:09:38 volumiopizero volumio[1687]: info: Initializing connection to go-librespot Websocket Oct 15 23:09:38 volumiopizero go-librespot[1930]: time="2026-10-15T23:09:38+02:00" level=debug msg="new websocket client" Oct 15 23:09:38 volumiopizero volumio[1687]: info: Connection to go-librespot Websocket established Oct 15 23:09:38 volumiopizero go-librespot[1930]: time="2026-10-15T23:09:38+02:00" level=debug msg="fetched chunk 2/17, size: 524288" uri="spotify:track:0TslIrrN4msR1roJKdxWXI" Oct 15 23:09:38 volumiopizero go-librespot[1930]: time="2026-10-15T23:09:38+02:00" level=trace msg="seek to 31594ms (diff: 95ms, samples: 1393295, bytes: 1209237)" uri="spotify:track:0TslIrrN4msR1roJKdxWXI" Oct 15 23:09:38 volumiopizero go-librespot[1930]: time="2026-10-15T23:09:38+02:00" level=debug msg="created new output device" Oct 15 23:09:39 volumiopizero go-librespot[1930]: time="2026-10-15T23:09:39+02:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 100000 us, period size = 4410 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 5 frames, PCM format = FLOAT_LE" Oct 15 23:09:39 volumiopizero go-librespot[1930]: time="2026-10-15T23:09:39+02:00" level=info msg="loaded track \"Heart Of Steel - Festival Mix\" (paused: false, position: 31594ms, duration: 221132ms, prefetched: false)" uri="spotify:track:0TslIrrN4msR1roJKdxWXI" Oct 15 23:09:39 volumiopizero go-librespot[1930]: time="2026-10-15T23:09:39+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 15 23:09:39 volumiopizero go-librespot[1930]: time="2026-10-15T23:09:39+02:00" level=trace msg="scheduling prefetch in 159s" Oct 15 23:09:39 volumiopizero go-librespot[1930]: time="2026-10-15T23:09:39+02:00" level=trace msg="emitting websocket event: metadata" Oct 15 23:09:39 volumiopizero go-librespot[1930]: time="2026-10-15T23:09:39+02:00" level=trace msg="emitting websocket event: active" Oct 15 23:09:39 volumiopizero go-librespot[1930]: time="2026-10-15T23:09:39+02:00" level=debug msg="sending successful reply for dealer request" Oct 15 23:09:39 volumiopizero go-librespot[1930]: time="2026-10-15T23:09:39+02:00" level=debug msg="skipping dealer message" uri="hm://herodotus/uri/spotify:playlist:6UqbfyIU9JEP9vSxmN5Ou6/resume-point-revision/5b689b17-b2a9-40ec-ac79-10af38f7a0ac" Oct 15 23:09:39 volumiopizero volumio[1687]: info: Aligning Spotify Volume to Volumio Volume Oct 15 23:09:39 volumiopizero volumio[1687]: info: CoreCommandRouter::volumioGetState Oct 15 23:09:39 volumiopizero volumio[1687]: info: CorePlayQueue::getTrack 0 Oct 15 23:09:39 volumiopizero volumio[1687]: info: Setting Spotify Volume from Volumio: 94 Oct 15 23:09:39 volumiopizero go-librespot[1930]: time="2026-10-15T23:09:39+02:00" level=debug msg="fetched chunk 3/17, size: 524288" uri="spotify:track:0TslIrrN4msR1roJKdxWXI" Oct 15 23:09:39 volumiopizero go-librespot[1930]: time="2026-10-15T23:09:39+02:00" level=debug msg="fetched chunk 1/17, size: 524288" uri="spotify:track:0TslIrrN4msR1roJKdxWXI" Oct 15 23:09:39 volumiopizero go-librespot[1930]: time="2026-10-15T23:09:39+02:00" level=debug msg="fetched chunk 4/17, size: 524288" uri="spotify:track:0TslIrrN4msR1roJKdxWXI" Oct 15 23:09:39 volumiopizero go-librespot[1930]: time="2026-10-15T23:09:39+02:00" level=debug msg="fetched chunk 5/17, size: 524288" uri="spotify:track:0TslIrrN4msR1roJKdxWXI" Oct 15 23:09:39 volumiopizero go-librespot[1930]: time="2026-10-15T23:09:39+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 15 23:09:39 volumiopizero go-librespot[1930]: time="2026-10-15T23:09:39+02:00" level=trace msg="emitting websocket event: playing" Oct 15 23:09:39 volumiopizero volumio[1687]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 15 23:09:39 volumiopizero volumio[1687]: TypeError: Cannot read property 'service' of undefined Oct 15 23:09:39 volumiopizero volumio[1687]: at ControllerSpotify.identifyPlaybackMode (/data/plugins/music_service/spop/index.js:345:50) Oct 15 23:09:39 volumiopizero volumio[1687]: at ControllerSpotify.parseEventState (/data/plugins/music_service/spop/index.js:271:18) Oct 15 23:09:39 volumiopizero volumio[1687]: at WebSocket.message (/data/plugins/music_service/spop/index.js:198:14) Oct 15 23:09:39 volumiopizero volumio[1687]: at WebSocket.emit (events.js:315:20) Oct 15 23:09:39 volumiopizero volumio[1687]: at Receiver.receiverOnMessage (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1239:20) Oct 15 23:09:39 volumiopizero volumio[1687]: at Receiver.emit (events.js:315:20) Oct 15 23:09:39 volumiopizero volumio[1687]: at Receiver.dataMessage (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:633:14) Oct 15 23:09:39 volumiopizero volumio[1687]: at Receiver.getData (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:532:10) Oct 15 23:09:39 volumiopizero volumio[1687]: at Receiver.startLoop (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:190:16) Oct 15 23:09:39 volumiopizero volumio[1687]: at Receiver._write (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:117:10) Oct 15 23:09:39 volumiopizero volumio[1687]: at writeOrBuffer (internal/streams/writable.js:358:12) Oct 15 23:09:39 volumiopizero volumio[1687]: at Receiver.Writable.write (internal/streams/writable.js:303:10) Oct 15 23:09:39 volumiopizero volumio[1687]: at Socket.socketOnData (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1374:35) Oct 15 23:09:39 volumiopizero volumio[1687]: at Socket.emit (events.js:315:20) Oct 15 23:09:39 volumiopizero volumio[1687]: at addChunk (internal/streams/readable.js:309:12) Oct 15 23:09:39 volumiopizero volumio[1687]: at readableAddChunk (internal/streams/readable.js:284:9) Oct 15 23:09:39 volumiopizero volumio[1687]: at Socket.Readable.push (internal/streams/readable.js:223:10) Oct 15 23:09:39 volumiopizero volumio[1687]: at TCP.onStreamRead (internal/stream_base_commons.js:188:23) Oct 15 23:09:39 volumiopizero volumio[1687]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 15 23:09:40 volumiopizero sudo[2044]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-10-15 23:08 Oct 15 23:09:40 volumiopizero sudo[2044]: 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="5c0e72e4b5817fe66c91c9d15d75ff13b8e3f4ba" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 30 Mar 2026 02:51:29 PM CEST" VOLUMIO_VERSION="3.914" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="98fe12c88d8ff391f569279e2f874892"