-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Sun 2025-06-15 23:37:40 CEST. -- Jun 15 23:36:02 volumiomusik systemd-timedated[1090]: Changed local time to Sun Jun 15 23:36:02 2025 Jun 15 23:36:02 volumiomusik sudo[1088]: pam_unix(sudo:session): session closed for user root Jun 15 23:36:02 volumiomusik volumio-time-update[577]: volumio-time-update-util: System time updated successfully. Jun 15 23:36:02 volumiomusik systemd[1]: Starting Daily apt download activities... Jun 15 23:36:02 volumiomusik systemd[1]: Started Volumio Time Update Utility. Jun 15 23:36:02 volumiomusik systemd[1]: Reached target Multi-User System. Jun 15 23:36:02 volumiomusik systemd[1]: Reached target Graphical Interface. Jun 15 23:36:02 volumiomusik systemd[1]: Starting Update UTMP about System Runlevel Changes... Jun 15 23:36:02 volumiomusik systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jun 15 23:36:02 volumiomusik systemd[1]: Started Update UTMP about System Runlevel Changes. Jun 15 23:36:02 volumiomusik volumio[1000]: info: Loading plugin "airplay_emulation"... Jun 15 23:36:02 volumiomusik volumio[1000]: info: Starting Shairport Sync Jun 15 23:36:02 volumiomusik volumio[1000]: info: Loading plugin "last_100"... Jun 15 23:36:02 volumiomusik volumio[1000]: info: Loading plugin "webradio"... Jun 15 23:36:02 volumiomusik volumio[1000]: info: Loading plugin "i2s_dacs"... Jun 15 23:36:02 volumiomusik volumio[1000]: info: I2S DAC not set, start Auto-detection Jun 15 23:36:02 volumiomusik volumio[1000]: info: Loading plugin "volumiodiscovery"... Jun 15 23:36:02 volumiomusik volumio[1000]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 15 23:36:02 volumiomusik volumio[1000]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 15 23:36:02 volumiomusik volumio[1000]: *** WARNING *** For more information see Jun 15 23:36:02 volumiomusik volumio[1000]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 15 23:36:02 volumiomusik volumio[1000]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 15 23:36:02 volumiomusik volumio[1000]: *** WARNING *** For more information see Jun 15 23:36:02 volumiomusik node[1000]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 15 23:36:02 volumiomusik node[1000]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 15 23:36:02 volumiomusik node[1000]: *** WARNING *** For more information see Jun 15 23:36:02 volumiomusik node[1000]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 15 23:36:02 volumiomusik node[1000]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 15 23:36:02 volumiomusik node[1000]: *** WARNING *** For more information see Jun 15 23:36:02 volumiomusik volumio[1000]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 15 23:36:02 volumiomusik volumio[1000]: info: Discovery: Started advertising with name: VolumioMusik Jun 15 23:36:02 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 15 23:36:02 volumiomusik volumio[1000]: info: Loading plugin "spop"... Jun 15 23:36:02 volumiomusik systemd[1]: apt-daily.service: Succeeded. Jun 15 23:36:02 volumiomusik systemd[1]: Started Daily apt download activities. Jun 15 23:36:02 volumiomusik systemd[1]: Startup finished in 11.940s (kernel) + 30.066s (userspace) = 42.007s. Jun 15 23:36:03 volumiomusik volumio[1000]: info: Loading plugin "youtube2"... Jun 15 23:36:04 volumiomusik sudo[1059]: pam_unix(sudo:session): session closed for user root Jun 15 23:36:05 volumiomusik systemd[1]: systemd-fsckd.service: Succeeded. Jun 15 23:36:05 volumiomusik volumio[1000]: info: Loading plugin "outputs"... Jun 15 23:36:05 volumiomusik volumio[1000]: info: Loading plugin "albumart"... Jun 15 23:36:06 volumiomusik volumio[1000]: info: Plugin example_plugin is not enabled Jun 15 23:36:06 volumiomusik volumio[1000]: info: Loading plugin "inputs"... Jun 15 23:36:06 volumiomusik volumio[1000]: info: Loading plugin "updater_comm"... Jun 15 23:36:06 volumiomusik sh[497]: timed out Jun 15 23:36:06 volumiomusik dhcpcd[530]: timed out Jun 15 23:36:06 volumiomusik sh[497]: dhcpcd exited Jun 15 23:36:06 volumiomusik dhcpcd[530]: dhcpcd exited Jun 15 23:36:06 volumiomusik sh[497]: ifup: failed to bring up eth0 Jun 15 23:36:06 volumiomusik systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Jun 15 23:36:06 volumiomusik systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Jun 15 23:36:06 volumiomusik volumio[1000]: info: Plugin mpdemulation is not enabled Jun 15 23:36:06 volumiomusik volumio[1000]: info: Loading plugin "rest_api"... Jun 15 23:36:06 volumiomusik volumio[1000]: info: Loading plugin "websocket"... Jun 15 23:36:06 volumiomusik volumio[1000]: info: Starting Socket.io Server version 2.3.0 Jun 15 23:36:06 volumiomusik volumio[1000]: info: Loading i18n strings for locale de Jun 15 23:36:06 volumiomusik volumio[1000]: Forking 3 albumart workers Jun 15 23:36:06 volumiomusik volumio[1000]: Updating browse sources language Jun 15 23:36:06 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 15 23:36:06 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 15 23:36:06 volumiomusik volumio[1000]: info: CoreCommandRouter::initPlayerControls Jun 15 23:36:06 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 23:36:06 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 23:36:06 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 23:36:06 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 23:36:06 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 23:36:06 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 23:36:06 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 23:36:06 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 23:36:06 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 15 23:36:06 volumiomusik volumio[1000]: Express server listening on port 3000 Jun 15 23:36:06 volumiomusik volumio[1000]: [Metrics] WebUI: 8s 7.06ms Jun 15 23:36:06 volumiomusik volumio[1000]: info: CoreStateMachine::resetVolumioState Jun 15 23:36:06 volumiomusik volumio[1000]: info: CoreStateMachine::getcurrentVolume Jun 15 23:36:06 volumiomusik volumio[1000]: info: CoreCommandRouter::volumioRetrievevolume Jun 15 23:36:06 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 15 23:36:06 volumiomusik volumio[1000]: info: Volumio Network Manager: Network status updated: 2 Jun 15 23:36:06 volumiomusik volumio[1000]: info: Reloading queue from file Jun 15 23:36:06 volumiomusik volumio[1000]: info: Setting Device type: Raspberry PI Jun 15 23:36:06 volumiomusik volumio[1000]: info: VolumeController:: Volume=63 Mute =false Jun 15 23:36:06 volumiomusik volumio[1000]: info: CoreStateMachine::pushState Jun 15 23:36:06 volumiomusik volumio[1000]: info: CorePlayQueue::getTrack 0 Jun 15 23:36:06 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 15 23:36:06 volumiomusik volumio[1000]: info: CoreCommandRouter::volumioPushState Jun 15 23:36:06 volumiomusik volumio[1000]: info: CoreStateMachine::updateTrackBlock Jun 15 23:36:06 volumiomusik volumio[1000]: info: CorePlayQueue::getTrackBlock Jun 15 23:36:06 volumiomusik volumio[1000]: info: CoreCommandRouter::volumioRetrievevolume Jun 15 23:36:06 volumiomusik volumio[1000]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jun 15 23:36:06 volumiomusik volumio[1000]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03115 Jun 15 23:36:06 volumiomusik volumio[1000]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jun 15 23:36:06 volumiomusik volumio[1000]: info: CoreStateMachine::setRepeat null single undefined Jun 15 23:36:06 volumiomusik volumio[1000]: info: CoreStateMachine::pushState Jun 15 23:36:06 volumiomusik volumio[1000]: info: CorePlayQueue::getTrack 0 Jun 15 23:36:06 volumiomusik volumio[1000]: info: CoreCommandRouter::volumioPushState Jun 15 23:36:06 volumiomusik volumio[1000]: info: CoreStateMachine::setRandom null Jun 15 23:36:06 volumiomusik volumio[1000]: info: CoreStateMachine::pushState Jun 15 23:36:06 volumiomusik volumio[1000]: info: CorePlayQueue::getTrack 0 Jun 15 23:36:06 volumiomusik volumio[1000]: Starting albumart workers Jun 15 23:36:06 volumiomusik volumio[1000]: Starting albumart workers Jun 15 23:36:06 volumiomusik volumio[1000]: Starting albumart workers Jun 15 23:36:06 volumiomusik volumio[1000]: info: CoreCommandRouter::volumioPushState Jun 15 23:36:06 volumiomusik volumio[1000]: info: Completed loading Core Plugins Jun 15 23:36:06 volumiomusik volumio[1000]: info: Preparing to generate the ALSA configuration file Jun 15 23:36:06 volumiomusik volumio[1000]: info: VolumeController:: Volume=63 Mute =false Jun 15 23:36:06 volumiomusik volumio[1000]: info: CoreStateMachine::pushState Jun 15 23:36:06 volumiomusik volumio[1000]: info: CorePlayQueue::getTrack 0 Jun 15 23:36:06 volumiomusik volumio[1000]: info: CoreCommandRouter::volumioPushState Jun 15 23:36:06 volumiomusik volumio[1000]: info: Asound.conf file unchanged, so no further update is needed Jun 15 23:36:06 volumiomusik volumio[1000]: info: Output device has changed, restarting MPD Jun 15 23:36:06 volumiomusik volumio[1000]: info: Output device has changed, restarting Shairport Sync Jun 15 23:36:06 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 23:36:06 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 15 23:36:06 volumiomusik sudo[1187]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 15 23:36:06 volumiomusik sudo[1187]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 23:36:06 volumiomusik sudo[1187]: pam_unix(sudo:session): session closed for user root Jun 15 23:36:06 volumiomusik sudo[1189]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 15 23:36:06 volumiomusik sudo[1189]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 23:36:06 volumiomusik systemd[1]: Stopping Music Player Daemon... Jun 15 23:36:07 volumiomusik volumio[1000]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 15 23:36:07 volumiomusik volumio[1000]: info: ___________ START PLUGINS ___________ Jun 15 23:36:07 volumiomusik volumio[1000]: info: ControllerMpd::onStart: Initializing MPD Jun 15 23:36:07 volumiomusik volumio[1000]: info: Creating MPD Configuration file Jun 15 23:36:07 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 15 23:36:07 volumiomusik volumio[1000]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 15 23:36:07 volumiomusik volumio[1000]: info: [1750023367047] CoreMusicLibrary::Adding element Medienserver Jun 15 23:36:07 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 15 23:36:07 volumiomusik sudo[1196]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 15 23:36:07 volumiomusik sudo[1196]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 23:36:07 volumiomusik sudo[1196]: pam_unix(sudo:session): session closed for user root Jun 15 23:36:07 volumiomusik sudo[1198]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 15 23:36:07 volumiomusik sudo[1198]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 23:36:07 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 23:36:07 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 15 23:36:07 volumiomusik systemd[1]: mpd.service: Succeeded. Jun 15 23:36:07 volumiomusik systemd[1]: Stopped Music Player Daemon. Jun 15 23:36:07 volumiomusik volumio[1000]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 15 23:36:07 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 23:36:07 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 15 23:36:07 volumiomusik systemd[1]: Starting Music Player Daemon... Jun 15 23:36:07 volumiomusik volumio[1000]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 15 23:36:07 volumiomusik volumio[1000]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 15 23:36:07 volumiomusik volumio[1000]: info: [1750023367250] CoreMusicLibrary::Adding element Last_100 Jun 15 23:36:07 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 15 23:36:07 volumiomusik volumio[1000]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 15 23:36:07 volumiomusik volumio[1000]: info: [1750023367253] CoreMusicLibrary::Adding element Webradio Jun 15 23:36:07 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 15 23:36:07 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 15 23:36:07 volumiomusik volumio[1000]: info: Initializing BBC Radios Jun 15 23:36:07 volumiomusik sudo[1201]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 15 23:36:07 volumiomusik sudo[1201]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 23:36:07 volumiomusik sudo[1201]: pam_unix(sudo:session): session closed for user root Jun 15 23:36:07 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 15 23:36:07 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 15 23:36:07 volumiomusik volumio[1000]: info: Creating Spotify config file Jun 15 23:36:07 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 23:36:07 volumiomusik systemd[1]: systemd-hostnamed.service: Succeeded. Jun 15 23:36:07 volumiomusik volumio[1000]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 15 23:36:07 volumiomusik volumio[1000]: info: [1750023367473] CoreMusicLibrary::Adding element YouTube2 Jun 15 23:36:07 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 15 23:36:07 volumiomusik volumio[1000]: Cannot find translation for source YouTube2 Jun 15 23:36:07 volumiomusik volumio[1000]: info: Volumio Calling Home Jun 15 23:36:07 volumiomusik volumio[1000]: info: Discovery: adding 451311c2-dd11-4c70-b35f-5d3d88d7881b Jun 15 23:36:07 volumiomusik volumio[1000]: info: Discovery: Found device VolumioMusik Jun 15 23:36:07 volumiomusik volumio[1000]: info: CoreCommandRouter::volumioGetState Jun 15 23:36:07 volumiomusik volumio[1000]: info: CorePlayQueue::getTrack 0 Jun 15 23:36:07 volumiomusik volumio[1000]: info: MPD Permissions set Jun 15 23:36:07 volumiomusik volumio[1000]: info: MPD Permissions set Jun 15 23:36:07 volumiomusik volumio[1000]: info: Volumio called home Jun 15 23:36:07 volumiomusik volumio[1000]: info: Spotify config file written Jun 15 23:36:07 volumiomusik sudo[1251]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jun 15 23:36:07 volumiomusik sudo[1251]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 23:36:07 volumiomusik volumio[1000]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Jun 15 23:36:07 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 23:36:07 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 23:36:07 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 23:36:07 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 23:36:07 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 23:36:07 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 23:36:07 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 23:36:07 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 23:36:07 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 15 23:36:07 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 23:36:07 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 23:36:07 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 23:36:07 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 23:36:07 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 23:36:07 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 23:36:07 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 23:36:07 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 23:36:07 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 23:36:07 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 15 23:36:07 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 23:36:07 volumiomusik systemd[1]: Started go-librespot Daemon. Jun 15 23:36:07 volumiomusik volumio[1000]: info: No need to fix Spotify hosts Jun 15 23:36:07 volumiomusik sudo[1251]: pam_unix(sudo:session): session closed for user root Jun 15 23:36:07 volumiomusik go-librespot[1254]: go-librespot daemon starting... Jun 15 23:36:07 volumiomusik volumio[1000]: info: Starting Shairport Sync Jun 15 23:36:07 volumiomusik volumio[1000]: info: Starting Shairport Sync Jun 15 23:36:07 volumiomusik sudo[1265]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 15 23:36:07 volumiomusik sudo[1265]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 23:36:07 volumiomusik volumio[1000]: info: Starting Shairport Sync Jun 15 23:36:07 volumiomusik sudo[1269]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 15 23:36:07 volumiomusik systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 15 23:36:07 volumiomusik systemd[1]: shairport-sync.service: Succeeded. Jun 15 23:36:07 volumiomusik sudo[1269]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 23:36:07 volumiomusik systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 15 23:36:07 volumiomusik sudo[1274]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 15 23:36:07 volumiomusik sudo[1274]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 23:36:07 volumiomusik volumio[1000]: info: CoreCommandRouter::volumioGetState Jun 15 23:36:07 volumiomusik volumio[1000]: info: CorePlayQueue::getTrack 0 Jun 15 23:36:07 volumiomusik systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 15 23:36:07 volumiomusik sudo[1265]: pam_unix(sudo:session): session closed for user root Jun 15 23:36:07 volumiomusik systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 15 23:36:07 volumiomusik systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jun 15 23:36:07 volumiomusik systemd[1]: shairport-sync.service: Succeeded. Jun 15 23:36:07 volumiomusik systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 15 23:36:07 volumiomusik systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 15 23:36:07 volumiomusik sudo[1274]: pam_unix(sudo:session): session closed for user root Jun 15 23:36:07 volumiomusik sudo[1269]: pam_unix(sudo:session): session closed for user root Jun 15 23:36:07 volumiomusik volumio[1000]: info: Shairport-Sync Started Jun 15 23:36:07 volumiomusik volumio[1000]: Error adding Membership: Error: addMembership EINVAL Jun 15 23:36:08 volumiomusik volumio[1000]: info: Shairport-Sync Started Jun 15 23:36:08 volumiomusik volumio[1000]: info: Shairport-Sync Started Jun 15 23:36:08 volumiomusik volumio[1000]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Jun 15 23:36:08 volumiomusik volumio[1000]: SPOTIFY: BQAr1iMhdd2ElCmpqeBgBY0YYqPMJWIZKP00swzGYvY7ImwVwFq6syk-bYyS4V1y8pXh-gzouTKZWp6X4i2KHy30V5GUBMKUL_2KvWwUVnMdv7ARJOwK3dXzCrmn8qE1vuLDgnBcMLAtvCCUAo6InzVNjKX6pC9Ni132dxuNIbRLAgF7u7i4as4sdnRQt1z67QwHaDsCLZv6Lr3DoBoklPeL3mZIN0iv7Q39d3OPfh02sbGtGl7368PPg3h2HFMQmuKjjN7fpb7c5_EwqQvpfz_Z7lGrPAoC3B7JKzSL_d8D8As Jun 15 23:36:08 volumiomusik volumio[1000]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Jun 15 23:36:08 volumiomusik volumio[1000]: info: New Spotify access token = BQAr1iMhdd2ElCmpqeBgBY0YYqPMJWIZKP00swzGYvY7ImwVwFq6syk-bYyS4V1y8pXh-gzouTKZWp6X4i2KHy30V5GUBMKUL_2KvWwUVnMdv7ARJOwK3dXzCrmn8qE1vuLDgnBcMLAtvCCUAo6InzVNjKX6pC9Ni132dxuNIbRLAgF7u7i4as4sdnRQt1z67QwHaDsCLZv6Lr3DoBoklPeL3mZIN0iv7Q39d3OPfh02sbGtGl7368PPg3h2HFMQmuKjjN7fpb7c5_EwqQvpfz_Z7lGrPAoC3B7JKzSL_d8D8As Jun 15 23:36:08 volumiomusik volumio[1000]: info: Spotify credentials grant success - running version from March 24, 2019 Jun 15 23:36:08 volumiomusik go-librespot[1254]: time="2025-06-15T23:36:08+02:00" level=info msg="running go-librespot 0.2.0" Jun 15 23:36:08 volumiomusik go-librespot[1254]: time="2025-06-15T23:36:08+02:00" level=debug msg="app state loaded" Jun 15 23:36:08 volumiomusik go-librespot[1254]: time="2025-06-15T23:36:08+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 15 23:36:08 volumiomusik mpd[1214]: Jun 15 23:36 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 15 23:36:08 volumiomusik volumio[1000]: SPOTIFY: User informations: {"country":"DE","display_name":"Boris","email":"boris080379@gmx.de","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/x750gbghj1elw869xlirvifh4"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/x750gbghj1elw869xlirvifh4","id":"x750gbghj1elw869xlirvifh4","images":[],"product":"premium","type":"user","uri":"spotify:user:x750gbghj1elw869xlirvifh4"} Jun 15 23:36:08 volumiomusik volumio[1000]: info: Spotify Successfully logged in Jun 15 23:36:08 volumiomusik volumio[1000]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 15 23:36:08 volumiomusik volumio[1000]: info: [1750023368205] CoreMusicLibrary::Adding element Spotify Jun 15 23:36:08 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 15 23:36:08 volumiomusik volumio[1000]: Cannot find translation for source YouTube2 Jun 15 23:36:08 volumiomusik volumio[1000]: Cannot find translation for source Spotify Jun 15 23:36:08 volumiomusik systemd[1]: Started Music Player Daemon. Jun 15 23:36:08 volumiomusik sudo[1189]: pam_unix(sudo:session): session closed for user root Jun 15 23:36:08 volumiomusik sudo[1198]: pam_unix(sudo:session): session closed for user root Jun 15 23:36:08 volumiomusik volumio[1000]: info: Completed starting Core Plugins Jun 15 23:36:08 volumiomusik volumio[1000]: info: ------------------------------------------- Jun 15 23:36:08 volumiomusik volumio[1000]: info: ----- MyVolumio plugins startup ---- Jun 15 23:36:08 volumiomusik volumio[1000]: info: ------------------------------------------- Jun 15 23:36:08 volumiomusik volumio[1000]: info: [MyVolumio PluginManager] Fetching plans data.... Jun 15 23:36:08 volumiomusik volumio[1000]: info: MPD running with PID1214 Jun 15 23:36:08 volumiomusik volumio[1000]: ,establishing connection Jun 15 23:36:08 volumiomusik volumio[1000]: error: MPD error: The expression evaluated to a falsy value: Jun 15 23:36:08 volumiomusik volumio[1000]: assert.ok(self.idling) Jun 15 23:36:08 volumiomusik volumio[1000]: error: The expression evaluated to a falsy value: Jun 15 23:36:08 volumiomusik volumio[1000]: assert.ok(self.idling) Jun 15 23:36:08 volumiomusik volumio[1000]: error: MPD error: The expression evaluated to a falsy value: Jun 15 23:36:08 volumiomusik volumio[1000]: assert.ok(self.idling) Jun 15 23:36:08 volumiomusik volumio[1000]: error: The expression evaluated to a falsy value: Jun 15 23:36:08 volumiomusik volumio[1000]: assert.ok(self.idling) Jun 15 23:36:08 volumiomusik volumio[1000]: error: updateQueue error: null Jun 15 23:36:08 volumiomusik go-librespot[1254]: time="2025-06-15T23:36:08+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Jun 15 23:36:08 volumiomusik go-librespot[1254]: time="2025-06-15T23:36:08+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Jun 15 23:36:08 volumiomusik go-librespot[1254]: time="2025-06-15T23:36:08+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Jun 15 23:36:08 volumiomusik go-librespot[1254]: time="2025-06-15T23:36:08+02:00" level=info msg="zeroconf server listening on port 44299" Jun 15 23:36:08 volumiomusik go-librespot[1254]: time="2025-06-15T23:36:08+02:00" level=debug msg="obtained new client token: AADBqzFDDMX1OU2eWqS9lMzKf1gwE6xH7AK8BnBGRgOyzkoV/kWCp6R7Dor33/HG02El1rFu9M4+UWmi+MHs5ik3WGbPGV2+uUwvMqo9xGc/TjF7qjvpsQrBPUowN2COM6E2feDwTqDsBdIUcHxKo61WU40ZeWnreLPOJcHlD2vDRUuXWnpIg94teOSiJ5RuYye8C+a66f1murSHUnLGkXUl8kz8vM66D0tMOd3q7qWr1zLvQlMbfu8+cY8=" Jun 15 23:36:08 volumiomusik go-librespot[1254]: time="2025-06-15T23:36:08+02:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:4070, retrying with a different AP" error="dial tcp 34.158.1.133:4070: connect: connection refused" Jun 15 23:36:08 volumiomusik go-librespot[1254]: time="2025-06-15T23:36:08+02:00" level=debug msg="connected to ap-gew4.spotify.com:443" Jun 15 23:36:08 volumiomusik go-librespot[1254]: time="2025-06-15T23:36:08+02:00" level=debug msg="completed keyexchange" Jun 15 23:36:08 volumiomusik go-librespot[1254]: time="2025-06-15T23:36:08+02:00" level=debug msg="completed challenge" Jun 15 23:36:08 volumiomusik go-librespot[1254]: time="2025-06-15T23:36:08+02:00" level=info msg="authenticated AP as x750gbghj1elw869xlirvifh4" Jun 15 23:36:08 volumiomusik go-librespot[1254]: time="2025-06-15T23:36:08+02:00" level=info msg="authenticated Login5 as x750gbghj1elw869xlirvifh4" Jun 15 23:36:08 volumiomusik go-librespot[1254]: time="2025-06-15T23:36:08+02:00" level=debug msg="initializing zeroconf session, username: x750gbghj1elw869xlirvifh4" Jun 15 23:36:08 volumiomusik go-librespot[1254]: time="2025-06-15T23:36:08+02:00" level=debug msg="dealer connection opened" Jun 15 23:36:08 volumiomusik go-librespot[1254]: time="2025-06-15T23:36:08+02:00" level=trace msg="starting accesspoint recv loop" Jun 15 23:36:08 volumiomusik go-librespot[1254]: time="2025-06-15T23:36:08+02:00" level=trace msg="starting dealer recv loop" Jun 15 23:36:08 volumiomusik go-librespot[1254]: time="2025-06-15T23:36:08+02:00" level=trace msg="received accesspoint ping" Jun 15 23:36:08 volumiomusik go-librespot[1254]: time="2025-06-15T23:36:08+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Jun 15 23:36:08 volumiomusik go-librespot[1254]: time="2025-06-15T23:36:08+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Jun 15 23:36:08 volumiomusik go-librespot[1254]: time="2025-06-15T23:36:08+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Jun 15 23:36:08 volumiomusik go-librespot[1254]: time="2025-06-15T23:36:08+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Jun 15 23:36:08 volumiomusik go-librespot[1254]: time="2025-06-15T23:36:08+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Jun 15 23:36:08 volumiomusik go-librespot[1254]: time="2025-06-15T23:36:08+02:00" level=debug msg="received connection id: NDkzZDIyODctYWE1MS00NGEzLWFhYzctMTYwNmU4OTBmMDljK2RlYWxlcit0Y3A6Ly8wYWNhNThlMS5pcC5nZXc0LnNwb3RpZnkubmV0OjU3MDArRjRGMEJFMEQ0QkUxMjIzMEJFRTcxMDlBNTA5RTQ3RjEyMkQ5OEYyQUNGNjY3MDYwOEU3OTI0MzRDNUQxMjFEMg==" Jun 15 23:36:08 volumiomusik go-librespot[1254]: time="2025-06-15T23:36:08+02:00" level=trace msg="received accesspoint pong ack" Jun 15 23:36:08 volumiomusik go-librespot[1254]: time="2025-06-15T23:36:08+02:00" level=debug msg="put connect state because NEW_DEVICE" Jun 15 23:36:10 volumiomusik volumio[1000]: info: go-librespot daemon successfully initialized Jun 15 23:36:11 volumiomusik sudo[1302]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 15 23:36:11 volumiomusik sudo[1302]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 23:36:11 volumiomusik sudo[1302]: pam_unix(sudo:session): session closed for user root Jun 15 23:36:11 volumiomusik sudo[1304]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 15 23:36:11 volumiomusik sudo[1304]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 23:36:11 volumiomusik sudo[1304]: pam_unix(sudo:session): session closed for user root Jun 15 23:36:11 volumiomusik sudo[1308]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jun 15 23:36:11 volumiomusik sudo[1308]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 23:36:11 volumiomusik systemd[1]: Started UPnP Renderer front-end to MPD. Jun 15 23:36:11 volumiomusik sudo[1308]: pam_unix(sudo:session): session closed for user root Jun 15 23:36:11 volumiomusik volumio[1000]: info: Upmpdcli Daemon Started Jun 15 23:36:11 volumiomusik volumio[1310]: Generating RSA private key, 4096 bit long modulus (2 primes) Jun 15 23:36:13 volumiomusik volumio[1000]: info: Initializing connection to go-librespot Websocket Jun 15 23:36:13 volumiomusik volumio-remote-updater[589]: [2025-06-15 23:36:13] [connect] Successful connection Jun 15 23:36:13 volumiomusik volumio[1000]: Cannot compose Albumart path Jun 15 23:36:13 volumiomusik volumio[1000]: Cannot compose Albumart path Jun 15 23:36:13 volumiomusik volumio[1000]: Cannot compose Albumart path Jun 15 23:36:13 volumiomusik volumio[1000]: Cannot compose Albumart path Jun 15 23:36:13 volumiomusik volumio[1000]: Cannot compose Albumart path Jun 15 23:36:13 volumiomusik volumio[1000]: Cannot compose Albumart path Jun 15 23:36:13 volumiomusik volumio[1000]: Cannot compose Albumart path Jun 15 23:36:13 volumiomusik volumio[1000]: Cannot compose Albumart path Jun 15 23:36:13 volumiomusik volumio[1000]: Cannot compose Albumart path Jun 15 23:36:13 volumiomusik volumio[1000]: Cannot compose Albumart path Jun 15 23:36:13 volumiomusik volumio[1000]: Cannot compose Albumart path Jun 15 23:36:13 volumiomusik volumio[1000]: Cannot compose Albumart path Jun 15 23:36:13 volumiomusik volumio[1000]: Cannot compose Albumart path Jun 15 23:36:13 volumiomusik volumio[1000]: Cannot compose Albumart path Jun 15 23:36:13 volumiomusik volumio[1000]: Cannot compose Albumart path Jun 15 23:36:14 volumiomusik go-librespot[1254]: time="2025-06-15T23:36:14+02:00" level=debug msg="new websocket client" Jun 15 23:36:14 volumiomusik volumio[1000]: info: Connection to go-librespot Websocket established Jun 15 23:36:14 volumiomusik volumio-remote-updater[589]: [2025-06-15 23:36:14] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1750023373 101 Jun 15 23:36:14 volumiomusik volumio[1000]: 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 Jun 15 23:36:16 volumiomusik volumio[1000]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jun 15 23:36:16 volumiomusik volumio[1000]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jun 15 23:36:16 volumiomusik volumio[1000]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jun 15 23:36:16 volumiomusik volumio[1000]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jun 15 23:36:16 volumiomusik volumio[1000]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jun 15 23:36:16 volumiomusik volumio[1000]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jun 15 23:36:16 volumiomusik volumio[1000]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jun 15 23:36:16 volumiomusik volumio[1000]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jun 15 23:36:16 volumiomusik volumio[1000]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jun 15 23:36:16 volumiomusik volumio[1000]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jun 15 23:36:16 volumiomusik volumio[1000]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jun 15 23:36:16 volumiomusik volumio[1000]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jun 15 23:36:16 volumiomusik volumio[1000]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jun 15 23:36:16 volumiomusik volumio[1000]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jun 15 23:36:16 volumiomusik volumio[1000]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jun 15 23:36:16 volumiomusik volumio[1000]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jun 15 23:36:16 volumiomusik volumio[1000]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jun 15 23:36:16 volumiomusik volumio[1000]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jun 15 23:36:16 volumiomusik volumio[1000]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jun 15 23:36:16 volumiomusik volumio[1000]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jun 15 23:36:16 volumiomusik volumio[1000]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jun 15 23:36:16 volumiomusik volumio[1000]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jun 15 23:36:16 volumiomusik volumio[1000]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jun 15 23:36:16 volumiomusik volumio[1000]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jun 15 23:36:16 volumiomusik volumio[1000]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jun 15 23:36:16 volumiomusik volumio[1000]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jun 15 23:36:16 volumiomusik volumio[1000]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jun 15 23:36:16 volumiomusik volumio[1000]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jun 15 23:36:16 volumiomusik volumio[1000]: info: Adding plugin bluetooth to MyMusic Plugins Jun 15 23:36:16 volumiomusik volumio[1000]: info: Adding plugin multiroom to MyMusic Plugins Jun 15 23:36:16 volumiomusik volumio[1000]: info: Adding plugin metavolumio to MyMusic Plugins Jun 15 23:36:16 volumiomusik volumio[1000]: info: Adding plugin cd_controller to MyMusic Plugins Jun 15 23:36:16 volumiomusik volumio[1000]: info: Adding plugin qobuzconnect to MyMusic Plugins Jun 15 23:36:16 volumiomusik volumio[1000]: info: Adding plugin smart_inputs to MyMusic Plugins Jun 15 23:36:16 volumiomusik volumio[1000]: info: Adding plugin tidalconnect to MyMusic Plugins Jun 15 23:36:16 volumiomusik volumio[1000]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jun 15 23:36:17 volumiomusik volumio[1000]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jun 15 23:36:17 volumiomusik volumio[1000]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jun 15 23:36:17 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 15 23:36:17 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 15 23:36:17 volumiomusik volumio[1000]: info: Starting MyVolumio Remote Streaming Endpoints Jun 15 23:36:17 volumiomusik volumio[1000]: info: MyVolumio login type: Token Jun 15 23:36:17 volumiomusik volumio[1000]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jun 15 23:36:17 volumiomusik volumio[1000]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jun 15 23:36:18 volumiomusik volumio[1000]: info: Starting Streaming Service Transparent Proxy Jun 15 23:36:18 volumiomusik volumio[1000]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jun 15 23:36:18 volumiomusik volumio[1000]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jun 15 23:36:18 volumiomusik volumio[1000]: info: Streaming services startup Jun 15 23:36:18 volumiomusik volumio[1000]: info: Starting Streaming Daemon Jun 15 23:36:18 volumiomusik sudo[1333]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 15 23:36:18 volumiomusik sudo[1333]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 23:36:18 volumiomusik volumio[1000]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jun 15 23:36:18 volumiomusik sudo[1333]: pam_unix(sudo:session): session closed for user root Jun 15 23:36:18 volumiomusik volumio[1000]: info: Getting Spotify volume Jun 15 23:36:18 volumiomusik volumio[1000]: error: Cannot start Volumio Streaming Daemon Jun 15 23:36:18 volumiomusik volumio[1000]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 15 23:36:18 volumiomusik volumio[1000]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 15 23:36:18 volumiomusik volumio[1000]: info: Spotify volume: 100 Jun 15 23:36:18 volumiomusik volumio[1000]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Jun 15 23:36:18 volumiomusik volumio[1000]: info: CoreCommandRouter::volumioGetState Jun 15 23:36:18 volumiomusik volumio[1000]: info: CorePlayQueue::getTrack 0 Jun 15 23:36:18 volumiomusik volumio[1000]: SPOTIFY: RECEIVED VOLUMIO VOLUME 63 Jun 15 23:36:18 volumiomusik volumio[1000]: SPOTIFY: SPOTIFY VOLUME 100 Jun 15 23:36:18 volumiomusik volumio[1000]: SPOTIFY: VOLUMIO VOLUME 63 Jun 15 23:36:18 volumiomusik volumio[1000]: SPOTIFY: DELTA VOLUME ENOUGH: true Jun 15 23:36:18 volumiomusik volumio[1000]: info: Setting Spotify Volume from Volumio: 63 Jun 15 23:36:18 volumiomusik volumio[1000]: error: MyVolumio Custom Token format not valid, refreshing it Jun 15 23:36:19 volumiomusik volumio[1000]: STREAMING PROXY: Starting server on port 3245 Jun 15 23:36:19 volumiomusik volumio[1000]: Node JS runtime: 14 Jun 15 23:36:19 volumiomusik volumio[1310]: ......................................................................................++++ Jun 15 23:36:20 volumiomusik volumio[1000]: SPOTIFY: SETTING SPOTIFY VOLUME 63 Jun 15 23:36:20 volumiomusik volumio[1000]: info: Sending Spotify command with payload to local API: /player/volume Jun 15 23:36:20 volumiomusik go-librespot[1254]: time="2025-06-15T23:36:20+02:00" level=debug msg="update volume to 41287/65535" Jun 15 23:36:20 volumiomusik volumio[1000]: info: MyVolumio login type: Token Jun 15 23:36:20 volumiomusik volumio[1000]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jun 15 23:36:21 volumiomusik go-librespot[1254]: time="2025-06-15T23:36:21+02:00" level=debug msg="put connect state because VOLUME_CHANGED" Jun 15 23:36:21 volumiomusik go-librespot[1254]: time="2025-06-15T23:36:21+02:00" level=trace msg="emitting websocket event: volume" Jun 15 23:36:21 volumiomusik volumio[1000]: SPOTIFY: received: {"type":"volume","data":{"value":63,"max":100}} Jun 15 23:36:21 volumiomusik volumio[1000]: SPOTIFY: RECEIVED SPOTIFY VOLUME 63 Jun 15 23:36:21 volumiomusik volumio[1000]: info: MyVolumio token set successfully Jun 15 23:36:21 volumiomusik volumio[1000]: info: MYVOLUMIO: Adding device Jun 15 23:36:21 volumiomusik volumio[1000]: info: MYVOLUMIO: Evaluating Server Jun 15 23:36:21 volumiomusik volumio[1000]: info: MyVolumio status changed Jun 15 23:36:21 volumiomusik volumio[1000]: info: Streaming services startup Jun 15 23:36:21 volumiomusik volumio[1000]: info: Starting Streaming Daemon Jun 15 23:36:21 volumiomusik volumio[1000]: info: Removing browser output: myVolumio user plan is not superstar Jun 15 23:36:21 volumiomusik volumio[1000]: info: Removing audio output: Jun 15 23:36:21 volumiomusik volumio[1000]: info: Stoppping Tunnel 1 Jun 15 23:36:21 volumiomusik sudo[1375]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 15 23:36:21 volumiomusik sudo[1375]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 23:36:21 volumiomusik sudo[1378]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jun 15 23:36:21 volumiomusik sudo[1378]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 23:36:21 volumiomusik sudo[1375]: pam_unix(sudo:session): session closed for user root Jun 15 23:36:21 volumiomusik volumio[1000]: error: Cannot start Volumio Streaming Daemon Jun 15 23:36:21 volumiomusik volumio[1000]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 15 23:36:21 volumiomusik volumio[1000]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 15 23:36:21 volumiomusik sudo[1378]: pam_unix(sudo:session): session closed for user root Jun 15 23:36:21 volumiomusik volumio[1000]: info: Remote SSH Stopped Jun 15 23:36:22 volumiomusik volumio[1000]: info: Setting Geolocation for MyVolumio to eu2 Jun 15 23:36:22 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 15 23:36:23 volumiomusik volumio[1000]: info: Updating MyVolumio device info Jun 15 23:36:23 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 15 23:36:23 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 15 23:36:23 volumiomusik volumio[1000]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jun 15 23:36:23 volumiomusik volumio[1000]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jun 15 23:36:24 volumiomusik volumio[1310]: ......................................................++++ Jun 15 23:36:24 volumiomusik volumio[1310]: e is 65537 (0x010001) Jun 15 23:36:24 volumiomusik volumio[1310]: writing RSA key Jun 15 23:36:27 volumiomusik volumio[1000]: info: MYVOLUMIO: Adding device Jun 15 23:36:27 volumiomusik volumio[1000]: info: MYVOLUMIO: Evaluating Server Jun 15 23:36:27 volumiomusik volumio[1000]: info: Setting Geolocation for MyVolumio to eu6 Jun 15 23:36:27 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 15 23:36:27 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 15 23:36:27 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 15 23:36:28 volumiomusik volumio[1000]: info: Updating MyVolumio device info Jun 15 23:36:28 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 15 23:36:29 volumiomusik volumio[1000]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jun 15 23:36:29 volumiomusik volumio[1000]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jun 15 23:36:32 volumiomusik systemd[1]: systemd-timedated.service: Succeeded. Jun 15 23:36:32 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 15 23:36:32 volumiomusik volumio[1000]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Jun 15 23:36:32 volumiomusik volumio[1000]: info: CoreCommandRouter::volumioGetState Jun 15 23:36:32 volumiomusik volumio[1000]: info: CorePlayQueue::getTrack 0 Jun 15 23:36:37 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 15 23:36:37 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 15 23:36:37 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 15 23:36:37 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 23:36:37 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 23:36:37 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jun 15 23:36:37 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jun 15 23:36:37 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jun 15 23:36:37 volumiomusik volumio[1000]: info: CoreCommandRouter::volumioGetBrowseSources Jun 15 23:36:37 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 15 23:36:37 volumiomusik volumio-remote-updater[589]: No test mode Jun 15 23:36:37 volumiomusik volumio-remote-updater[589]: No alpha test mode Jun 15 23:36:37 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jun 15 23:36:37 volumiomusik volumio[1000]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jun 15 23:36:37 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jun 15 23:36:38 volumiomusik volumio[1000]: error: MyVolumio Plugin failed to authenticate in a timely fashion Jun 15 23:36:38 volumiomusik volumio[1000]: info: Completed starting MyVolumio Plugin Jun 15 23:36:38 volumiomusik volumio[1000]: [Metrics] CommandRouter: 39s 348.16ms Jun 15 23:36:38 volumiomusik volumio[1000]: info: CoreCommandRouter::volumiosetStartupVolume Jun 15 23:36:38 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 23:36:38 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 15 23:36:38 volumiomusik volumio[1000]: info: CoreCommandRouter::Close All Modals sent Jun 15 23:36:38 volumiomusik volumio[1000]: info: CoreCommandRouter::Close All Modals sent Jun 15 23:36:38 volumiomusik go-librespot[1254]: time="2025-06-15T23:36:38+02:00" level=trace msg="sent dealer ping" Jun 15 23:36:38 volumiomusik go-librespot[1254]: time="2025-06-15T23:36:38+02:00" level=trace msg="received dealer pong" Jun 15 23:36:39 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jun 15 23:36:39 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 15 23:36:39 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jun 15 23:36:41 volumiomusik ntpd[770]: Soliciting pool server 141.144.241.16 Jun 15 23:36:42 volumiomusik ntpd[770]: Soliciting pool server 178.215.228.24 Jun 15 23:36:43 volumiomusik ntpd[770]: Soliciting pool server 78.46.87.46 Jun 15 23:36:45 volumiomusik volumio[1000]: info: BOOT COMPLETED Jun 15 23:36:52 volumiomusik ntpd[770]: Soliciting pool server 148.251.5.46 Jun 15 23:36:52 volumiomusik ntpd[770]: Soliciting pool server 159.69.249.184 Jun 15 23:36:52 volumiomusik ntpd[770]: Soliciting pool server 195.201.173.232 Jun 15 23:36:53 volumiomusik ntpd[770]: Soliciting pool server 185.194.239.35 Jun 15 23:36:53 volumiomusik ntpd[770]: Soliciting pool server 176.9.157.155 Jun 15 23:36:54 volumiomusik ntpd[770]: Soliciting pool server 176.9.8.206 Jun 15 23:36:54 volumiomusik ntpd[770]: Soliciting pool server 77.90.40.94 Jun 15 23:36:56 volumiomusik ntpd[770]: receive: Unexpected origin timestamp 0xebf9bb76.e4f880d0 does not match aorg 0000000000.00000000 from server@195.201.173.232 xmt 0xebf9bb78.0e7a7f7c Jun 15 23:36:57 volumiomusik ntpd[770]: Soliciting pool server 89.168.112.172 Jun 15 23:36:57 volumiomusik ntpd[770]: Soliciting pool server 109.123.244.54 Jun 15 23:36:58 volumiomusik ntpd[770]: Soliciting pool server 2a0a:51c0::47 Jun 15 23:37:06 volumiomusik wpa_supplicant[866]: RRM: Ignoring radio measurement request: Not RRM network Jun 15 23:37:08 volumiomusik wpa_supplicant[866]: RRM: Ignoring radio measurement request: Not RRM network Jun 15 23:37:10 volumiomusik go-librespot[1254]: time="2025-06-15T23:37:10+02:00" level=trace msg="sent dealer ping" Jun 15 23:37:10 volumiomusik go-librespot[1254]: time="2025-06-15T23:37:10+02:00" level=trace msg="received dealer pong" Jun 15 23:37:14 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 15 23:37:14 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 15 23:37:14 volumiomusik volumio[1000]: info: Discovery: Getting this device information Jun 15 23:37:14 volumiomusik volumio[1000]: info: CoreCommandRouter::volumioGetState Jun 15 23:37:14 volumiomusik volumio[1000]: info: CorePlayQueue::getTrack 0 Jun 15 23:37:14 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 15 23:37:14 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 15 23:37:14 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 15 23:37:14 volumiomusik volumio[1000]: info: Discovery: Getting this device information Jun 15 23:37:14 volumiomusik volumio[1000]: info: CoreCommandRouter::volumioGetState Jun 15 23:37:14 volumiomusik volumio[1000]: info: CorePlayQueue::getTrack 0 Jun 15 23:37:14 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 15 23:37:14 volumiomusik volumio[1000]: verbose: New Socket.io Connection to 192.168.178.51:3000 from 192.168.178.48 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Jun 15 23:37:14 volumiomusik volumio[1000]: info: CoreCommandRouter::volumioGetState Jun 15 23:37:14 volumiomusik volumio[1000]: info: CorePlayQueue::getTrack 0 Jun 15 23:37:14 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 15 23:37:14 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 15 23:37:17 volumiomusik volumio[1000]: info: CoreCommandRouter::volumioGetState Jun 15 23:37:17 volumiomusik volumio[1000]: info: CorePlayQueue::getTrack 0 Jun 15 23:37:18 volumiomusik sudo[1534]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 15 23:37:18 volumiomusik sudo[1534]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 23:37:18 volumiomusik sudo[1534]: pam_unix(sudo:session): session closed for user root Jun 15 23:37:18 volumiomusik sudo[1536]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 15 23:37:18 volumiomusik sudo[1536]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 23:37:18 volumiomusik sudo[1536]: pam_unix(sudo:session): session closed for user root Jun 15 23:37:18 volumiomusik volumio[1000]: verbose: New Socket.io Connection to 192.168.178.51 from 192.168.178.48 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 6 Jun 15 23:37:18 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 15 23:37:18 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 15 23:37:18 volumiomusik sudo[1540]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 15 23:37:18 volumiomusik sudo[1540]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 23:37:18 volumiomusik sudo[1540]: pam_unix(sudo:session): session closed for user root Jun 15 23:37:18 volumiomusik sudo[1542]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 15 23:37:18 volumiomusik sudo[1542]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 23:37:18 volumiomusik sudo[1542]: pam_unix(sudo:session): session closed for user root Jun 15 23:37:19 volumiomusik volumio[1000]: verbose: New Socket.io Connection to 192.168.178.51 from 192.168.178.48 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 7 Jun 15 23:37:19 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 15 23:37:19 volumiomusik volumio[1000]: info: CoreCommandRouter::volumioGetVisibleSources Jun 15 23:37:19 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 15 23:37:19 volumiomusik volumio[1000]: info: CoreCommandRouter::volumioGetState Jun 15 23:37:19 volumiomusik volumio[1000]: info: CorePlayQueue::getTrack 0 Jun 15 23:37:19 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 15 23:37:19 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 15 23:37:19 volumiomusik volumio[1000]: info: Received Get System Info Jun 15 23:37:19 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 15 23:37:19 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 15 23:37:19 volumiomusik volumio[1000]: info: Discovery: Getting this device information Jun 15 23:37:19 volumiomusik volumio[1000]: info: CoreCommandRouter::volumioGetState Jun 15 23:37:19 volumiomusik volumio[1000]: info: CorePlayQueue::getTrack 0 Jun 15 23:37:19 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 15 23:37:19 volumiomusik volumio[1000]: info: CoreCommandRouter::volumioGetState Jun 15 23:37:19 volumiomusik volumio[1000]: info: CorePlayQueue::getTrack 0 Jun 15 23:37:19 volumiomusik volumio[1000]: info: Listing playlists Jun 15 23:37:19 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 15 23:37:19 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 15 23:37:19 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 15 23:37:20 volumiomusik volumio[1000]: info: CoreCommandRouter::volumioGetState Jun 15 23:37:20 volumiomusik volumio[1000]: info: CorePlayQueue::getTrack 0 Jun 15 23:37:20 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jun 15 23:37:20 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 15 23:37:20 volumiomusik volumio[1000]: info: Received Get System Info Jun 15 23:37:20 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 15 23:37:20 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 15 23:37:20 volumiomusik volumio[1000]: info: Discovery: Getting this device information Jun 15 23:37:20 volumiomusik volumio[1000]: info: CoreCommandRouter::volumioGetState Jun 15 23:37:20 volumiomusik volumio[1000]: info: CorePlayQueue::getTrack 0 Jun 15 23:37:20 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 15 23:37:22 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 15 23:37:22 volumiomusik volumio[1000]: info: Received Get System Info Jun 15 23:37:22 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 15 23:37:22 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 15 23:37:22 volumiomusik volumio[1000]: info: Discovery: Getting this device information Jun 15 23:37:22 volumiomusik volumio[1000]: info: CoreCommandRouter::volumioGetState Jun 15 23:37:22 volumiomusik volumio[1000]: info: CorePlayQueue::getTrack 0 Jun 15 23:37:22 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 15 23:37:28 volumiomusik volumio[1000]: info: CoreCommandRouter::volumioGetQueue Jun 15 23:37:28 volumiomusik volumio[1000]: info: CoreStateMachine::getQueue Jun 15 23:37:28 volumiomusik volumio[1000]: info: CorePlayQueue::getQueue Jun 15 23:37:29 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 15 23:37:32 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 15 23:37:32 volumiomusik volumio[1000]: info: Preload queue cleared Jun 15 23:37:35 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 15 23:37:36 volumiomusik volumio[1000]: info: Preload queue cleared Jun 15 23:37:37 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 15 23:37:37 volumiomusik volumio[1000]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jun 15 23:37:37 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jun 15 23:37:37 volumiomusik volumio[1000]: info: Received Get System Version Jun 15 23:37:37 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 15 23:37:37 volumiomusik volumio[1000]: info: Received Get System Info Jun 15 23:37:37 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 15 23:37:37 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 15 23:37:37 volumiomusik volumio[1000]: info: Discovery: Getting this device information Jun 15 23:37:37 volumiomusik volumio[1000]: info: CoreCommandRouter::volumioGetState Jun 15 23:37:37 volumiomusik volumio[1000]: info: CorePlayQueue::getTrack 0 Jun 15 23:37:37 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 15 23:37:38 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 15 23:37:38 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 15 23:37:38 volumiomusik volumio[1000]: info: Discovery: Getting this device information Jun 15 23:37:38 volumiomusik volumio[1000]: info: CoreCommandRouter::volumioGetState Jun 15 23:37:38 volumiomusik volumio[1000]: info: CorePlayQueue::getTrack 0 Jun 15 23:37:38 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 15 23:37:40 volumiomusik go-librespot[1254]: time="2025-06-15T23:37:40+02:00" level=trace msg="sent dealer ping" Jun 15 23:37:40 volumiomusik go-librespot[1254]: time="2025-06-15T23:37:40+02:00" level=trace msg="received dealer pong" Jun 15 23:37:40 volumiomusik volumio[1000]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 15 23:37:40 volumiomusik volumio[1000]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 15 23:37:40 volumiomusik volumio[1000]: TypeError: Cannot read property 'length' of undefined Jun 15 23:37:40 volumiomusik volumio[1000]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Jun 15 23:37:40 volumiomusik volumio[1000]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Jun 15 23:37:40 volumiomusik volumio[1000]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Jun 15 23:37:40 volumiomusik volumio[1000]: at Parser.emit (events.js:315:20) Jun 15 23:37:40 volumiomusik volumio[1000]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Jun 15 23:37:40 volumiomusik volumio[1000]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Jun 15 23:37:40 volumiomusik volumio[1000]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Jun 15 23:37:40 volumiomusik volumio[1000]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Jun 15 23:37:40 volumiomusik volumio[1000]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Jun 15 23:37:40 volumiomusik volumio[1000]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Jun 15 23:37:40 volumiomusik volumio[1000]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Jun 15 23:37:40 volumiomusik volumio[1000]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Jun 15 23:37:40 volumiomusik volumio[1000]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Jun 15 23:37:40 volumiomusik volumio[1000]: at IncomingMessage.emit (events.js:327:22) Jun 15 23:37:40 volumiomusik volumio[1000]: at endReadableNT (internal/streams/readable.js:1327:12) Jun 15 23:37:40 volumiomusik volumio[1000]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Jun 15 23:37:40 volumiomusik volumio[1000]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 15 23:37:40 volumiomusik sudo[1626]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-15 23:36 Jun 15 23:37:40 volumiomusik sudo[1626]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 04:52:53 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="a72866a0de4045751d03a035de6290e1"