-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Mon 2025-03-10 14:03:40 CET. -- Mar 10 14:02:32 volumio-wohnzimmer systemd-timedated[1213]: Changed local time to Mon Mar 10 14:02:32 2025 Mar 10 14:02:32 volumio-wohnzimmer sudo[1191]: pam_unix(sudo:session): session closed for user root Mar 10 14:02:32 volumio-wohnzimmer volumio-time-update[540]: volumio-time-update-util: System time updated successfully. Mar 10 14:02:32 volumio-wohnzimmer volumio[1064]: info: Loading plugin "upnp"... Mar 10 14:02:32 volumio-wohnzimmer volumio[1064]: info: [1741611752054] Starting Upmpd Daemon Mar 10 14:02:32 volumio-wohnzimmer volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 10 14:02:32 volumio-wohnzimmer volumio[1064]: info: Loading plugin "my_music"... Mar 10 14:02:32 volumio-wohnzimmer systemd[1]: Starting Daily apt download activities... Mar 10 14:02:32 volumio-wohnzimmer systemd[1]: Started Volumio Time Update Utility. Mar 10 14:02:32 volumio-wohnzimmer systemd[1]: Reached target Multi-User System. Mar 10 14:02:32 volumio-wohnzimmer systemd[1]: Reached target Graphical Interface. Mar 10 14:02:32 volumio-wohnzimmer volumio[1064]: info: Loading plugin "mpd"... Mar 10 14:02:32 volumio-wohnzimmer systemd[1]: Starting Update UTMP about System Runlevel Changes... Mar 10 14:02:32 volumio-wohnzimmer systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Mar 10 14:02:32 volumio-wohnzimmer systemd[1]: Started Update UTMP about System Runlevel Changes. Mar 10 14:02:32 volumio-wohnzimmer kernel: Key type cifs.spnego registered Mar 10 14:02:32 volumio-wohnzimmer kernel: Key type cifs.idmap registered Mar 10 14:02:32 volumio-wohnzimmer kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. Mar 10 14:02:32 volumio-wohnzimmer kernel: CIFS: Attempting to mount //moria.fritz.box/music Mar 10 14:02:32 volumio-wohnzimmer volumio[1064]: info: Loading plugin "upnp_browser"... Mar 10 14:02:32 volumio-wohnzimmer ntpd[1132]: Soliciting pool server 185.232.69.65 Mar 10 14:02:32 volumio-wohnzimmer ntpd[1132]: Soliciting pool server 78.46.230.13 Mar 10 14:02:33 volumio-wohnzimmer volumio[1064]: info: Loading plugin "alarm-clock"... Mar 10 14:02:33 volumio-wohnzimmer volumio[1064]: info: Loading plugin "airplay_emulation"... Mar 10 14:02:33 volumio-wohnzimmer volumio[1064]: info: Starting Shairport Sync Mar 10 14:02:33 volumio-wohnzimmer volumio[1064]: info: Loading plugin "last_100"... Mar 10 14:02:33 volumio-wohnzimmer volumio[1064]: info: Loading plugin "webradio"... Mar 10 14:02:33 volumio-wohnzimmer volumio[1064]: info: Loading plugin "i2s_dacs"... Mar 10 14:02:33 volumio-wohnzimmer volumio[1064]: info: Loading plugin "volumiodiscovery"... Mar 10 14:02:33 volumio-wohnzimmer ntpd[1132]: Soliciting pool server 185.252.140.125 Mar 10 14:02:33 volumio-wohnzimmer volumio[1064]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 10 14:02:33 volumio-wohnzimmer volumio[1064]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 10 14:02:33 volumio-wohnzimmer volumio[1064]: *** WARNING *** For more information see Mar 10 14:02:33 volumio-wohnzimmer node[1064]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 10 14:02:33 volumio-wohnzimmer volumio[1064]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 10 14:02:33 volumio-wohnzimmer volumio[1064]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 10 14:02:33 volumio-wohnzimmer volumio[1064]: *** WARNING *** For more information see Mar 10 14:02:33 volumio-wohnzimmer node[1064]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 10 14:02:33 volumio-wohnzimmer node[1064]: *** WARNING *** For more information see Mar 10 14:02:33 volumio-wohnzimmer node[1064]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 10 14:02:33 volumio-wohnzimmer node[1064]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 10 14:02:33 volumio-wohnzimmer node[1064]: *** WARNING *** For more information see Mar 10 14:02:33 volumio-wohnzimmer volumio[1064]: info: Applying required configuration parameters for plugin volumiodiscovery Mar 10 14:02:33 volumio-wohnzimmer volumio[1064]: info: Discovery: Started advertising with name: Volumio Wohnzimmer Mar 10 14:02:33 volumio-wohnzimmer volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 10 14:02:33 volumio-wohnzimmer volumio[1064]: info: Loading plugin "soundcloud"... Mar 10 14:02:33 volumio-wohnzimmer volumio-remote-updater[536]: [2025-03-10 14:02:33] [connect] Successful connection Mar 10 14:02:34 volumio-wohnzimmer ntpd[1132]: Soliciting pool server 2001:638:504:2000::32 Mar 10 14:02:34 volumio-wohnzimmer volumio[1064]: info: Loading plugin "spop"... Mar 10 14:02:38 volumio-wohnzimmer sudo[1218]: pam_unix(sudo:session): session closed for user root Mar 10 14:02:38 volumio-wohnzimmer kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Mar 10 14:02:38 volumio-wohnzimmer kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Mar 10 14:02:43 volumio-wohnzimmer volumio[1064]: info: Loading plugin "youtube2"... Mar 10 14:02:43 volumio-wohnzimmer systemd[1]: apt-daily.service: Succeeded. Mar 10 14:02:43 volumio-wohnzimmer systemd[1]: Started Daily apt download activities. Mar 10 14:02:44 volumio-wohnzimmer systemd[1]: Starting Daily apt upgrade and clean activities... Mar 10 14:02:45 volumio-wohnzimmer systemd[1]: apt-daily-upgrade.service: Succeeded. Mar 10 14:02:45 volumio-wohnzimmer systemd[1]: Started Daily apt upgrade and clean activities. Mar 10 14:02:45 volumio-wohnzimmer systemd[1]: Startup finished in 13.435s (kernel) + 28.408s (userspace) = 41.844s. Mar 10 14:02:49 volumio-wohnzimmer volumio[1064]: info: Loading plugin "ytcr"... Mar 10 14:02:50 volumio-wohnzimmer systemd[1]: systemd-fsckd.service: Succeeded. Mar 10 14:02:50 volumio-wohnzimmer volumio-remote-updater[536]: [2025-03-10 14:02:50] [connect] Successful connection Mar 10 14:02:51 volumio-wohnzimmer volumio[1064]: info: Loading plugin "now_playing"... Mar 10 14:02:53 volumio-wohnzimmer volumio[1064]: info: Loading plugin "outputs"... Mar 10 14:02:53 volumio-wohnzimmer volumio[1064]: info: Loading plugin "albumart"... Mar 10 14:02:53 volumio-wohnzimmer volumio[1064]: info: Plugin example_plugin is not enabled Mar 10 14:02:53 volumio-wohnzimmer volumio[1064]: info: Loading plugin "inputs"... Mar 10 14:02:53 volumio-wohnzimmer volumio[1064]: info: Loading plugin "updater_comm"... Mar 10 14:02:53 volumio-wohnzimmer volumio[1064]: info: Plugin mpdemulation is not enabled Mar 10 14:02:53 volumio-wohnzimmer volumio[1064]: info: Loading plugin "rest_api"... Mar 10 14:02:53 volumio-wohnzimmer volumio[1064]: info: Loading plugin "websocket"... Mar 10 14:02:53 volumio-wohnzimmer volumio[1064]: info: Starting Socket.io Server version 2.3.0 Mar 10 14:02:53 volumio-wohnzimmer volumio[1064]: info: Loading plugin "80s80s"... Mar 10 14:02:53 volumio-wohnzimmer volumio[1064]: Forking 3 albumart workers Mar 10 14:02:54 volumio-wohnzimmer volumio[1064]: info: Applying required configuration parameters for plugin 80s80s Mar 10 14:02:54 volumio-wohnzimmer volumio[1064]: info: [1741611774038] [80s80s] API delay: 30 Mar 10 14:02:54 volumio-wohnzimmer volumio[1064]: info: Loading plugin "Systeminfo"... Mar 10 14:02:54 volumio-wohnzimmer systemd[1]: systemd-hostnamed.service: Succeeded. Mar 10 14:02:54 volumio-wohnzimmer volumio[1064]: Starting albumart workers Mar 10 14:02:54 volumio-wohnzimmer volumio[1064]: Starting albumart workers Mar 10 14:02:54 volumio-wohnzimmer volumio[1064]: Starting albumart workers Mar 10 14:02:54 volumio-wohnzimmer volumio[1064]: info: Loading i18n strings for locale de Mar 10 14:02:54 volumio-wohnzimmer volumio[1064]: Updating browse sources language Mar 10 14:02:54 volumio-wohnzimmer volumio[1064]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 10 14:02:55 volumio-wohnzimmer volumio[1064]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 10 14:02:55 volumio-wohnzimmer volumio[1064]: info: CoreCommandRouter::initPlayerControls Mar 10 14:02:55 volumio-wohnzimmer volumio[1064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 10 14:02:55 volumio-wohnzimmer volumio[1064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 10 14:02:55 volumio-wohnzimmer volumio[1064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 10 14:02:55 volumio-wohnzimmer volumio[1064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 10 14:02:55 volumio-wohnzimmer volumio[1064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 10 14:02:55 volumio-wohnzimmer volumio[1064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 10 14:02:55 volumio-wohnzimmer volumio[1064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 10 14:02:55 volumio-wohnzimmer volumio[1064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 10 14:02:55 volumio-wohnzimmer volumio[1064]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 10 14:02:55 volumio-wohnzimmer volumio[1064]: Express server listening on port 3000 Mar 10 14:02:55 volumio-wohnzimmer volumio[1064]: [Metrics] WebUI: 26s 4.87ms Mar 10 14:02:55 volumio-wohnzimmer volumio[1064]: info: CoreStateMachine::resetVolumioState Mar 10 14:02:55 volumio-wohnzimmer volumio[1064]: info: CoreStateMachine::getcurrentVolume Mar 10 14:02:55 volumio-wohnzimmer volumio[1064]: info: CoreCommandRouter::volumioRetrievevolume Mar 10 14:02:55 volumio-wohnzimmer volumio[1064]: info: CoreStateMachine::pushState Mar 10 14:02:55 volumio-wohnzimmer volumio[1064]: info: CorePlayQueue::getTrack 0 Mar 10 14:02:55 volumio-wohnzimmer volumio[1064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 10 14:02:55 volumio-wohnzimmer volumio[1064]: info: CoreCommandRouter::volumioPushState Mar 10 14:02:55 volumio-wohnzimmer sudo[1428]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 10 14:02:55 volumio-wohnzimmer sudo[1428]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 10 14:02:55 volumio-wohnzimmer sudo[1428]: pam_unix(sudo:session): session closed for user root Mar 10 14:02:55 volumio-wohnzimmer sudo[1430]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 10 14:02:55 volumio-wohnzimmer sudo[1430]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 10 14:02:55 volumio-wohnzimmer sudo[1430]: pam_unix(sudo:session): session closed for user root Mar 10 14:02:55 volumio-wohnzimmer volumio[1064]: info: Volumio Network Manager: Network status updated: 1 Mar 10 14:02:55 volumio-wohnzimmer volumio[1064]: info: Cannot mount NAS Moria at system boot, trial number 1 ,retrying in 5 seconds Mar 10 14:02:55 volumio-wohnzimmer volumio[1064]: info: CoreCommandRouter::volumioGetState Mar 10 14:02:55 volumio-wohnzimmer volumio[1064]: info: CorePlayQueue::getTrack 0 Mar 10 14:02:55 volumio-wohnzimmer volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 10 14:02:55 volumio-wohnzimmer volumio[1064]: info: Reloading queue from file Mar 10 14:02:55 volumio-wohnzimmer volumio[1064]: info: CoreStateMachine::setRepeat null single undefined Mar 10 14:02:55 volumio-wohnzimmer volumio[1064]: info: CoreStateMachine::pushState Mar 10 14:02:55 volumio-wohnzimmer volumio[1064]: info: CorePlayQueue::getTrack 0 Mar 10 14:02:55 volumio-wohnzimmer volumio[1064]: info: CoreCommandRouter::volumioPushState Mar 10 14:02:55 volumio-wohnzimmer volumio[1064]: info: CoreStateMachine::setRandom null Mar 10 14:02:55 volumio-wohnzimmer volumio[1064]: info: CoreStateMachine::pushState Mar 10 14:02:55 volumio-wohnzimmer volumio[1064]: info: CorePlayQueue::getTrack 0 Mar 10 14:02:55 volumio-wohnzimmer volumio[1064]: info: CoreCommandRouter::volumioPushState Mar 10 14:02:55 volumio-wohnzimmer volumio[1064]: info: Setting Device type: Raspberry PI Mar 10 14:02:55 volumio-wohnzimmer volumio[1064]: info: Listing playlists Mar 10 14:02:55 volumio-wohnzimmer volumio[1064]: info: Listing playlists Mar 10 14:02:55 volumio-wohnzimmer volumio[1064]: info: Completed loading Core Plugins Mar 10 14:02:55 volumio-wohnzimmer volumio[1064]: info: Preparing to generate the ALSA configuration file Mar 10 14:02:55 volumio-wohnzimmer volumio[1064]: info: Discovery: adding 07538e23-5acd-4bc4-986f-bbe258cd367f Mar 10 14:02:55 volumio-wohnzimmer volumio[1064]: info: Discovery: Found device Volumio Wohnzimmer Mar 10 14:02:55 volumio-wohnzimmer volumio[1064]: info: CoreCommandRouter::volumioGetState Mar 10 14:02:55 volumio-wohnzimmer volumio[1064]: info: CorePlayQueue::getTrack 0 Mar 10 14:02:55 volumio-wohnzimmer volumio[1064]: info: Asound.conf file unchanged, so no further update is needed Mar 10 14:02:55 volumio-wohnzimmer volumio[1064]: info: Output device has changed, restarting MPD Mar 10 14:02:55 volumio-wohnzimmer sudo[1439]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Mar 10 14:02:55 volumio-wohnzimmer sudo[1439]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 10 14:02:55 volumio-wohnzimmer sudo[1442]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 10 14:02:55 volumio-wohnzimmer sudo[1442]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 10 14:02:55 volumio-wohnzimmer volumio[1064]: info: Output device has changed, restarting Shairport Sync Mar 10 14:02:55 volumio-wohnzimmer volumio[1064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 10 14:02:55 volumio-wohnzimmer volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 10 14:02:55 volumio-wohnzimmer sudo[1442]: pam_unix(sudo:session): session closed for user root Mar 10 14:02:55 volumio-wohnzimmer sudo[1446]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 10 14:02:55 volumio-wohnzimmer systemd[1]: Started UPnP Renderer front-end to MPD. Mar 10 14:02:55 volumio-wohnzimmer sudo[1446]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 10 14:02:55 volumio-wohnzimmer sudo[1439]: pam_unix(sudo:session): session closed for user root Mar 10 14:02:55 volumio-wohnzimmer systemd[1]: Stopping Music Player Daemon... Mar 10 14:02:55 volumio-wohnzimmer volumio[1064]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 10 14:02:55 volumio-wohnzimmer volumio[1064]: info: ___________ START PLUGINS ___________ Mar 10 14:02:55 volumio-wohnzimmer volumio[1064]: info: ControllerMpd::onStart: Initializing MPD Mar 10 14:02:55 volumio-wohnzimmer volumio[1064]: info: Creating MPD Configuration file Mar 10 14:02:55 volumio-wohnzimmer sudo[1452]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 10 14:02:55 volumio-wohnzimmer sudo[1452]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 10 14:02:55 volumio-wohnzimmer sudo[1452]: pam_unix(sudo:session): session closed for user root Mar 10 14:02:55 volumio-wohnzimmer volumio[1064]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 10 14:02:55 volumio-wohnzimmer volumio[1064]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 10 14:02:55 volumio-wohnzimmer volumio[1064]: info: [1741611775837] CoreMusicLibrary::Adding element Medienserver Mar 10 14:02:55 volumio-wohnzimmer volumio[1064]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 10 14:02:55 volumio-wohnzimmer sudo[1455]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 10 14:02:55 volumio-wohnzimmer sudo[1455]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 10 14:02:55 volumio-wohnzimmer volumio[1064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 10 14:02:55 volumio-wohnzimmer volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 10 14:02:55 volumio-wohnzimmer volumio[1064]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 10 14:02:55 volumio-wohnzimmer volumio[1064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 10 14:02:55 volumio-wohnzimmer volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 10 14:02:55 volumio-wohnzimmer volumio[1064]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 10 14:02:55 volumio-wohnzimmer volumio[1064]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 10 14:02:55 volumio-wohnzimmer volumio[1064]: info: [1741611775976] CoreMusicLibrary::Adding element Last_100 Mar 10 14:02:55 volumio-wohnzimmer volumio[1064]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 10 14:02:55 volumio-wohnzimmer volumio[1064]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 10 14:02:55 volumio-wohnzimmer volumio[1064]: info: [1741611775980] CoreMusicLibrary::Adding element Webradio Mar 10 14:02:55 volumio-wohnzimmer volumio[1064]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 10 14:02:55 volumio-wohnzimmer systemd[1]: mpd.service: Succeeded. Mar 10 14:02:55 volumio-wohnzimmer volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 10 14:02:55 volumio-wohnzimmer systemd[1]: Stopped Music Player Daemon. Mar 10 14:02:56 volumio-wohnzimmer volumio[1064]: info: Initializing BBC Radios Mar 10 14:02:56 volumio-wohnzimmer systemd[1]: Starting Music Player Daemon... Mar 10 14:02:56 volumio-wohnzimmer volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 10 14:02:56 volumio-wohnzimmer volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 10 14:02:56 volumio-wohnzimmer volumio[1064]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 10 14:02:56 volumio-wohnzimmer volumio[1064]: info: [1741611776093] CoreMusicLibrary::Adding element SoundCloud Mar 10 14:02:56 volumio-wohnzimmer volumio[1064]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 10 14:02:56 volumio-wohnzimmer volumio[1064]: Cannot find translation for source SoundCloud Mar 10 14:02:56 volumio-wohnzimmer volumio[1064]: info: Creating Spotify config file Mar 10 14:02:56 volumio-wohnzimmer volumio[1064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 10 14:02:56 volumio-wohnzimmer sudo[1463]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 10 14:02:56 volumio-wohnzimmer sudo[1463]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 10 14:02:56 volumio-wohnzimmer sudo[1463]: pam_unix(sudo:session): session closed for user root Mar 10 14:02:56 volumio-wohnzimmer volumio[1064]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 10 14:02:56 volumio-wohnzimmer volumio[1064]: info: [1741611776213] CoreMusicLibrary::Adding element YouTube2 Mar 10 14:02:56 volumio-wohnzimmer volumio[1064]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 10 14:02:56 volumio-wohnzimmer volumio[1064]: Cannot find translation for source SoundCloud Mar 10 14:02:56 volumio-wohnzimmer volumio[1064]: Cannot find translation for source YouTube2 Mar 10 14:02:56 volumio-wohnzimmer volumio[1064]: info: [now-playing] ConfigUpdater: config is up to date. Mar 10 14:02:56 volumio-wohnzimmer volumio[1064]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 10 14:02:56 volumio-wohnzimmer volumio[1064]: info: [1741611776271] CoreMusicLibrary::Adding element 80s80s Radio Mar 10 14:02:56 volumio-wohnzimmer volumio[1064]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 10 14:02:56 volumio-wohnzimmer volumio[1064]: Cannot find translation for source SoundCloud Mar 10 14:02:56 volumio-wohnzimmer volumio[1064]: Cannot find translation for source YouTube2 Mar 10 14:02:56 volumio-wohnzimmer volumio[1064]: Cannot find translation for source 80s80s Radio Mar 10 14:02:56 volumio-wohnzimmer volumio[1064]: info: Volumio Calling Home Mar 10 14:02:56 volumio-wohnzimmer volumio[1064]: info: [now-playing] App is listening on port 4004. Mar 10 14:02:56 volumio-wohnzimmer volumio[1064]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Mar 10 14:02:56 volumio-wohnzimmer volumio[1064]: info: MPD Permissions set Mar 10 14:02:56 volumio-wohnzimmer volumio[1064]: info: MPD Permissions set Mar 10 14:02:56 volumio-wohnzimmer volumio[1064]: info: Upmpdcli Daemon Started Mar 10 14:02:56 volumio-wohnzimmer volumio[1064]: info: Spotify config file written Mar 10 14:02:56 volumio-wohnzimmer sudo[1480]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Mar 10 14:02:56 volumio-wohnzimmer volumio[1064]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Mar 10 14:02:56 volumio-wohnzimmer volumio[1064]: info: Volumio called home Mar 10 14:02:56 volumio-wohnzimmer sudo[1480]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 10 14:02:56 volumio-wohnzimmer volumio[1064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 10 14:02:56 volumio-wohnzimmer volumio[1064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 10 14:02:56 volumio-wohnzimmer volumio[1064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 10 14:02:56 volumio-wohnzimmer volumio[1064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 10 14:02:56 volumio-wohnzimmer volumio[1064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 10 14:02:56 volumio-wohnzimmer volumio[1064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 10 14:02:56 volumio-wohnzimmer volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 10 14:02:56 volumio-wohnzimmer volumio[1064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 10 14:02:56 volumio-wohnzimmer volumio[1064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 10 14:02:56 volumio-wohnzimmer volumio[1064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 10 14:02:56 volumio-wohnzimmer volumio[1064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 10 14:02:56 volumio-wohnzimmer volumio[1064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 10 14:02:56 volumio-wohnzimmer systemd[1]: Started go-librespot Daemon. Mar 10 14:02:56 volumio-wohnzimmer go-librespot[1486]: Librespot-go daemon starting... Mar 10 14:02:56 volumio-wohnzimmer volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 10 14:02:56 volumio-wohnzimmer volumio[1064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 10 14:02:56 volumio-wohnzimmer volumio[1064]: info: No need to fix Spotify hosts Mar 10 14:02:56 volumio-wohnzimmer sudo[1480]: pam_unix(sudo:session): session closed for user root Mar 10 14:02:57 volumio-wohnzimmer volumio[1064]: info: Starting Shairport Sync Mar 10 14:02:57 volumio-wohnzimmer volumio[1064]: info: Starting Shairport Sync Mar 10 14:02:57 volumio-wohnzimmer sudo[1498]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 10 14:02:57 volumio-wohnzimmer sudo[1498]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 10 14:02:57 volumio-wohnzimmer volumio[1064]: info: Starting Shairport Sync Mar 10 14:02:57 volumio-wohnzimmer sudo[1500]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 10 14:02:57 volumio-wohnzimmer volumio[1064]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Mar 10 14:02:57 volumio-wohnzimmer volumio[1064]: SPOTIFY: BQBvTLQnnqShbiGUC2dgNeL4Ss8E2CV0ejTfQ6A_zbX2-OOt4-XGhytzSfP0MxyZfSN9QkveEpsFNVSGmDclY4VpbKCASwZdDBj_ZyrcJAazhmTcxyEf91Q1NZ3266abUWjTWDhYSWm4s5_lwPLgUV6lHZoWix_YoQz0Zzr-ZTH7KOv8gSf-5Z5yOQIMLu4T95Gj16gJlhqbjwbnyYgqU6y9nuPhATxzC_7PO7P8Kwjmsif3EqGri1uBYKjfr8tHjWEIUUX-kLTMRXX70EhkJFtKRrRAElspZetmyX0 Mar 10 14:02:57 volumio-wohnzimmer volumio[1064]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Mar 10 14:02:57 volumio-wohnzimmer sudo[1500]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 10 14:02:57 volumio-wohnzimmer systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 10 14:02:57 volumio-wohnzimmer volumio[1064]: info: New Spotify access token = BQBvTLQnnqShbiGUC2dgNeL4Ss8E2CV0ejTfQ6A_zbX2-OOt4-XGhytzSfP0MxyZfSN9QkveEpsFNVSGmDclY4VpbKCASwZdDBj_ZyrcJAazhmTcxyEf91Q1NZ3266abUWjTWDhYSWm4s5_lwPLgUV6lHZoWix_YoQz0Zzr-ZTH7KOv8gSf-5Z5yOQIMLu4T95Gj16gJlhqbjwbnyYgqU6y9nuPhATxzC_7PO7P8Kwjmsif3EqGri1uBYKjfr8tHjWEIUUX-kLTMRXX70EhkJFtKRrRAElspZetmyX0 Mar 10 14:02:57 volumio-wohnzimmer volumio[1064]: info: Spotify credentials grant success - running version from March 24, 2019 Mar 10 14:02:57 volumio-wohnzimmer systemd[1]: shairport-sync.service: Succeeded. Mar 10 14:02:57 volumio-wohnzimmer sudo[1503]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 10 14:02:57 volumio-wohnzimmer sudo[1503]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 10 14:02:57 volumio-wohnzimmer volumio[1064]: info: CoreCommandRouter::volumioGetState Mar 10 14:02:57 volumio-wohnzimmer volumio[1064]: info: CorePlayQueue::getTrack 0 Mar 10 14:02:57 volumio-wohnzimmer systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 10 14:02:57 volumio-wohnzimmer systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 10 14:02:57 volumio-wohnzimmer sudo[1498]: pam_unix(sudo:session): session closed for user root Mar 10 14:02:57 volumio-wohnzimmer systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 10 14:02:57 volumio-wohnzimmer systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Mar 10 14:02:57 volumio-wohnzimmer systemd[1]: shairport-sync.service: Succeeded. Mar 10 14:02:57 volumio-wohnzimmer systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 10 14:02:57 volumio-wohnzimmer systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 10 14:02:57 volumio-wohnzimmer sudo[1500]: pam_unix(sudo:session): session closed for user root Mar 10 14:02:57 volumio-wohnzimmer sudo[1503]: pam_unix(sudo:session): session closed for user root Mar 10 14:02:57 volumio-wohnzimmer volumio[1064]: info: Shairport-Sync Started Mar 10 14:02:57 volumio-wohnzimmer volumio[1064]: Error adding Membership: Error: addMembership EINVAL Mar 10 14:02:57 volumio-wohnzimmer volumio[1064]: info: Shairport-Sync Started Mar 10 14:02:57 volumio-wohnzimmer volumio[1064]: info: Shairport-Sync Started Mar 10 14:02:57 volumio-wohnzimmer volumio[1064]: info: CoreCommandRouter::volumioGetState Mar 10 14:02:57 volumio-wohnzimmer volumio[1064]: info: CorePlayQueue::getTrack 0 Mar 10 14:02:57 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:02:57+01:00" level=info msg="generated new device id: 408f4e47f35f85c39ecd7dd2c49af515edbd046a" Mar 10 14:02:57 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:02:57+01:00" level=debug msg="stored credentials found for kwakketfephc24zgbw35608uj" Mar 10 14:02:57 volumio-wohnzimmer volumio[1064]: SPOTIFY: User informations: {"country":"DE","display_name":"GRKPI","email":"spotify@sr-club.de","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/kwakketfephc24zgbw35608uj"},"followers":{"href":null,"total":2},"href":"https://api.spotify.com/v1/users/kwakketfephc24zgbw35608uj","id":"kwakketfephc24zgbw35608uj","images":[],"product":"premium","type":"user","uri":"spotify:user:kwakketfephc24zgbw35608uj"} Mar 10 14:02:57 volumio-wohnzimmer volumio[1064]: info: Spotify Successfully logged in Mar 10 14:02:57 volumio-wohnzimmer volumio[1064]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 10 14:02:57 volumio-wohnzimmer volumio[1064]: info: [1741611777358] CoreMusicLibrary::Adding element Spotify Mar 10 14:02:57 volumio-wohnzimmer volumio[1064]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 10 14:02:57 volumio-wohnzimmer volumio[1064]: Cannot find translation for source SoundCloud Mar 10 14:02:57 volumio-wohnzimmer volumio[1064]: Cannot find translation for source YouTube2 Mar 10 14:02:57 volumio-wohnzimmer volumio[1064]: Cannot find translation for source 80s80s Radio Mar 10 14:02:57 volumio-wohnzimmer volumio[1064]: Cannot find translation for source Spotify Mar 10 14:02:57 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:02:57+01: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]" Mar 10 14:02:57 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:02:57+01: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]" Mar 10 14:02:57 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:02:57+01: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]" Mar 10 14:02:57 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:02:57+01:00" level=debug msg="zeroconf server listening on port 43745" Mar 10 14:02:57 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:02:57+01:00" level=debug msg="obtained new client token: AACkABY5pUFjWh7maxBCJ+gf9jNlg+0ULHv5ev4mgWGu9xRM4x7rtSQhEY+JrDEK8gdCe4NQTpOi7u/CxdyggE8adH5HRpw+Xi33E2mCcg3//G2diVxiJWeOQ+e5z0ld6onmr3OyijPz7M4zj+CRg/IlMEuNhuFYkh5fIpv8NA1OohtI1nveEM3XiyyBgeq3WD/BHxSfZ2sYYlgEtlD/HK+1pGZeTDDA9U6mk0b9+6fitlODQHjS2omYmQ==" Mar 10 14:02:57 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:02:57+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Mar 10 14:02:57 volumio-wohnzimmer mpd[1475]: Mar 10 14:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 10 14:02:57 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:02:57+01:00" level=debug msg="completed keyexchange" Mar 10 14:02:58 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:02:58+01:00" level=debug msg="completed challenge" Mar 10 14:02:58 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:02:58+01:00" level=debug msg="authenticated as kwakketfephc24zgbw35608uj" Mar 10 14:02:58 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:02:58+01:00" level=debug msg="authenticated as kwakketfephc24zgbw35608uj" Mar 10 14:02:58 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:02:58+01:00" level=debug msg="dealer connection opened" Mar 10 14:02:58 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:02:58+01:00" level=debug msg="initializing zeroconf session, username: kwakketfephc24zgbw35608uj" Mar 10 14:02:58 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:02:58+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Mar 10 14:02:58 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:02:58+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Mar 10 14:02:58 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:02:58+01:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Mar 10 14:02:58 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:02:58+01:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Mar 10 14:02:58 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:02:58+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Mar 10 14:02:58 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:02:58+01:00" level=debug msg="autoplay enabled: false" Mar 10 14:02:58 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:02:58+01:00" level=debug msg="received connection id: MjEzMWM1M2MtMTlhZC00MmNmLTg1ZTQtMjM3ZTY0ZjlmZTFmK2RlYWxlcit0Y3A6Ly8wYWNhNThiZC5pcC5nZXc0LnNwb3RpZnkubmV0OjU3MDArNTMwQkVFRkY1RUNDQkU5N0IwQUFEOTZBRDRGMTFFQzc1REE5OEVFQTY3ODVFQzVDRjYzRTE4MEMwMjc4QTNEMw==" Mar 10 14:02:58 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:02:58+01:00" level=debug msg="put connect state because NEW_DEVICE" Mar 10 14:02:58 volumio-wohnzimmer systemd[1]: Started Music Player Daemon. Mar 10 14:02:58 volumio-wohnzimmer sudo[1446]: pam_unix(sudo:session): session closed for user root Mar 10 14:02:58 volumio-wohnzimmer volumio[1064]: Upnp client error: Error: This socket has been ended by the other party Mar 10 14:02:58 volumio-wohnzimmer sudo[1455]: pam_unix(sudo:session): session closed for user root Mar 10 14:02:58 volumio-wohnzimmer volumio[1064]: error: MPD error: The expression evaluated to a falsy value: Mar 10 14:02:58 volumio-wohnzimmer volumio[1064]: assert.ok(self.idling) Mar 10 14:02:58 volumio-wohnzimmer volumio[1064]: error: The expression evaluated to a falsy value: Mar 10 14:02:58 volumio-wohnzimmer volumio[1064]: assert.ok(self.idling) Mar 10 14:02:58 volumio-wohnzimmer volumio[1064]: info: MPD running with PID1475 Mar 10 14:02:58 volumio-wohnzimmer volumio[1064]: ,establishing connection Mar 10 14:02:58 volumio-wohnzimmer volumio[1064]: error: updateQueue error: null Mar 10 14:02:58 volumio-wohnzimmer volumio[1064]: [yt-cast-receiver] Listening on port 8098 Mar 10 14:02:59 volumio-wohnzimmer volumio[1064]: info: Completed starting Core Plugins Mar 10 14:02:59 volumio-wohnzimmer volumio[1064]: info: ------------------------------------------- Mar 10 14:02:59 volumio-wohnzimmer volumio[1064]: info: ----- MyVolumio plugins startup ---- Mar 10 14:02:59 volumio-wohnzimmer volumio[1064]: info: ------------------------------------------- Mar 10 14:02:59 volumio-wohnzimmer volumio[1064]: info: [MyVolumio PluginManager] Fetching plans data.... Mar 10 14:02:59 volumio-wohnzimmer volumio[1064]: error: updateQueue error: null Mar 10 14:02:59 volumio-wohnzimmer volumio[1443]: Generating RSA private key, 4096 bit long modulus (2 primes) Mar 10 14:02:59 volumio-wohnzimmer volumio[1064]: info: go-librespot daemon successfully initialized Mar 10 14:03:00 volumio-wohnzimmer sudo[1532]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=georg,password=gogo123,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //moria.fritz.box/music /mnt/NAS/Moria Mar 10 14:03:00 volumio-wohnzimmer sudo[1532]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 10 14:03:00 volumio-wohnzimmer kernel: CIFS: Attempting to mount //moria.fritz.box/music Mar 10 14:03:02 volumio-wohnzimmer volumio[1064]: info: Initializing connection to go-librespot Websocket Mar 10 14:03:02 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:02+01:00" level=debug msg="new websocket client" Mar 10 14:03:03 volumio-wohnzimmer volumio[1064]: info: Connection to go-librespot Websocket established Mar 10 14:03:03 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:03+01:00" level=error msg="failed receiving dealer message" error="failed to get reader: received close frame: status = StatusGoingAway and reason = \"\"" Mar 10 14:03:03 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:03+01:00" level=error msg="failed receiving packet" error="failed reading packet header: read tcp 192.168.178.54:51026->34.158.1.133:4070: use of closed network connection" Mar 10 14:03:03 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:03+01:00" level=debug msg="obtained new client token: AAAlZTb0vfRk11BmToUrBR7YxGa8D4cdFWSr8pyviYulwawJNLUqkYnumFiGv603pbLJdQQ6hUNdfyKkGM9Ghisl2AC1MaBGVtroP+Cd3GZzkf1u8e2hiKDvH8aOEu/K/eiF4FW8izh1Azz92zy6UTKYRQKz8z3M+UDDsrqAjMGYbPGGrCNd3MKC6GUy5zw1eTAtO5ousWlLCkxNFhy5X85DHc2gNA4DMGFcuQyUjtdy0S5k6mEQDVY=" Mar 10 14:03:03 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:03+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Mar 10 14:03:03 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:03+01:00" level=debug msg="completed keyexchange" Mar 10 14:03:03 volumio-wohnzimmer systemd[1]: systemd-timedated.service: Succeeded. Mar 10 14:03:03 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:03+01:00" level=debug msg="completed challenge" Mar 10 14:03:03 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:03+01:00" level=debug msg="authenticated as b008fr7kb2zlhrpsbq1r8n8pq" Mar 10 14:03:03 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:03+01:00" level=debug msg="authenticated as b008fr7kb2zlhrpsbq1r8n8pq" Mar 10 14:03:03 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:03+01:00" level=debug msg="dealer connection opened" Mar 10 14:03:03 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:03+01:00" level=info msg="accepted zeroconf user b008fr7kb2zlhrpsbq1r8n8pq from Bantje" Mar 10 14:03:03 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:03+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Mar 10 14:03:03 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:03+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Mar 10 14:03:03 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:03+01:00" level=debug msg="autoplay enabled: false" Mar 10 14:03:03 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:03+01:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Mar 10 14:03:03 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:03+01:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Mar 10 14:03:03 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:03+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Mar 10 14:03:03 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:03+01:00" level=debug msg="received connection id: YzAwYzFjNmEtNDUxNy00ZDIyLTgyZmEtMGNiYjFhZGU4N2NjK2RlYWxlcit0Y3A6Ly8wYWNhNTk3OC5pcC5nZXc0LnNwb3RpZnkubmV0OjU3MDArNzE1QzA4QUZGNTVEQzMzNkM4RjQ3OUU0MDA4MTdCRUZCRDE0QUVGOTlBMjM4OURFMzRGOTBFN0I0NDIwRTU2Qg==" Mar 10 14:03:03 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:03+01:00" level=debug msg="put connect state because NEW_DEVICE" Mar 10 14:03:03 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:03+01:00" level=debug msg="handling transfer player command from 59c6aa148bed3146ff817b1e313cc3be79b9fa69" Mar 10 14:03:04 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:04+01:00" level=debug msg="resolved context of track" uri="spotify:playlist:5QWRuhJReSb9aQiUHnwA87" Mar 10 14:03:04 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:04+01:00" level=trace msg="fetched new page 0 with 4196 items (list: 4196)" uri="spotify:playlist:5QWRuhJReSb9aQiUHnwA87" Mar 10 14:03:04 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:04+01:00" level=debug msg="shuffled context with seed 10123780205323237178 (len: 4196, keep: 4040)" Mar 10 14:03:04 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:04+01:00" level=debug msg="loading track (paused: false, position: 150804ms)" uri="spotify:track:25rv9xm99crXFj5XXyIvOG" Mar 10 14:03:04 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:04+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Mar 10 14:03:04 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:04+01:00" level=trace msg="emitting websocket event: will_play" Mar 10 14:03:04 volumio-wohnzimmer volumio[1064]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:25rv9xm99crXFj5XXyIvOG","play_origin":"playlist"}} Mar 10 14:03:04 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:04+01:00" level=debug msg="selected format OGG_VORBIS_320 (aa7322562b5aa9634a4e4a6cebad6fb9a530eddd)" uri="spotify:track:25rv9xm99crXFj5XXyIvOG" Mar 10 14:03:04 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:04+01:00" level=debug msg="requested aes key for file aa7322562b5aa9634a4e4a6cebad6fb9a530eddd, gid: 25rv9xm99crXFj5XXyIvOG" Mar 10 14:03:04 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:04+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 345" Mar 10 14:03:04 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:04+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1703" Mar 10 14:03:04 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:04+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1303" Mar 10 14:03:05 volumio-wohnzimmer volumio-remote-updater[536]: [2025-03-10 14:03:05] [connect] Successful connection Mar 10 14:03:05 volumio-wohnzimmer volumio-remote-updater[536]: [2025-03-10 14:03:05] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1741611785 101 Mar 10 14:03:05 volumio-wohnzimmer volumio[1064]: 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: 2 Mar 10 14:03:05 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:05+01:00" level=debug msg="fetched first chunk of 14, total size is 6874836 bytes" uri="spotify:track:25rv9xm99crXFj5XXyIvOG" Mar 10 14:03:05 volumio-wohnzimmer volumio[1064]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Mar 10 14:03:05 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:05+01:00" level=debug msg="fetched chunk 1/13, size: 524288" uri="spotify:track:25rv9xm99crXFj5XXyIvOG" Mar 10 14:03:05 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:05+01:00" level=debug msg="fetched chunk 2/13, size: 524288" uri="spotify:track:25rv9xm99crXFj5XXyIvOG" Mar 10 14:03:05 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:05+01:00" level=debug msg="fetched chunk 3/13, size: 524288" uri="spotify:track:25rv9xm99crXFj5XXyIvOG" Mar 10 14:03:05 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:05+01:00" level=debug msg="fetched chunk 13/13, size: 59092" uri="spotify:track:25rv9xm99crXFj5XXyIvOG" Mar 10 14:03:06 volumio-wohnzimmer volumio[1064]: info: Getting Spotify volume Mar 10 14:03:06 volumio-wohnzimmer volumio[1064]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Mar 10 14:03:06 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:06+01:00" level=debug msg="fetched chunk 10/13, size: 524288" uri="spotify:track:25rv9xm99crXFj5XXyIvOG" Mar 10 14:03:06 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:06+01:00" level=trace msg="seek to 150804ms (diff: 225ms, samples: 6650456, bytes: 5529303)" uri="spotify:track:25rv9xm99crXFj5XXyIvOG" Mar 10 14:03:06 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:06+01:00" level=debug msg="fetched chunk 12/13, size: 524288" uri="spotify:track:25rv9xm99crXFj5XXyIvOG" Mar 10 14:03:06 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:06+01:00" level=debug msg="fetched chunk 11/13, size: 524288" uri="spotify:track:25rv9xm99crXFj5XXyIvOG" Mar 10 14:03:06 volumio-wohnzimmer kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Mar 10 14:03:06 volumio-wohnzimmer kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Mar 10 14:03:06 volumio-wohnzimmer sudo[1532]: pam_unix(sudo:session): session closed for user root Mar 10 14:03:06 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:06+01:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 5102 us, period size = 225 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 98 frames" Mar 10 14:03:06 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:06+01:00" level=debug msg="created new output device" Mar 10 14:03:06 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:06+01:00" level=info msg="loaded track \"Kapitel 67 - Schicksalsfäden - Die Chroniken der Seelenwächter, Band 2\" (paused: false, position: 150804ms, duration: 186600ms, prefetched: false)" uri="spotify:track:25rv9xm99crXFj5XXyIvOG" Mar 10 14:03:06 volumio-wohnzimmer kernel: bcm2835-i2s 3f203000.i2s: I2S SYNC error! Mar 10 14:03:06 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:06+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Mar 10 14:03:06 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:06+01:00" level=trace msg="emitting websocket event: metadata" Mar 10 14:03:06 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:06+01:00" level=debug msg="prefetching next track" uri="spotify:track:2mVLIrXIkn2J06GCsCSNKH" Mar 10 14:03:06 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:06+01:00" level=trace msg="emitting websocket event: active" Mar 10 14:03:06 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:06+01:00" level=debug msg="sending successful reply for dealer request" Mar 10 14:03:06 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:06+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Mar 10 14:03:06 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:06+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Mar 10 14:03:06 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:06+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Mar 10 14:03:06 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:06+01:00" level=debug msg="selected format OGG_VORBIS_320 (c7d21bc903725aa7256e677f92fc071e1b847dbf)" uri="spotify:track:2mVLIrXIkn2J06GCsCSNKH" Mar 10 14:03:06 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:06+01:00" level=debug msg="requested aes key for file c7d21bc903725aa7256e677f92fc071e1b847dbf, gid: 2mVLIrXIkn2J06GCsCSNKH" Mar 10 14:03:06 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:06+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Mar 10 14:03:06 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:06+01:00" level=trace msg="emitting websocket event: playing" Mar 10 14:03:06 volumio-wohnzimmer volumio[1443]: ..........................................................++++ Mar 10 14:03:06 volumio-wohnzimmer volumio[1064]: info: Spotify volume: 100 Mar 10 14:03:06 volumio-wohnzimmer volumio[1064]: info: CoreCommandRouter::volumioGetState Mar 10 14:03:06 volumio-wohnzimmer volumio[1064]: info: CorePlayQueue::getTrack 0 Mar 10 14:03:06 volumio-wohnzimmer volumio[1064]: info: Cannot mount NAS Moria at system boot, trial number 2 ,retrying in 5 seconds Mar 10 14:03:07 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:07+01:00" level=debug msg="fetched first chunk of 12, total size is 5812912 bytes" uri="spotify:track:2mVLIrXIkn2J06GCsCSNKH" Mar 10 14:03:07 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:07+01:00" level=trace msg="seek to 0ms (diff: 0ms, samples: 0, bytes: 0)" uri="spotify:track:2mVLIrXIkn2J06GCsCSNKH" Mar 10 14:03:07 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:07+01:00" level=info msg="prefetched track \"Kapitel 34 - Fieber - Die Chroniken der Seelenwächter, Band 19\" (duration: 182133ms)" uri="spotify:track:2mVLIrXIkn2J06GCsCSNKH" Mar 10 14:03:07 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:07+01:00" level=debug msg="fetched chunk 1/11, size: 524288" uri="spotify:track:2mVLIrXIkn2J06GCsCSNKH" Mar 10 14:03:07 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:07+01:00" level=debug msg="fetched chunk 2/11, size: 524288" uri="spotify:track:2mVLIrXIkn2J06GCsCSNKH" Mar 10 14:03:07 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:07+01:00" level=debug msg="fetched chunk 3/11, size: 524288" uri="spotify:track:2mVLIrXIkn2J06GCsCSNKH" Mar 10 14:03:07 volumio-wohnzimmer volumio[1064]: info: CoreCommandRouter::volumioGetState Mar 10 14:03:07 volumio-wohnzimmer volumio[1064]: info: CorePlayQueue::getTrack 0 Mar 10 14:03:08 volumio-wohnzimmer volumio[1064]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:25rv9xm99crXFj5XXyIvOG","name":"Kapitel 67 - Schicksalsfäden - Die Chroniken der Seelenwächter, Band 2","artist_names":["Nicole Böhm","Pia-Rhona Saxe","Jan Langer","Cornelia Prescher"],"album_name":"Schicksalsfäden [Die Chroniken der Seelenwächter, Band 2 (Ungekürzt)]","album_cover_url":"https://i.scdn.co/image/ab67616d00001e028d5e1bd258cb824ea7372fb5","position":150804,"duration":186600,"release_date":"year:2022 month:6 day:24","track_number":28,"disc_number":2}} Mar 10 14:03:08 volumio-wohnzimmer volumio[1064]: SPOTIFY: received: {"type":"active","data":null} Mar 10 14:03:08 volumio-wohnzimmer volumio[1064]: info: Aligning Spotify Volume to Volumio Volume Mar 10 14:03:08 volumio-wohnzimmer volumio[1064]: info: CoreCommandRouter::volumioGetState Mar 10 14:03:08 volumio-wohnzimmer volumio[1064]: info: CorePlayQueue::getTrack 0 Mar 10 14:03:08 volumio-wohnzimmer volumio[1064]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:25rv9xm99crXFj5XXyIvOG","play_origin":"playlist"}} Mar 10 14:03:08 volumio-wohnzimmer volumio[1064]: info: Spotify is playing in volatile mode Mar 10 14:03:08 volumio-wohnzimmer volumio[1064]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 10 14:03:08 volumio-wohnzimmer volumio[1064]: SPOTIFY: UNSET VOLATILE Mar 10 14:03:08 volumio-wohnzimmer volumio[1064]: SPOTIFY: {"status":"stop","position":0,"title":"","artist":"","album":"","albumart":"/albumart","duration":0,"uri":"","seek":0,"samplerate":"","channels":"","bitdepth":"","Streaming":false,"service":"mpd","volume":100,"dbVolume":null,"mute":false,"disableVolumeControl":true,"random":null,"repeat":null,"repeatSingle":false,"updatedb":false,"consume":false} Mar 10 14:03:08 volumio-wohnzimmer volumio[1064]: SPOTIFY: PUSH STATE SPOTIFY Mar 10 14:03:08 volumio-wohnzimmer volumio[1064]: SPOTIFY: {"status":"play","service":"spop","title":"Kapitel 67 - Schicksalsfäden - Die Chroniken der Seelenwächter, Band 2","artist":"Nicole Böhm, Pia-Rhona Saxe, Jan Langer, Cornelia Prescher","album":"Schicksalsfäden [Die Chroniken der Seelenwächter, Band 2 (Ungekürzt)]","albumart":"https://i.scdn.co/image/ab67616d00001e028d5e1bd258cb824ea7372fb5","uri":"spotify:track:25rv9xm99crXFj5XXyIvOG","trackType":"spotify","seek":150804,"duration":186,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Mar 10 14:03:08 volumio-wohnzimmer volumio[1064]: info: CoreCommandRouter::servicePushState Mar 10 14:03:08 volumio-wohnzimmer volumio[1064]: info: CoreStateMachine::pushState Mar 10 14:03:08 volumio-wohnzimmer volumio[1064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 10 14:03:08 volumio-wohnzimmer volumio[1064]: info: CoreCommandRouter::volumioPushState Mar 10 14:03:08 volumio-wohnzimmer volumio[1064]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Mar 10 14:03:08 volumio-wohnzimmer volumio[1064]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Mar 10 14:03:08 volumio-wohnzimmer volumio[1064]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Mar 10 14:03:08 volumio-wohnzimmer volumio[1064]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Mar 10 14:03:08 volumio-wohnzimmer volumio[1064]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Mar 10 14:03:08 volumio-wohnzimmer volumio[1064]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Mar 10 14:03:08 volumio-wohnzimmer volumio[1064]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Mar 10 14:03:08 volumio-wohnzimmer volumio[1064]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Mar 10 14:03:08 volumio-wohnzimmer volumio[1064]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Mar 10 14:03:08 volumio-wohnzimmer volumio[1064]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Mar 10 14:03:08 volumio-wohnzimmer volumio[1064]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Mar 10 14:03:08 volumio-wohnzimmer volumio[1064]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Mar 10 14:03:08 volumio-wohnzimmer volumio[1064]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Mar 10 14:03:08 volumio-wohnzimmer volumio[1064]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Mar 10 14:03:08 volumio-wohnzimmer volumio[1064]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Mar 10 14:03:08 volumio-wohnzimmer volumio[1064]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Mar 10 14:03:08 volumio-wohnzimmer volumio[1064]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Mar 10 14:03:08 volumio-wohnzimmer volumio[1064]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Mar 10 14:03:08 volumio-wohnzimmer volumio[1064]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Mar 10 14:03:08 volumio-wohnzimmer volumio[1064]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Mar 10 14:03:08 volumio-wohnzimmer volumio[1064]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Mar 10 14:03:08 volumio-wohnzimmer volumio[1064]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Mar 10 14:03:08 volumio-wohnzimmer volumio[1064]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Mar 10 14:03:08 volumio-wohnzimmer volumio[1064]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Mar 10 14:03:08 volumio-wohnzimmer volumio[1064]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Mar 10 14:03:08 volumio-wohnzimmer volumio[1064]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Mar 10 14:03:08 volumio-wohnzimmer volumio[1064]: info: Adding plugin bluetooth to MyMusic Plugins Mar 10 14:03:08 volumio-wohnzimmer volumio[1064]: info: Adding plugin multiroom to MyMusic Plugins Mar 10 14:03:08 volumio-wohnzimmer volumio[1064]: info: Adding plugin metavolumio to MyMusic Plugins Mar 10 14:03:08 volumio-wohnzimmer volumio[1064]: info: Adding plugin cd_controller to MyMusic Plugins Mar 10 14:03:08 volumio-wohnzimmer volumio[1064]: info: Adding plugin smart_inputs to MyMusic Plugins Mar 10 14:03:08 volumio-wohnzimmer volumio[1064]: info: Adding plugin tidalconnect to MyMusic Plugins Mar 10 14:03:08 volumio-wohnzimmer volumio[1064]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Mar 10 14:03:10 volumio-wohnzimmer volumio[1064]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Mar 10 14:03:10 volumio-wohnzimmer volumio[1064]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Mar 10 14:03:10 volumio-wohnzimmer volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 10 14:03:10 volumio-wohnzimmer volumio[1064]: info: Starting MyVolumio Remote Streaming Endpoints Mar 10 14:03:10 volumio-wohnzimmer volumio[1064]: info: MyVolumio login type: Token Mar 10 14:03:10 volumio-wohnzimmer volumio[1064]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Mar 10 14:03:10 volumio-wohnzimmer volumio[1064]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Mar 10 14:03:10 volumio-wohnzimmer volumio[1443]: ..............................++++ Mar 10 14:03:10 volumio-wohnzimmer volumio[1443]: e is 65537 (0x010001) Mar 10 14:03:10 volumio-wohnzimmer volumio[1443]: writing RSA key Mar 10 14:03:11 volumio-wohnzimmer volumio[1064]: info: Starting Streaming Service Transparent Proxy Mar 10 14:03:11 volumio-wohnzimmer volumio[1064]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Mar 10 14:03:11 volumio-wohnzimmer volumio[1064]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Mar 10 14:03:11 volumio-wohnzimmer volumio[1064]: info: Streaming services startup Mar 10 14:03:11 volumio-wohnzimmer volumio[1064]: info: Starting Streaming Daemon Mar 10 14:03:11 volumio-wohnzimmer volumio[1064]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Mar 10 14:03:11 volumio-wohnzimmer sudo[1587]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 10 14:03:11 volumio-wohnzimmer sudo[1587]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 10 14:03:11 volumio-wohnzimmer sudo[1587]: pam_unix(sudo:session): session closed for user root Mar 10 14:03:11 volumio-wohnzimmer volumio[1064]: SPOTIFY: PUSH STATE SPOTIFY Mar 10 14:03:11 volumio-wohnzimmer volumio[1064]: SPOTIFY: {"status":"play","service":"spop","title":"Kapitel 67 - Schicksalsfäden - Die Chroniken der Seelenwächter, Band 2","artist":"Nicole Böhm, Pia-Rhona Saxe, Jan Langer, Cornelia Prescher","album":"Schicksalsfäden [Die Chroniken der Seelenwächter, Band 2 (Ungekürzt)]","albumart":"https://i.scdn.co/image/ab67616d00001e028d5e1bd258cb824ea7372fb5","uri":"spotify:track:25rv9xm99crXFj5XXyIvOG","trackType":"spotify","seek":150804,"duration":186,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Mar 10 14:03:11 volumio-wohnzimmer volumio[1064]: info: CoreCommandRouter::servicePushState Mar 10 14:03:11 volumio-wohnzimmer volumio[1064]: info: CoreStateMachine::pushState Mar 10 14:03:11 volumio-wohnzimmer volumio[1064]: info: CoreCommandRouter::volumioPushState Mar 10 14:03:11 volumio-wohnzimmer sudo[1595]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=georg,password=gogo123,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //moria.fritz.box/music /mnt/NAS/Moria Mar 10 14:03:11 volumio-wohnzimmer sudo[1595]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 10 14:03:12 volumio-wohnzimmer kernel: CIFS: Attempting to mount //moria.fritz.box/music Mar 10 14:03:12 volumio-wohnzimmer volumio[1064]: error: Cannot start Volumio Streaming Daemon Mar 10 14:03:12 volumio-wohnzimmer volumio[1064]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 10 14:03:12 volumio-wohnzimmer volumio[1064]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 10 14:03:13 volumio-wohnzimmer volumio[1064]: STREAMING PROXY: Starting server on port 3245 Mar 10 14:03:13 volumio-wohnzimmer volumio[1064]: Node JS runtime: 14 Mar 10 14:03:13 volumio-wohnzimmer volumio[1064]: error: MyVolumio Custom Token format not valid, refreshing it Mar 10 14:03:13 volumio-wohnzimmer volumio[1064]: <--- Last few GCs ---> Mar 10 14:03:13 volumio-wohnzimmer volumio[1064]: [1064:0x4dd7780] 47364 ms: Scavenge 174.9 (186.1) -> 174.9 (186.1) MB, 0.8 / 0.0 ms (average mu = 0.947, current mu = 0.878) allocation failure Mar 10 14:03:13 volumio-wohnzimmer volumio[1064]: [1064:0x4dd7780] 47493 ms: Scavenge 186.1 (197.2) -> 186.0 (197.2) MB, 4.9 / 0.0 ms (average mu = 0.947, current mu = 0.878) allocation failure Mar 10 14:03:13 volumio-wohnzimmer volumio[1064]: [1064:0x4dd7780] 47556 ms: Scavenge 197.3 (208.3) -> 197.2 (208.3) MB, 4.2 / 0.0 ms (average mu = 0.947, current mu = 0.878) allocation failure Mar 10 14:03:13 volumio-wohnzimmer volumio[1064]: <--- JS stacktrace ---> Mar 10 14:03:13 volumio-wohnzimmer volumio[1064]: FATAL ERROR: MarkCompactCollector: young object promotion failed Allocation failed - JavaScript heap out of memory Mar 10 14:03:14 volumio-wohnzimmer volumio-remote-updater[536]: [2025-03-10 14:03:14] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Mar 10 14:03:14 volumio-wohnzimmer volumio-remote-updater[536]: [2025-03-10 14:03:14] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Mar 10 14:03:14 volumio-wohnzimmer systemd[1]: volumio.service: Main process exited, code=killed, status=11/SEGV Mar 10 14:03:14 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:14+01:00" level=error msg="websocket connection errored" error="failed to get reader: failed to read frame header: EOF" Mar 10 14:03:14 volumio-wohnzimmer kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Mar 10 14:03:14 volumio-wohnzimmer kernel: CIFS: VFS: cifs_mount failed w/return code = -4 Mar 10 14:03:14 volumio-wohnzimmer sudo[1595]: pam_unix(sudo:session): session closed for user root Mar 10 14:03:14 volumio-wohnzimmer systemd[1]: volumio.service: Failed with result 'signal'. Mar 10 14:03:14 volumio-wohnzimmer systemd[1]: Started dynamicswap service. Mar 10 14:03:14 volumio-wohnzimmer systemd[1]: dynamicswap.service: Succeeded. Mar 10 14:03:14 volumio-wohnzimmer systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Mar 10 14:03:14 volumio-wohnzimmer systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Mar 10 14:03:14 volumio-wohnzimmer systemd[1]: Stopped Volumio Backend Module. Mar 10 14:03:14 volumio-wohnzimmer systemd[1]: Started Volumio Backend Module. Mar 10 14:03:14 volumio-wohnzimmer systemd[1]: Started dynamicswap service. Mar 10 14:03:14 volumio-wohnzimmer systemd[1]: dynamicswap.service: Succeeded. Mar 10 14:03:15 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:15+01:00" level=debug msg="handling pause player command from 59c6aa148bed3146ff817b1e313cc3be79b9fa69" Mar 10 14:03:15 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:15+01:00" level=debug msg="pause track at 159469ms" Mar 10 14:03:15 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:15+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Mar 10 14:03:15 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:15+01:00" level=debug msg="sending successful reply for dealer request" Mar 10 14:03:15 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:15+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Mar 10 14:03:15 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:15+01:00" level=trace msg="emitting websocket event: paused" Mar 10 14:03:16 volumio-wohnzimmer volumio[1624]: info: ------------------------------------------- Mar 10 14:03:16 volumio-wohnzimmer volumio[1624]: info: ----- Volumio3 ---- Mar 10 14:03:16 volumio-wohnzimmer volumio[1624]: info: ------------------------------------------- Mar 10 14:03:16 volumio-wohnzimmer volumio[1624]: info: ----- System startup ---- Mar 10 14:03:16 volumio-wohnzimmer volumio[1624]: info: ------------------------------------------- Mar 10 14:03:17 volumio-wohnzimmer volumio[1624]: info: MYVOLUMIO Environment detected Mar 10 14:03:17 volumio-wohnzimmer volumio[1624]: info: Plugin folders cleanup Mar 10 14:03:17 volumio-wohnzimmer volumio[1624]: info: Scanning into folder /volumio/app/plugins/ Mar 10 14:03:17 volumio-wohnzimmer volumio[1624]: info: Scanning category audio_interface Mar 10 14:03:17 volumio-wohnzimmer volumio[1624]: info: Scanning category miscellanea Mar 10 14:03:17 volumio-wohnzimmer volumio[1624]: info: Scanning category music_service Mar 10 14:03:17 volumio-wohnzimmer volumio[1624]: info: Scanning category plugins.json Mar 10 14:03:17 volumio-wohnzimmer volumio[1624]: info: Scanning category system_controller Mar 10 14:03:17 volumio-wohnzimmer volumio[1624]: info: Scanning category user_interface Mar 10 14:03:17 volumio-wohnzimmer volumio[1624]: info: Scanning into folder /data/plugins/ Mar 10 14:03:17 volumio-wohnzimmer volumio[1624]: info: Scanning category music_service Mar 10 14:03:17 volumio-wohnzimmer volumio[1624]: info: Scanning category user_interface Mar 10 14:03:17 volumio-wohnzimmer volumio[1624]: info: Plugin folders cleanup completed Mar 10 14:03:17 volumio-wohnzimmer volumio[1624]: info: ------------------------------------------- Mar 10 14:03:17 volumio-wohnzimmer volumio[1624]: info: ----- Core plugins startup ---- Mar 10 14:03:17 volumio-wohnzimmer volumio[1624]: info: ------------------------------------------- Mar 10 14:03:17 volumio-wohnzimmer volumio[1624]: info: Loading plugins from folder /volumio/app/plugins/ Mar 10 14:03:17 volumio-wohnzimmer volumio[1624]: info: Adding plugin upnp to MyMusic Plugins Mar 10 14:03:17 volumio-wohnzimmer volumio[1624]: info: Adding plugin airplay_emulation to MyMusic Plugins Mar 10 14:03:17 volumio-wohnzimmer volumio[1624]: info: Adding plugin upnp_browser to MyMusic Plugins Mar 10 14:03:17 volumio-wohnzimmer volumio[1624]: info: Loading plugins from folder /data/plugins/ Mar 10 14:03:17 volumio-wohnzimmer volumio[1624]: info: Loading plugin "system"... Mar 10 14:03:17 volumio-wohnzimmer volumio[1624]: info: Loading plugin "appearance"... Mar 10 14:03:17 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:17+01:00" level=debug msg="handling resume player command from 59c6aa148bed3146ff817b1e313cc3be79b9fa69" Mar 10 14:03:17 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:17+01:00" level=trace msg="seek to 159469ms (diff: 41ms, samples: 7032582, bytes: 5871144)" uri="spotify:track:25rv9xm99crXFj5XXyIvOG" Mar 10 14:03:17 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:17+01:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 5102 us, period size = 225 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 98 frames" Mar 10 14:03:17 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:17+01:00" level=debug msg="resume track at 159428ms" Mar 10 14:03:18 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:18+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Mar 10 14:03:18 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:18+01:00" level=debug msg="sending successful reply for dealer request" Mar 10 14:03:18 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:18+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Mar 10 14:03:18 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:18+01:00" level=trace msg="emitting websocket event: playing" Mar 10 14:03:18 volumio-wohnzimmer volumio[1624]: info: Loading plugin "network"... Mar 10 14:03:18 volumio-wohnzimmer volumio[1624]: info: Refreshing Cached IP Addresses Mar 10 14:03:18 volumio-wohnzimmer sudo[1662]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 10 14:03:18 volumio-wohnzimmer sudo[1662]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 10 14:03:18 volumio-wohnzimmer sudo[1662]: pam_unix(sudo:session): session closed for user root Mar 10 14:03:18 volumio-wohnzimmer sudo[1664]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 10 14:03:18 volumio-wohnzimmer sudo[1664]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 10 14:03:18 volumio-wohnzimmer volumio[1624]: info: Loading plugin "services"... Mar 10 14:03:18 volumio-wohnzimmer sudo[1664]: pam_unix(sudo:session): session closed for user root Mar 10 14:03:18 volumio-wohnzimmer volumio[1624]: info: Loading plugin "alsa_controller"... Mar 10 14:03:18 volumio-wohnzimmer volumio[1624]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 10 14:03:18 volumio-wohnzimmer volumio[1624]: info: Loading plugin "wizard"... Mar 10 14:03:18 volumio-wohnzimmer volumio[1624]: info: Loading plugin "networkfs"... Mar 10 14:03:18 volumio-wohnzimmer volumio[1624]: info: Starting Udev Watcher for removable devices Mar 10 14:03:18 volumio-wohnzimmer sudo[1685]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=georg,password=gogo123,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //moria.fritz.box/music /mnt/NAS/Moria Mar 10 14:03:18 volumio-wohnzimmer sudo[1685]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 10 14:03:18 volumio-wohnzimmer volumio[1624]: info: Ignoring mount for partition: boot Mar 10 14:03:18 volumio-wohnzimmer volumio[1624]: info: Ignoring mount for partition: volumio Mar 10 14:03:18 volumio-wohnzimmer volumio[1624]: info: Ignoring mount for partition: volumio_data Mar 10 14:03:18 volumio-wohnzimmer volumio[1624]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 10 14:03:18 volumio-wohnzimmer volumio[1624]: info: Loading plugin "volumio_command_line_client"... Mar 10 14:03:18 volumio-wohnzimmer volumio[1624]: info: Loading plugin "upnp"... Mar 10 14:03:18 volumio-wohnzimmer kernel: CIFS: Attempting to mount //moria.fritz.box/music Mar 10 14:03:18 volumio-wohnzimmer volumio[1624]: info: [1741611798686] Starting Upmpd Daemon Mar 10 14:03:18 volumio-wohnzimmer volumio[1624]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 10 14:03:18 volumio-wohnzimmer volumio[1624]: info: Loading plugin "my_music"... Mar 10 14:03:18 volumio-wohnzimmer volumio[1624]: info: Loading plugin "mpd"... Mar 10 14:03:18 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:18+01:00" level=debug msg="handling pause player command from 59c6aa148bed3146ff817b1e313cc3be79b9fa69" Mar 10 14:03:18 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:18+01:00" level=debug msg="pause track at 160159ms" Mar 10 14:03:18 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:18+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Mar 10 14:03:18 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:18+01:00" level=debug msg="sending successful reply for dealer request" Mar 10 14:03:18 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:18+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Mar 10 14:03:18 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:18+01:00" level=trace msg="emitting websocket event: paused" Mar 10 14:03:19 volumio-wohnzimmer volumio-remote-updater[536]: [2025-03-10 14:03:19] [connect] Successful connection Mar 10 14:03:19 volumio-wohnzimmer volumio[1624]: info: Loading plugin "upnp_browser"... Mar 10 14:03:19 volumio-wohnzimmer volumio[1624]: info: Loading plugin "alarm-clock"... Mar 10 14:03:19 volumio-wohnzimmer volumio[1624]: info: Loading plugin "airplay_emulation"... Mar 10 14:03:19 volumio-wohnzimmer volumio[1624]: info: Starting Shairport Sync Mar 10 14:03:19 volumio-wohnzimmer volumio[1624]: info: Loading plugin "last_100"... Mar 10 14:03:19 volumio-wohnzimmer volumio[1624]: info: Loading plugin "webradio"... Mar 10 14:03:19 volumio-wohnzimmer volumio[1624]: info: Loading plugin "i2s_dacs"... Mar 10 14:03:19 volumio-wohnzimmer volumio[1624]: info: Loading plugin "volumiodiscovery"... Mar 10 14:03:19 volumio-wohnzimmer volumio[1624]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 10 14:03:19 volumio-wohnzimmer volumio[1624]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 10 14:03:19 volumio-wohnzimmer volumio[1624]: *** WARNING *** For more information see Mar 10 14:03:19 volumio-wohnzimmer node[1624]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 10 14:03:19 volumio-wohnzimmer volumio[1624]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 10 14:03:19 volumio-wohnzimmer volumio[1624]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 10 14:03:19 volumio-wohnzimmer volumio[1624]: *** WARNING *** For more information see Mar 10 14:03:19 volumio-wohnzimmer node[1624]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 10 14:03:19 volumio-wohnzimmer node[1624]: *** WARNING *** For more information see Mar 10 14:03:19 volumio-wohnzimmer node[1624]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 10 14:03:19 volumio-wohnzimmer node[1624]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 10 14:03:19 volumio-wohnzimmer node[1624]: *** WARNING *** For more information see Mar 10 14:03:19 volumio-wohnzimmer volumio[1624]: info: Applying required configuration parameters for plugin volumiodiscovery Mar 10 14:03:19 volumio-wohnzimmer volumio[1624]: info: Discovery: Started advertising with name: Volumio Wohnzimmer Mar 10 14:03:20 volumio-wohnzimmer volumio[1624]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 10 14:03:20 volumio-wohnzimmer volumio[1624]: info: Loading plugin "soundcloud"... Mar 10 14:03:20 volumio-wohnzimmer volumio[1624]: info: Loading plugin "spop"... Mar 10 14:03:20 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:20+01:00" level=debug msg="update volume to 64880/65535" Mar 10 14:03:20 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:20+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Mar 10 14:03:20 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:20+01:00" level=trace msg="emitting websocket event: volume" Mar 10 14:03:20 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:20+01:00" level=debug msg="update volume to 64224/65535" Mar 10 14:03:21 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:21+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Mar 10 14:03:21 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:21+01:00" level=trace msg="emitting websocket event: volume" Mar 10 14:03:21 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:21+01:00" level=debug msg="update volume to 63569/65535" Mar 10 14:03:21 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:21+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Mar 10 14:03:21 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:21+01:00" level=trace msg="emitting websocket event: volume" Mar 10 14:03:21 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:21+01:00" level=debug msg="update volume to 62914/65535" Mar 10 14:03:21 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:21+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Mar 10 14:03:21 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:21+01:00" level=trace msg="emitting websocket event: volume" Mar 10 14:03:21 volumio-wohnzimmer volumio[1624]: info: Loading plugin "youtube2"... Mar 10 14:03:21 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:21+01:00" level=debug msg="update volume to 62258/65535" Mar 10 14:03:21 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:21+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Mar 10 14:03:21 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:21+01:00" level=trace msg="emitting websocket event: volume" Mar 10 14:03:21 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:21+01:00" level=debug msg="update volume to 61603/65535" Mar 10 14:03:22 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:22+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Mar 10 14:03:22 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:22+01:00" level=trace msg="emitting websocket event: volume" Mar 10 14:03:22 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:22+01:00" level=debug msg="update volume to 60948/65535" Mar 10 14:03:22 volumio-wohnzimmer volumiologrotate[534]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Mar 10 14:03:22 volumio-wohnzimmer volumiologrotate[534]: ls: cannot access 'WOHNZIMMER': No such file or directory Mar 10 14:03:22 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:22+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Mar 10 14:03:22 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:22+01:00" level=trace msg="emitting websocket event: volume" Mar 10 14:03:22 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:22+01:00" level=debug msg="update volume to 60292/65535" Mar 10 14:03:22 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:22+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Mar 10 14:03:22 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:22+01:00" level=trace msg="emitting websocket event: volume" Mar 10 14:03:22 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:22+01:00" level=debug msg="update volume to 59637/65535" Mar 10 14:03:22 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:22+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Mar 10 14:03:22 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:22+01:00" level=trace msg="emitting websocket event: volume" Mar 10 14:03:23 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:23+01:00" level=debug msg="update volume to 58981/65535" Mar 10 14:03:24 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:24+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Mar 10 14:03:24 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:24+01:00" level=trace msg="emitting websocket event: volume" Mar 10 14:03:24 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:24+01:00" level=debug msg="update volume to 57671/65535" Mar 10 14:03:24 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:24+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Mar 10 14:03:24 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:24+01:00" level=trace msg="emitting websocket event: volume" Mar 10 14:03:24 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:24+01:00" level=debug msg="update volume to 57015/65535" Mar 10 14:03:24 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:24+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Mar 10 14:03:24 volumio-wohnzimmer go-librespot[1486]: time="2025-03-10T14:03:24+01:00" level=trace msg="emitting websocket event: volume" Mar 10 14:03:24 volumio-wohnzimmer sudo[1685]: pam_unix(sudo:session): session closed for user root Mar 10 14:03:24 volumio-wohnzimmer kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Mar 10 14:03:24 volumio-wohnzimmer kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Mar 10 14:03:25 volumio-wohnzimmer volumio[1624]: info: Loading plugin "ytcr"... Mar 10 14:03:26 volumio-wohnzimmer volumio[1624]: info: Loading plugin "now_playing"... Mar 10 14:03:27 volumio-wohnzimmer volumio[1624]: info: Loading plugin "outputs"... Mar 10 14:03:27 volumio-wohnzimmer volumio[1624]: info: Loading plugin "albumart"... Mar 10 14:03:27 volumio-wohnzimmer volumio[1624]: info: Plugin example_plugin is not enabled Mar 10 14:03:27 volumio-wohnzimmer volumio[1624]: info: Loading plugin "inputs"... Mar 10 14:03:27 volumio-wohnzimmer volumio[1624]: info: Loading plugin "updater_comm"... Mar 10 14:03:27 volumio-wohnzimmer volumio[1624]: info: Plugin mpdemulation is not enabled Mar 10 14:03:27 volumio-wohnzimmer volumio[1624]: info: Loading plugin "rest_api"... Mar 10 14:03:28 volumio-wohnzimmer volumio[1624]: info: Loading plugin "websocket"... Mar 10 14:03:28 volumio-wohnzimmer volumio[1624]: info: Starting Socket.io Server version 2.3.0 Mar 10 14:03:28 volumio-wohnzimmer volumio[1624]: info: Loading plugin "80s80s"... Mar 10 14:03:28 volumio-wohnzimmer volumio[1624]: Forking 3 albumart workers Mar 10 14:03:28 volumio-wohnzimmer volumio[1624]: info: Applying required configuration parameters for plugin 80s80s Mar 10 14:03:28 volumio-wohnzimmer volumio[1624]: info: [1741611808311] [80s80s] API delay: 30 Mar 10 14:03:28 volumio-wohnzimmer volumio[1624]: info: Loading plugin "Systeminfo"... Mar 10 14:03:28 volumio-wohnzimmer volumio[1624]: info: Loading i18n strings for locale de Mar 10 14:03:28 volumio-wohnzimmer volumio[1624]: Updating browse sources language Mar 10 14:03:28 volumio-wohnzimmer volumio[1624]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 10 14:03:28 volumio-wohnzimmer volumio[1624]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 10 14:03:28 volumio-wohnzimmer volumio[1624]: info: CoreCommandRouter::initPlayerControls Mar 10 14:03:28 volumio-wohnzimmer volumio[1624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 10 14:03:28 volumio-wohnzimmer volumio[1624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 10 14:03:28 volumio-wohnzimmer volumio[1624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 10 14:03:28 volumio-wohnzimmer volumio[1624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 10 14:03:28 volumio-wohnzimmer volumio[1624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 10 14:03:28 volumio-wohnzimmer volumio[1624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 10 14:03:28 volumio-wohnzimmer volumio[1624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 10 14:03:28 volumio-wohnzimmer volumio[1624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 10 14:03:28 volumio-wohnzimmer volumio[1624]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 10 14:03:28 volumio-wohnzimmer volumio[1624]: Express server listening on port 3000 Mar 10 14:03:28 volumio-wohnzimmer volumio[1624]: [Metrics] WebUI: 13s 284.49ms Mar 10 14:03:29 volumio-wohnzimmer volumio[1624]: info: CoreStateMachine::resetVolumioState Mar 10 14:03:29 volumio-wohnzimmer volumio[1624]: info: CoreStateMachine::getcurrentVolume Mar 10 14:03:29 volumio-wohnzimmer volumio[1624]: info: CoreCommandRouter::volumioRetrievevolume Mar 10 14:03:29 volumio-wohnzimmer volumio[1624]: info: CoreStateMachine::pushState Mar 10 14:03:29 volumio-wohnzimmer volumio[1624]: info: CorePlayQueue::getTrack 0 Mar 10 14:03:29 volumio-wohnzimmer volumio[1624]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 10 14:03:29 volumio-wohnzimmer volumio[1624]: info: CoreCommandRouter::volumioPushState Mar 10 14:03:29 volumio-wohnzimmer volumio[1624]: Starting albumart workers Mar 10 14:03:29 volumio-wohnzimmer volumio[1624]: Starting albumart workers Mar 10 14:03:29 volumio-wohnzimmer sudo[1792]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 10 14:03:29 volumio-wohnzimmer sudo[1792]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 10 14:03:29 volumio-wohnzimmer sudo[1792]: pam_unix(sudo:session): session closed for user root Mar 10 14:03:29 volumio-wohnzimmer sudo[1794]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 10 14:03:29 volumio-wohnzimmer sudo[1794]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 10 14:03:29 volumio-wohnzimmer volumio[1624]: info: Volumio Network Manager: Network status updated: 1 Mar 10 14:03:29 volumio-wohnzimmer sudo[1794]: pam_unix(sudo:session): session closed for user root Mar 10 14:03:29 volumio-wohnzimmer volumio[1624]: info: Cannot mount NAS Moria at system boot, trial number 1 ,retrying in 5 seconds Mar 10 14:03:29 volumio-wohnzimmer volumio[1624]: Starting albumart workers Mar 10 14:03:29 volumio-wohnzimmer volumio[1624]: info: CoreCommandRouter::volumioGetState Mar 10 14:03:29 volumio-wohnzimmer volumio[1624]: info: CorePlayQueue::getTrack 0 Mar 10 14:03:29 volumio-wohnzimmer volumio[1624]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 10 14:03:29 volumio-wohnzimmer volumio[1624]: info: Reloading queue from file Mar 10 14:03:29 volumio-wohnzimmer volumio[1624]: info: CoreStateMachine::setRepeat null single undefined Mar 10 14:03:29 volumio-wohnzimmer volumio[1624]: info: CoreStateMachine::pushState Mar 10 14:03:29 volumio-wohnzimmer volumio[1624]: info: CorePlayQueue::getTrack 0 Mar 10 14:03:29 volumio-wohnzimmer volumio[1624]: info: CoreCommandRouter::volumioPushState Mar 10 14:03:29 volumio-wohnzimmer volumio[1624]: info: CoreStateMachine::setRandom null Mar 10 14:03:29 volumio-wohnzimmer volumio[1624]: info: CoreStateMachine::pushState Mar 10 14:03:29 volumio-wohnzimmer volumio[1624]: info: CorePlayQueue::getTrack 0 Mar 10 14:03:29 volumio-wohnzimmer volumio[1624]: info: CoreCommandRouter::volumioPushState Mar 10 14:03:29 volumio-wohnzimmer volumio[1624]: info: Setting Device type: Raspberry PI Mar 10 14:03:29 volumio-wohnzimmer volumio[1624]: info: Listing playlists Mar 10 14:03:29 volumio-wohnzimmer volumio[1624]: info: Listing playlists Mar 10 14:03:29 volumio-wohnzimmer volumio[1624]: info: Completed loading Core Plugins Mar 10 14:03:29 volumio-wohnzimmer volumio[1624]: info: Preparing to generate the ALSA configuration file Mar 10 14:03:29 volumio-wohnzimmer volumio[1624]: info: Asound.conf file unchanged, so no further update is needed Mar 10 14:03:29 volumio-wohnzimmer volumio[1624]: info: Output device has changed, restarting MPD Mar 10 14:03:29 volumio-wohnzimmer sudo[1803]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Mar 10 14:03:29 volumio-wohnzimmer sudo[1803]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 10 14:03:29 volumio-wohnzimmer sudo[1803]: pam_unix(sudo:session): session closed for user root Mar 10 14:03:29 volumio-wohnzimmer sudo[1806]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 10 14:03:29 volumio-wohnzimmer sudo[1806]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 10 14:03:29 volumio-wohnzimmer volumio[1624]: info: Output device has changed, restarting Shairport Sync Mar 10 14:03:29 volumio-wohnzimmer volumio[1624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 10 14:03:29 volumio-wohnzimmer volumio[1624]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 10 14:03:29 volumio-wohnzimmer sudo[1806]: pam_unix(sudo:session): session closed for user root Mar 10 14:03:29 volumio-wohnzimmer sudo[1808]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 10 14:03:29 volumio-wohnzimmer sudo[1808]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 10 14:03:29 volumio-wohnzimmer systemd[1]: Stopping Music Player Daemon... Mar 10 14:03:29 volumio-wohnzimmer volumio[1624]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 10 14:03:29 volumio-wohnzimmer volumio[1624]: info: ___________ START PLUGINS ___________ Mar 10 14:03:29 volumio-wohnzimmer volumio[1624]: info: ControllerMpd::onStart: Initializing MPD Mar 10 14:03:29 volumio-wohnzimmer volumio[1624]: info: Creating MPD Configuration file Mar 10 14:03:29 volumio-wohnzimmer sudo[1827]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 10 14:03:29 volumio-wohnzimmer sudo[1827]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 10 14:03:29 volumio-wohnzimmer sudo[1827]: pam_unix(sudo:session): session closed for user root Mar 10 14:03:29 volumio-wohnzimmer volumio[1624]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 10 14:03:29 volumio-wohnzimmer volumio[1624]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 10 14:03:29 volumio-wohnzimmer volumio[1624]: info: [1741611809959] CoreMusicLibrary::Adding element Medienserver Mar 10 14:03:29 volumio-wohnzimmer volumio[1624]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 10 14:03:29 volumio-wohnzimmer sudo[1839]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 10 14:03:29 volumio-wohnzimmer sudo[1839]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 10 14:03:30 volumio-wohnzimmer volumio[1624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 10 14:03:30 volumio-wohnzimmer volumio[1624]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 10 14:03:30 volumio-wohnzimmer volumio[1624]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 10 14:03:30 volumio-wohnzimmer volumio[1624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 10 14:03:30 volumio-wohnzimmer volumio[1624]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 10 14:03:30 volumio-wohnzimmer systemd[1]: mpd.service: Succeeded. Mar 10 14:03:30 volumio-wohnzimmer systemd[1]: Stopped Music Player Daemon. Mar 10 14:03:30 volumio-wohnzimmer volumio[1624]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 10 14:03:30 volumio-wohnzimmer volumio[1624]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 10 14:03:30 volumio-wohnzimmer volumio[1624]: info: [1741611810142] CoreMusicLibrary::Adding element Last_100 Mar 10 14:03:30 volumio-wohnzimmer volumio[1624]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 10 14:03:30 volumio-wohnzimmer volumio[1624]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 10 14:03:30 volumio-wohnzimmer volumio[1624]: info: [1741611810145] CoreMusicLibrary::Adding element Webradio Mar 10 14:03:30 volumio-wohnzimmer volumio[1624]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 10 14:03:30 volumio-wohnzimmer volumio[1624]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 10 14:03:30 volumio-wohnzimmer volumio[1624]: info: Initializing BBC Radios Mar 10 14:03:30 volumio-wohnzimmer systemd[1]: Starting Music Player Daemon... Mar 10 14:03:30 volumio-wohnzimmer volumio[1624]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 10 14:03:30 volumio-wohnzimmer volumio[1624]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 10 14:03:30 volumio-wohnzimmer volumio[1624]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 10 14:03:30 volumio-wohnzimmer volumio[1624]: info: [1741611810249] CoreMusicLibrary::Adding element SoundCloud Mar 10 14:03:30 volumio-wohnzimmer volumio[1624]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 10 14:03:30 volumio-wohnzimmer volumio[1624]: Cannot find translation for source SoundCloud Mar 10 14:03:30 volumio-wohnzimmer volumio[1624]: info: Creating Spotify config file Mar 10 14:03:30 volumio-wohnzimmer volumio[1624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 10 14:03:30 volumio-wohnzimmer sudo[1850]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 10 14:03:30 volumio-wohnzimmer sudo[1850]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 10 14:03:30 volumio-wohnzimmer sudo[1850]: pam_unix(sudo:session): session closed for user root Mar 10 14:03:30 volumio-wohnzimmer volumio[1624]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 10 14:03:30 volumio-wohnzimmer volumio[1624]: info: [1741611810371] CoreMusicLibrary::Adding element YouTube2 Mar 10 14:03:30 volumio-wohnzimmer volumio[1624]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 10 14:03:30 volumio-wohnzimmer volumio[1624]: Cannot find translation for source SoundCloud Mar 10 14:03:30 volumio-wohnzimmer volumio[1624]: Cannot find translation for source YouTube2 Mar 10 14:03:30 volumio-wohnzimmer volumio[1624]: info: [now-playing] ConfigUpdater: config is up to date. Mar 10 14:03:30 volumio-wohnzimmer volumio[1624]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 10 14:03:30 volumio-wohnzimmer volumio[1624]: info: [1741611810422] CoreMusicLibrary::Adding element 80s80s Radio Mar 10 14:03:30 volumio-wohnzimmer volumio[1624]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 10 14:03:30 volumio-wohnzimmer volumio[1624]: Cannot find translation for source SoundCloud Mar 10 14:03:30 volumio-wohnzimmer volumio[1624]: Cannot find translation for source YouTube2 Mar 10 14:03:30 volumio-wohnzimmer volumio[1624]: Cannot find translation for source 80s80s Radio Mar 10 14:03:30 volumio-wohnzimmer volumio[1624]: info: Volumio Calling Home Mar 10 14:03:30 volumio-wohnzimmer volumio[1624]: info: [now-playing] App is listening on port 4004. Mar 10 14:03:30 volumio-wohnzimmer volumio[1624]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Mar 10 14:03:30 volumio-wohnzimmer volumio[1624]: info: Discovery: adding 07538e23-5acd-4bc4-986f-bbe258cd367f Mar 10 14:03:30 volumio-wohnzimmer volumio[1624]: info: Discovery: Found device Volumio Wohnzimmer Mar 10 14:03:30 volumio-wohnzimmer volumio[1624]: info: CoreCommandRouter::volumioGetState Mar 10 14:03:30 volumio-wohnzimmer volumio[1624]: info: CorePlayQueue::getTrack 0 Mar 10 14:03:30 volumio-wohnzimmer volumio[1624]: info: MPD Permissions set Mar 10 14:03:30 volumio-wohnzimmer volumio[1624]: info: MPD Permissions set Mar 10 14:03:30 volumio-wohnzimmer volumio[1624]: info: Upmpdcli Daemon Started Mar 10 14:03:30 volumio-wohnzimmer volumio[1624]: info: Spotify config file written Mar 10 14:03:30 volumio-wohnzimmer sudo[1867]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Mar 10 14:03:30 volumio-wohnzimmer sudo[1867]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 10 14:03:30 volumio-wohnzimmer volumio[1624]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Mar 10 14:03:30 volumio-wohnzimmer volumio[1624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 10 14:03:30 volumio-wohnzimmer volumio[1624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 10 14:03:30 volumio-wohnzimmer volumio[1624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 10 14:03:30 volumio-wohnzimmer volumio[1624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 10 14:03:30 volumio-wohnzimmer volumio[1624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 10 14:03:30 volumio-wohnzimmer volumio[1624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 10 14:03:30 volumio-wohnzimmer volumio[1624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 10 14:03:30 volumio-wohnzimmer volumio[1624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 10 14:03:30 volumio-wohnzimmer systemd[1]: Stopping go-librespot Daemon... Mar 10 14:03:30 volumio-wohnzimmer systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Mar 10 14:03:30 volumio-wohnzimmer systemd[1]: go-librespot-daemon.service: Succeeded. Mar 10 14:03:30 volumio-wohnzimmer systemd[1]: Stopped go-librespot Daemon. Mar 10 14:03:30 volumio-wohnzimmer volumio[1624]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 10 14:03:30 volumio-wohnzimmer volumio[1624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 10 14:03:30 volumio-wohnzimmer volumio[1624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 10 14:03:30 volumio-wohnzimmer volumio[1624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 10 14:03:30 volumio-wohnzimmer volumio[1624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 10 14:03:30 volumio-wohnzimmer volumio[1624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 10 14:03:30 volumio-wohnzimmer volumio[1624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 10 14:03:30 volumio-wohnzimmer volumio[1624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 10 14:03:30 volumio-wohnzimmer volumio[1624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 10 14:03:30 volumio-wohnzimmer volumio[1624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 10 14:03:30 volumio-wohnzimmer volumio[1624]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 10 14:03:30 volumio-wohnzimmer volumio[1624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 10 14:03:30 volumio-wohnzimmer volumio[1624]: info: No need to fix Spotify hosts Mar 10 14:03:30 volumio-wohnzimmer systemd[1]: Started go-librespot Daemon. Mar 10 14:03:30 volumio-wohnzimmer sudo[1867]: pam_unix(sudo:session): session closed for user root Mar 10 14:03:30 volumio-wohnzimmer go-librespot[1870]: Librespot-go daemon starting... Mar 10 14:03:30 volumio-wohnzimmer go-librespot[1870]: time="2025-03-10T14:03:30+01:00" level=info msg="generated new device id: 375a2741c965025b94b8264d64ff600995a7d738" Mar 10 14:03:30 volumio-wohnzimmer go-librespot[1870]: time="2025-03-10T14:03:30+01:00" level=debug msg="stored credentials found for kwakketfephc24zgbw35608uj" Mar 10 14:03:30 volumio-wohnzimmer volumio[1624]: info: Volumio called home Mar 10 14:03:30 volumio-wohnzimmer volumio[1624]: info: Starting Shairport Sync Mar 10 14:03:31 volumio-wohnzimmer volumio[1624]: info: Starting Shairport Sync Mar 10 14:03:31 volumio-wohnzimmer sudo[1887]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 10 14:03:31 volumio-wohnzimmer sudo[1887]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 10 14:03:31 volumio-wohnzimmer volumio[1624]: info: Starting Shairport Sync Mar 10 14:03:31 volumio-wohnzimmer systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 10 14:03:31 volumio-wohnzimmer systemd[1]: shairport-sync.service: Succeeded. Mar 10 14:03:31 volumio-wohnzimmer systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 10 14:03:31 volumio-wohnzimmer sudo[1890]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 10 14:03:31 volumio-wohnzimmer sudo[1890]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 10 14:03:31 volumio-wohnzimmer go-librespot[1870]: time="2025-03-10T14:03:31+01: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]" Mar 10 14:03:31 volumio-wohnzimmer go-librespot[1870]: time="2025-03-10T14:03:31+01: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]" Mar 10 14:03:31 volumio-wohnzimmer go-librespot[1870]: time="2025-03-10T14:03:31+01: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]" Mar 10 14:03:31 volumio-wohnzimmer go-librespot[1870]: time="2025-03-10T14:03:31+01:00" level=debug msg="zeroconf server listening on port 42635" Mar 10 14:03:31 volumio-wohnzimmer volumio[1624]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Mar 10 14:03:31 volumio-wohnzimmer volumio[1624]: SPOTIFY: BQC8xWLT4RixkBYirJxHF7m5n4ZYrVt4mEILTEIQllCN6aRq-e2_F3SVNpCAnXr0hxvb5F8F4QMJRaUafIeVCvXBEj_IQFYhYPnR06ufGR4AGd17L7dpUHjH-a4n6j47Rumg6mlv4Wuuxp3a0pHYwbfG9yapjfBrtS5HWmDE8euQFesh4RfRrl6MtH6UDpN-Q5i6oZ5atCfmTIHxfHDVAivp1Ed9xT8OipUlrpmk6j3ZAWxqiXhaaCWRRGqWERQslNt75q0U_wqLAnPiDIHqqZQe0DNvJ8c5nyfnSok Mar 10 14:03:31 volumio-wohnzimmer volumio[1624]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Mar 10 14:03:31 volumio-wohnzimmer volumio[1624]: info: New Spotify access token = BQC8xWLT4RixkBYirJxHF7m5n4ZYrVt4mEILTEIQllCN6aRq-e2_F3SVNpCAnXr0hxvb5F8F4QMJRaUafIeVCvXBEj_IQFYhYPnR06ufGR4AGd17L7dpUHjH-a4n6j47Rumg6mlv4Wuuxp3a0pHYwbfG9yapjfBrtS5HWmDE8euQFesh4RfRrl6MtH6UDpN-Q5i6oZ5atCfmTIHxfHDVAivp1Ed9xT8OipUlrpmk6j3ZAWxqiXhaaCWRRGqWERQslNt75q0U_wqLAnPiDIHqqZQe0DNvJ8c5nyfnSok Mar 10 14:03:31 volumio-wohnzimmer volumio[1624]: info: Spotify credentials grant success - running version from March 24, 2019 Mar 10 14:03:31 volumio-wohnzimmer sudo[1894]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 10 14:03:31 volumio-wohnzimmer sudo[1894]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 10 14:03:31 volumio-wohnzimmer systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 10 14:03:31 volumio-wohnzimmer sudo[1887]: pam_unix(sudo:session): session closed for user root Mar 10 14:03:31 volumio-wohnzimmer go-librespot[1870]: time="2025-03-10T14:03:31+01:00" level=debug msg="obtained new client token: AACJTtLo9MAZuf9xlq/nJOg+HCzqYUkw/v36LtD7o+lmqmkJgxvGHP9zv6jiC/lgguDVIxSX64IIvKLyrasKsrpGM6maW25CKaptd67jhDyQIP+nU1UmuffnLeZGKGkf8HhX5JwEt318XkOoAikxZYwHhAyhOj9Mb65aqBQi6DBoc9EZfUEo0tFsH8xVJSh7HIEsCfUTWnIn0n59r/CxSFfvXedFHF8spMC/NqI5rHo217/kGNwKARc=" Mar 10 14:03:31 volumio-wohnzimmer systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 10 14:03:31 volumio-wohnzimmer systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Mar 10 14:03:31 volumio-wohnzimmer systemd[1]: shairport-sync.service: Succeeded. Mar 10 14:03:31 volumio-wohnzimmer systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 10 14:03:31 volumio-wohnzimmer volumio[1624]: info: CoreCommandRouter::volumioGetState Mar 10 14:03:31 volumio-wohnzimmer volumio[1624]: info: CorePlayQueue::getTrack 0 Mar 10 14:03:31 volumio-wohnzimmer systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 10 14:03:31 volumio-wohnzimmer go-librespot[1870]: time="2025-03-10T14:03:31+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Mar 10 14:03:31 volumio-wohnzimmer sudo[1894]: pam_unix(sudo:session): session closed for user root Mar 10 14:03:31 volumio-wohnzimmer go-librespot[1870]: time="2025-03-10T14:03:31+01:00" level=debug msg="completed keyexchange" Mar 10 14:03:31 volumio-wohnzimmer sudo[1890]: pam_unix(sudo:session): session closed for user root Mar 10 14:03:31 volumio-wohnzimmer volumio[1624]: info: Shairport-Sync Started Mar 10 14:03:31 volumio-wohnzimmer volumio[1624]: Error adding Membership: Error: addMembership EINVAL Mar 10 14:03:31 volumio-wohnzimmer volumio[1624]: info: Shairport-Sync Started Mar 10 14:03:31 volumio-wohnzimmer volumio[1624]: info: Shairport-Sync Started Mar 10 14:03:31 volumio-wohnzimmer volumio[1624]: SPOTIFY: User informations: {"country":"DE","display_name":"GRKPI","email":"spotify@sr-club.de","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/kwakketfephc24zgbw35608uj"},"followers":{"href":null,"total":2},"href":"https://api.spotify.com/v1/users/kwakketfephc24zgbw35608uj","id":"kwakketfephc24zgbw35608uj","images":[],"product":"premium","type":"user","uri":"spotify:user:kwakketfephc24zgbw35608uj"} Mar 10 14:03:31 volumio-wohnzimmer volumio[1624]: info: Spotify Successfully logged in Mar 10 14:03:31 volumio-wohnzimmer volumio[1624]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 10 14:03:31 volumio-wohnzimmer volumio[1624]: info: [1741611811378] CoreMusicLibrary::Adding element Spotify Mar 10 14:03:31 volumio-wohnzimmer volumio[1624]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 10 14:03:31 volumio-wohnzimmer volumio[1624]: Cannot find translation for source SoundCloud Mar 10 14:03:31 volumio-wohnzimmer volumio[1624]: Cannot find translation for source YouTube2 Mar 10 14:03:31 volumio-wohnzimmer volumio[1624]: Cannot find translation for source 80s80s Radio Mar 10 14:03:31 volumio-wohnzimmer volumio[1624]: Cannot find translation for source Spotify Mar 10 14:03:31 volumio-wohnzimmer volumio-remote-updater[536]: [2025-03-10 14:03:31] [connect] Successful connection Mar 10 14:03:31 volumio-wohnzimmer volumio-remote-updater[536]: [2025-03-10 14:03:31] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1741611811 101 Mar 10 14:03:31 volumio-wohnzimmer volumio[1624]: 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: 2 Mar 10 14:03:31 volumio-wohnzimmer go-librespot[1870]: time="2025-03-10T14:03:31+01:00" level=debug msg="completed challenge" Mar 10 14:03:31 volumio-wohnzimmer go-librespot[1870]: time="2025-03-10T14:03:31+01:00" level=debug msg="authenticated as kwakketfephc24zgbw35608uj" Mar 10 14:03:32 volumio-wohnzimmer mpd[1862]: Mar 10 14:03 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 10 14:03:32 volumio-wohnzimmer go-librespot[1870]: time="2025-03-10T14:03:32+01:00" level=debug msg="authenticated as kwakketfephc24zgbw35608uj" Mar 10 14:03:32 volumio-wohnzimmer go-librespot[1870]: time="2025-03-10T14:03:32+01:00" level=debug msg="dealer connection opened" Mar 10 14:03:32 volumio-wohnzimmer go-librespot[1870]: time="2025-03-10T14:03:32+01:00" level=debug msg="initializing zeroconf session, username: kwakketfephc24zgbw35608uj" Mar 10 14:03:32 volumio-wohnzimmer go-librespot[1870]: time="2025-03-10T14:03:32+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Mar 10 14:03:32 volumio-wohnzimmer go-librespot[1870]: time="2025-03-10T14:03:32+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Mar 10 14:03:32 volumio-wohnzimmer go-librespot[1870]: time="2025-03-10T14:03:32+01:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Mar 10 14:03:32 volumio-wohnzimmer go-librespot[1870]: time="2025-03-10T14:03:32+01:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Mar 10 14:03:32 volumio-wohnzimmer go-librespot[1870]: time="2025-03-10T14:03:32+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Mar 10 14:03:32 volumio-wohnzimmer go-librespot[1870]: time="2025-03-10T14:03:32+01:00" level=debug msg="autoplay enabled: false" Mar 10 14:03:32 volumio-wohnzimmer go-librespot[1870]: time="2025-03-10T14:03:32+01:00" level=debug msg="received connection id: Zjc4MTA0MGUtYjMxZS00MzViLWEwM2QtZWZjNTMwYjA4MDhmK2RlYWxlcit0Y3A6Ly8wYWNhNWE0MS5pcC5nZXc0LnNwb3RpZnkubmV0OjU3MDArRjM0NjYzODJCQTJDMDdCMDgwQkE4NDRBRUY2N0E3MTU2OTY3OTYwNUVFRjFFQUY5MjYzQjQ4NzM4MUYwODlGQw==" Mar 10 14:03:32 volumio-wohnzimmer go-librespot[1870]: time="2025-03-10T14:03:32+01:00" level=debug msg="put connect state because NEW_DEVICE" Mar 10 14:03:33 volumio-wohnzimmer systemd[1]: Started Music Player Daemon. Mar 10 14:03:33 volumio-wohnzimmer sudo[1808]: pam_unix(sudo:session): session closed for user root Mar 10 14:03:33 volumio-wohnzimmer sudo[1839]: pam_unix(sudo:session): session closed for user root Mar 10 14:03:33 volumio-wohnzimmer volumio[1624]: error: MPD error: The expression evaluated to a falsy value: Mar 10 14:03:33 volumio-wohnzimmer volumio[1624]: assert.ok(self.idling) Mar 10 14:03:33 volumio-wohnzimmer volumio[1624]: error: The expression evaluated to a falsy value: Mar 10 14:03:33 volumio-wohnzimmer volumio[1624]: assert.ok(self.idling) Mar 10 14:03:33 volumio-wohnzimmer volumio[1624]: info: MPD running with PID1862 Mar 10 14:03:33 volumio-wohnzimmer volumio[1624]: ,establishing connection Mar 10 14:03:33 volumio-wohnzimmer volumio[1624]: error: updateQueue error: null Mar 10 14:03:33 volumio-wohnzimmer volumio[1624]: [yt-cast-receiver] Listening on port 8098 Mar 10 14:03:33 volumio-wohnzimmer volumio[1624]: info: Completed starting Core Plugins Mar 10 14:03:33 volumio-wohnzimmer volumio[1624]: info: ------------------------------------------- Mar 10 14:03:33 volumio-wohnzimmer volumio[1624]: info: ----- MyVolumio plugins startup ---- Mar 10 14:03:33 volumio-wohnzimmer volumio[1624]: info: ------------------------------------------- Mar 10 14:03:33 volumio-wohnzimmer volumio[1624]: info: [MyVolumio PluginManager] Fetching plans data.... Mar 10 14:03:33 volumio-wohnzimmer volumio[1624]: error: updateQueue error: null Mar 10 14:03:33 volumio-wohnzimmer volumio[1624]: info: go-librespot daemon successfully initialized Mar 10 14:03:34 volumio-wohnzimmer sudo[1905]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=georg,password=gogo123,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //moria.fritz.box/music /mnt/NAS/Moria Mar 10 14:03:34 volumio-wohnzimmer sudo[1905]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 10 14:03:34 volumio-wohnzimmer kernel: CIFS: Attempting to mount //moria.fritz.box/music Mar 10 14:03:36 volumio-wohnzimmer volumio[1624]: info: Initializing connection to go-librespot Websocket Mar 10 14:03:36 volumio-wohnzimmer go-librespot[1870]: time="2025-03-10T14:03:36+01:00" level=debug msg="new websocket client" Mar 10 14:03:36 volumio-wohnzimmer volumio[1624]: info: Connection to go-librespot Websocket established Mar 10 14:03:37 volumio-wohnzimmer volumio[1624]: info: CoreCommandRouter::volumioGetState Mar 10 14:03:37 volumio-wohnzimmer volumio[1624]: info: CorePlayQueue::getTrack 0 Mar 10 14:03:37 volumio-wohnzimmer go-librespot[1870]: time="2025-03-10T14:03:37+01:00" level=error msg="failed receiving dealer message" error="failed to get reader: received close frame: status = StatusGoingAway and reason = \"\"" Mar 10 14:03:37 volumio-wohnzimmer go-librespot[1870]: time="2025-03-10T14:03:37+01:00" level=error msg="failed receiving packet" error="failed reading packet header: read tcp 192.168.178.54:47472->34.158.1.133:4070: use of closed network connection" Mar 10 14:03:37 volumio-wohnzimmer go-librespot[1870]: time="2025-03-10T14:03:37+01:00" level=debug msg="obtained new client token: AAAC+zOFRzRjrW57pQBOzpcLa9ugEE+OzFPG/tr9eOSFvFx/5lMZJEyGOKn7dWXTsdjOzz9k8BUplEIDBgz7nxfZ9/oDE/+FBp6TmqtCZVpHuNgoOmn3DWb9kIBEG3vrOoAGlskqHX76fqxpEMrB9COpYdDcgTpJx+C5M+3u79ijfof/NZ7mRHIsG2nrMv/HxEz+LuvFdA52BmZvXFStcfaF5fbWk6/Ei2i8IRuzF0fXDLNa5Lh9g7G6aQ==" Mar 10 14:03:37 volumio-wohnzimmer go-librespot[1870]: time="2025-03-10T14:03:37+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Mar 10 14:03:37 volumio-wohnzimmer go-librespot[1870]: time="2025-03-10T14:03:37+01:00" level=debug msg="completed keyexchange" Mar 10 14:03:37 volumio-wohnzimmer go-librespot[1870]: time="2025-03-10T14:03:37+01:00" level=debug msg="completed challenge" Mar 10 14:03:38 volumio-wohnzimmer go-librespot[1870]: time="2025-03-10T14:03:38+01:00" level=debug msg="authenticated as b008fr7kb2zlhrpsbq1r8n8pq" Mar 10 14:03:38 volumio-wohnzimmer go-librespot[1870]: time="2025-03-10T14:03:38+01:00" level=debug msg="authenticated as b008fr7kb2zlhrpsbq1r8n8pq" Mar 10 14:03:38 volumio-wohnzimmer go-librespot[1870]: time="2025-03-10T14:03:38+01:00" level=debug msg="dealer connection opened" Mar 10 14:03:38 volumio-wohnzimmer go-librespot[1870]: time="2025-03-10T14:03:38+01:00" level=info msg="accepted zeroconf user b008fr7kb2zlhrpsbq1r8n8pq from Bantje" Mar 10 14:03:38 volumio-wohnzimmer go-librespot[1870]: time="2025-03-10T14:03:38+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Mar 10 14:03:38 volumio-wohnzimmer go-librespot[1870]: time="2025-03-10T14:03:38+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Mar 10 14:03:38 volumio-wohnzimmer go-librespot[1870]: time="2025-03-10T14:03:38+01:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Mar 10 14:03:38 volumio-wohnzimmer go-librespot[1870]: time="2025-03-10T14:03:38+01:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Mar 10 14:03:38 volumio-wohnzimmer go-librespot[1870]: time="2025-03-10T14:03:38+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Mar 10 14:03:38 volumio-wohnzimmer go-librespot[1870]: time="2025-03-10T14:03:38+01:00" level=debug msg="autoplay enabled: false" Mar 10 14:03:38 volumio-wohnzimmer go-librespot[1870]: time="2025-03-10T14:03:38+01:00" level=debug msg="received connection id: MzNjOTEyMzYtZTY4Zi00NjQ2LWFjNDctYmNhNmE2NTcyZmQ0K2RlYWxlcit0Y3A6Ly8wYWNhNWE1Ny5pcC5nZXc0LnNwb3RpZnkubmV0OjU3MDArQTg1RjI0QTFCNDhCRDdGMzQyM0FFODM1ODU5RkVENUFDMDk3QUM1Mzk5RkFEQzFGNDhFNzg0RkZCNDA0OUVCRg==" Mar 10 14:03:38 volumio-wohnzimmer go-librespot[1870]: time="2025-03-10T14:03:38+01:00" level=debug msg="put connect state because NEW_DEVICE" Mar 10 14:03:38 volumio-wohnzimmer go-librespot[1870]: time="2025-03-10T14:03:38+01:00" level=debug msg="handling transfer player command from 59c6aa148bed3146ff817b1e313cc3be79b9fa69" Mar 10 14:03:38 volumio-wohnzimmer go-librespot[1870]: time="2025-03-10T14:03:38+01:00" level=debug msg="resolved context of track" uri="spotify:user:b008fr7kb2zlhrpsbq1r8n8pq:collection" Mar 10 14:03:38 volumio-wohnzimmer go-librespot[1870]: time="2025-03-10T14:03:38+01:00" level=trace msg="fetched new page 0 with 127 items (list: 127)" uri="spotify:user:b008fr7kb2zlhrpsbq1r8n8pq:collection" Mar 10 14:03:38 volumio-wohnzimmer go-librespot[1870]: time="2025-03-10T14:03:38+01:00" level=debug msg="shuffled context with seed 10943911328139067978 (len: 127, keep: 122)" Mar 10 14:03:38 volumio-wohnzimmer go-librespot[1870]: time="2025-03-10T14:03:38+01:00" level=debug msg="loading track (paused: false, position: 4487ms)" uri="spotify:track:60a0Rd6pjrkxjPbaKzXjfq" Mar 10 14:03:38 volumio-wohnzimmer go-librespot[1870]: time="2025-03-10T14:03:38+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Mar 10 14:03:38 volumio-wohnzimmer go-librespot[1870]: time="2025-03-10T14:03:38+01:00" level=trace msg="emitting websocket event: will_play" Mar 10 14:03:38 volumio-wohnzimmer volumio[1624]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:60a0Rd6pjrkxjPbaKzXjfq","play_origin":"playlist"}} Mar 10 14:03:38 volumio-wohnzimmer go-librespot[1870]: time="2025-03-10T14:03:38+01:00" level=debug msg="selected format OGG_VORBIS_320 (2e1e3c01162ca501682d93020cc68744b65bfd68)" uri="spotify:track:60a0Rd6pjrkxjPbaKzXjfq" Mar 10 14:03:38 volumio-wohnzimmer go-librespot[1870]: time="2025-03-10T14:03:38+01:00" level=debug msg="requested aes key for file 2e1e3c01162ca501682d93020cc68744b65bfd68, gid: 60a0Rd6pjrkxjPbaKzXjfq" Mar 10 14:03:39 volumio-wohnzimmer go-librespot[1870]: time="2025-03-10T14:03:39+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 345" Mar 10 14:03:39 volumio-wohnzimmer go-librespot[1870]: time="2025-03-10T14:03:39+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1703" Mar 10 14:03:39 volumio-wohnzimmer go-librespot[1870]: time="2025-03-10T14:03:39+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1303" Mar 10 14:03:39 volumio-wohnzimmer go-librespot[1870]: time="2025-03-10T14:03:39+01:00" level=debug msg="fetched first chunk of 17, total size is 8741472 bytes" uri="spotify:track:60a0Rd6pjrkxjPbaKzXjfq" Mar 10 14:03:39 volumio-wohnzimmer go-librespot[1870]: time="2025-03-10T14:03:39+01:00" level=trace msg="seek to 4487ms (diff: 112ms, samples: 197876, bytes: 167947)" uri="spotify:track:60a0Rd6pjrkxjPbaKzXjfq" Mar 10 14:03:39 volumio-wohnzimmer go-librespot[1870]: time="2025-03-10T14:03:39+01:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 5102 us, period size = 225 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 98 frames" Mar 10 14:03:39 volumio-wohnzimmer go-librespot[1870]: time="2025-03-10T14:03:39+01:00" level=debug msg="created new output device" Mar 10 14:03:39 volumio-wohnzimmer go-librespot[1870]: time="2025-03-10T14:03:39+01:00" level=info msg="loaded track \"In the End\" (paused: false, position: 4487ms, duration: 216880ms, prefetched: false)" uri="spotify:track:60a0Rd6pjrkxjPbaKzXjfq" Mar 10 14:03:39 volumio-wohnzimmer kernel: bcm2835-i2s 3f203000.i2s: I2S SYNC error! Mar 10 14:03:39 volumio-wohnzimmer go-librespot[1870]: time="2025-03-10T14:03:39+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Mar 10 14:03:39 volumio-wohnzimmer go-librespot[1870]: time="2025-03-10T14:03:39+01:00" level=debug msg="fetched chunk 3/16, size: 524288" uri="spotify:track:60a0Rd6pjrkxjPbaKzXjfq" Mar 10 14:03:39 volumio-wohnzimmer go-librespot[1870]: time="2025-03-10T14:03:39+01:00" level=trace msg="scheduling prefetch in 182s" Mar 10 14:03:39 volumio-wohnzimmer go-librespot[1870]: time="2025-03-10T14:03:39+01:00" level=trace msg="emitting websocket event: metadata" Mar 10 14:03:39 volumio-wohnzimmer go-librespot[1870]: time="2025-03-10T14:03:39+01:00" level=trace msg="emitting websocket event: active" Mar 10 14:03:39 volumio-wohnzimmer go-librespot[1870]: time="2025-03-10T14:03:39+01:00" level=debug msg="sending successful reply for dealer request" Mar 10 14:03:39 volumio-wohnzimmer go-librespot[1870]: time="2025-03-10T14:03:39+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Mar 10 14:03:39 volumio-wohnzimmer go-librespot[1870]: time="2025-03-10T14:03:39+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Mar 10 14:03:39 volumio-wohnzimmer volumio[1624]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:60a0Rd6pjrkxjPbaKzXjfq","name":"In the End","artist_names":["Linkin Park"],"album_name":"Hybrid Theory (Bonus Edition)","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02e2f039481babe23658fc719a","position":4487,"duration":216880,"release_date":"year:2000 month:10 day:24","track_number":8,"disc_number":1}} Mar 10 14:03:39 volumio-wohnzimmer volumio[1624]: SPOTIFY: received: {"type":"active","data":null} Mar 10 14:03:39 volumio-wohnzimmer volumio[1624]: info: Aligning Spotify Volume to Volumio Volume Mar 10 14:03:39 volumio-wohnzimmer volumio[1624]: info: CoreCommandRouter::volumioGetState Mar 10 14:03:39 volumio-wohnzimmer volumio[1624]: info: CorePlayQueue::getTrack 0 Mar 10 14:03:39 volumio-wohnzimmer go-librespot[1870]: time="2025-03-10T14:03:39+01:00" level=debug msg="fetched chunk 2/16, size: 524288" uri="spotify:track:60a0Rd6pjrkxjPbaKzXjfq" Mar 10 14:03:39 volumio-wohnzimmer go-librespot[1870]: time="2025-03-10T14:03:39+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Mar 10 14:03:39 volumio-wohnzimmer go-librespot[1870]: time="2025-03-10T14:03:39+01:00" level=debug msg="fetched chunk 1/16, size: 524288" uri="spotify:track:60a0Rd6pjrkxjPbaKzXjfq" Mar 10 14:03:39 volumio-wohnzimmer go-librespot[1870]: time="2025-03-10T14:03:39+01:00" level=trace msg="emitting websocket event: playing" Mar 10 14:03:39 volumio-wohnzimmer volumio[1624]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:60a0Rd6pjrkxjPbaKzXjfq","play_origin":"playlist"}} Mar 10 14:03:39 volumio-wohnzimmer volumio[1624]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 10 14:03:39 volumio-wohnzimmer volumio[1624]: TypeError: Cannot read property 'service' of undefined Mar 10 14:03:39 volumio-wohnzimmer volumio[1624]: at ControllerSpotify.identifyPlaybackMode (/data/plugins/music_service/spop/index.js:337:50) Mar 10 14:03:39 volumio-wohnzimmer volumio[1624]: at ControllerSpotify.parseEventState (/data/plugins/music_service/spop/index.js:263:18) Mar 10 14:03:39 volumio-wohnzimmer volumio[1624]: at WebSocket.message (/data/plugins/music_service/spop/index.js:190:14) Mar 10 14:03:39 volumio-wohnzimmer volumio[1624]: at WebSocket.emit (events.js:315:20) Mar 10 14:03:39 volumio-wohnzimmer volumio[1624]: at Receiver.receiverOnMessage (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1220:20) Mar 10 14:03:39 volumio-wohnzimmer volumio[1624]: at Receiver.emit (events.js:315:20) Mar 10 14:03:39 volumio-wohnzimmer volumio[1624]: at Receiver.dataMessage (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:596:14) Mar 10 14:03:39 volumio-wohnzimmer volumio[1624]: at Receiver.getData (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:496:10) Mar 10 14:03:39 volumio-wohnzimmer volumio[1624]: at Receiver.startLoop (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:167:16) Mar 10 14:03:39 volumio-wohnzimmer volumio[1624]: at Receiver._write (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:94:10) Mar 10 14:03:39 volumio-wohnzimmer volumio[1624]: at writeOrBuffer (internal/streams/writable.js:358:12) Mar 10 14:03:39 volumio-wohnzimmer volumio[1624]: at Receiver.Writable.write (internal/streams/writable.js:303:10) Mar 10 14:03:39 volumio-wohnzimmer volumio[1624]: at Socket.socketOnData (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1355:35) Mar 10 14:03:39 volumio-wohnzimmer volumio[1624]: at Socket.emit (events.js:315:20) Mar 10 14:03:39 volumio-wohnzimmer volumio[1624]: at addChunk (internal/streams/readable.js:309:12) Mar 10 14:03:39 volumio-wohnzimmer volumio[1624]: at readableAddChunk (internal/streams/readable.js:284:9) Mar 10 14:03:39 volumio-wohnzimmer volumio[1624]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 10 14:03:40 volumio-wohnzimmer sudo[1943]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-10 14:02 Mar 10 14:03:40 volumio-wohnzimmer sudo[1943]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="a01a833dd28483f88cb390596f5090b7187992af" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 27 Feb 2025 11:42:54 AM CET" VOLUMIO_VERSION="3.795" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4049a67f1c09d4a94e033ba35a80a144"