-- Logs begin at Mon 2024-09-02 21:25:12 CEST, end at Mon 2024-09-02 21:27:14 CEST. --
Sep 02 21:26:10 keller-volumio ntpd[761]: receive: Unexpected origin timestamp 0xea808fb5.953bf013 does not match aorg 0000000000.00000000 from server@116.203.244.102 xmt 0xea808fd2.cd9d392e
Sep 02 21:26:10 keller-volumio ntpd[761]: receive: Unexpected origin timestamp 0xea808fb5.954c7e15 does not match aorg 0000000000.00000000 from server@185.232.69.65 xmt 0xea808fd2.cdbe9d02
Sep 02 21:26:10 keller-volumio ntpd[761]: receive: Unexpected origin timestamp 0xea808fb5.955b5f68 does not match aorg 0000000000.00000000 from server@194.59.207.235 xmt 0xea808fd2.cddae437
Sep 02 21:26:10 keller-volumio ntpd[761]: receive: Unexpected origin timestamp 0xea808fb5.95532765 does not match aorg 0000000000.00000000 from server@49.12.35.6 xmt 0xea808fd2.cdb8249c
Sep 02 21:26:10 keller-volumio ntpd[761]: receive: Unexpected origin timestamp 0xea808fb5.955feebf does not match aorg 0000000000.00000000 from server@144.76.43.40 xmt 0xea808fd2.cdf147ff
Sep 02 21:26:10 keller-volumio ntpd[761]: receive: Unexpected origin timestamp 0xea808fb5.95579717 does not match aorg 0000000000.00000000 from server@194.164.164.175 xmt 0xea808fd2.d23a4652
Sep 02 21:26:10 keller-volumio volumio[799]: info: -------------------------------------------
Sep 02 21:26:10 keller-volumio volumio[799]: info: ----- Volumio3 ----
Sep 02 21:26:10 keller-volumio volumio[799]: info: -------------------------------------------
Sep 02 21:26:10 keller-volumio volumio[799]: info: ----- System startup ----
Sep 02 21:26:10 keller-volumio volumio[799]: info: -------------------------------------------
Sep 02 21:26:13 keller-volumio volumio[799]: info: MYVOLUMIO Environment detected
Sep 02 21:26:13 keller-volumio volumio[799]: info: Plugin folders cleanup
Sep 02 21:26:13 keller-volumio volumio[799]: info: Scanning into folder /volumio/app/plugins/
Sep 02 21:26:13 keller-volumio volumio[799]: info: Scanning category audio_interface
Sep 02 21:26:13 keller-volumio volumio[799]: info: Scanning category miscellanea
Sep 02 21:26:13 keller-volumio volumio[799]: info: Scanning category music_service
Sep 02 21:26:13 keller-volumio volumio[799]: info: Scanning category plugins.json
Sep 02 21:26:13 keller-volumio volumio[799]: info: Scanning category system_controller
Sep 02 21:26:13 keller-volumio volumio[799]: info: Scanning category user_interface
Sep 02 21:26:13 keller-volumio volumio[799]: info: Scanning into folder /data/plugins/
Sep 02 21:26:13 keller-volumio volumio[799]: info: Scanning category music_service
Sep 02 21:26:13 keller-volumio volumio[799]: info: Plugin folders cleanup completed
Sep 02 21:26:13 keller-volumio volumio[799]: info: -------------------------------------------
Sep 02 21:26:13 keller-volumio volumio[799]: info: ----- Core plugins startup ----
Sep 02 21:26:13 keller-volumio volumio[799]: info: -------------------------------------------
Sep 02 21:26:13 keller-volumio volumio[799]: info: Loading plugins from folder /volumio/app/plugins/
Sep 02 21:26:13 keller-volumio volumio[799]: info: Adding plugin upnp to MyMusic Plugins
Sep 02 21:26:14 keller-volumio volumio[799]: info: Adding plugin airplay_emulation to MyMusic Plugins
Sep 02 21:26:14 keller-volumio volumio[799]: info: Adding plugin upnp_browser to MyMusic Plugins
Sep 02 21:26:14 keller-volumio volumio[799]: info: Loading plugins from folder /data/plugins/
Sep 02 21:26:14 keller-volumio volumio[799]: info: Loading plugin "system"...
Sep 02 21:26:14 keller-volumio volumio[799]: info: Loading plugin "appearance"...
Sep 02 21:26:17 keller-volumio volumio[799]: info: Loading plugin "network"...
Sep 02 21:26:17 keller-volumio volumio[799]: info: Refreshing Cached IP Addresses
Sep 02 21:26:17 keller-volumio volumio[799]: info: Loading plugin "services"...
Sep 02 21:26:17 keller-volumio volumio[799]: info: Loading plugin "alsa_controller"...
Sep 02 21:26:17 keller-volumio sudo[849]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Sep 02 21:26:17 keller-volumio sudo[847]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Sep 02 21:26:17 keller-volumio sudo[845]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Sep 02 21:26:17 keller-volumio sudo[845]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 02 21:26:17 keller-volumio sudo[849]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 02 21:26:17 keller-volumio sudo[847]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 02 21:26:17 keller-volumio sudo[845]: pam_unix(sudo:session): session closed for user root
Sep 02 21:26:18 keller-volumio sudo[849]: pam_unix(sudo:session): session closed for user root
Sep 02 21:26:18 keller-volumio sudo[847]: pam_unix(sudo:session): session closed for user root
Sep 02 21:26:18 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Sep 02 21:26:18 keller-volumio volumio[799]: info: Loading plugin "wizard"...
Sep 02 21:26:18 keller-volumio volumio[799]: info: Loading plugin "networkfs"...
Sep 02 21:26:18 keller-volumio volumio[799]: info: Starting Udev Watcher for removable devices
Sep 02 21:26:18 keller-volumio volumio[799]: info: Ignoring mount for partition: boot
Sep 02 21:26:18 keller-volumio volumio[799]: info: Ignoring mount for partition: volumio
Sep 02 21:26:18 keller-volumio volumio[799]: info: Ignoring mount for partition: volumio_data
Sep 02 21:26:18 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Sep 02 21:26:18 keller-volumio volumio[799]: info: Loading plugin "volumio_command_line_client"...
Sep 02 21:26:18 keller-volumio volumio[799]: info: Plugin upnp is not enabled
Sep 02 21:26:18 keller-volumio volumio[799]: info: Loading plugin "my_music"...
Sep 02 21:26:18 keller-volumio volumio[799]: info: Loading plugin "mpd"...
Sep 02 21:26:19 keller-volumio systemd[1]: systemd-fsckd.service: Succeeded.
Sep 02 21:26:19 keller-volumio volumio[799]: info: Plugin upnp_browser is not enabled
Sep 02 21:26:19 keller-volumio volumio[799]: info: Loading plugin "alarm-clock"...
Sep 02 21:26:19 keller-volumio volumio[799]: info: Plugin airplay_emulation is not enabled
Sep 02 21:26:19 keller-volumio volumio[799]: info: Loading plugin "last_100"...
Sep 02 21:26:19 keller-volumio volumio[799]: info: Loading plugin "webradio"...
Sep 02 21:26:20 keller-volumio volumio[799]: info: Loading plugin "i2s_dacs"...
Sep 02 21:26:20 keller-volumio volumio[799]: info: Loading plugin "volumiodiscovery"...
Sep 02 21:26:20 keller-volumio volumio[799]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Sep 02 21:26:20 keller-volumio volumio[799]: *** WARNING *** Please fix your application to use the native API of Avahi!
Sep 02 21:26:20 keller-volumio node[799]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Sep 02 21:26:20 keller-volumio volumio[799]: *** WARNING *** For more information see
Sep 02 21:26:20 keller-volumio volumio[799]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Sep 02 21:26:20 keller-volumio volumio[799]: *** WARNING *** Please fix your application to use the native API of Avahi!
Sep 02 21:26:20 keller-volumio volumio[799]: *** WARNING *** For more information see
Sep 02 21:26:20 keller-volumio node[799]: *** WARNING *** Please fix your application to use the native API of Avahi!
Sep 02 21:26:20 keller-volumio node[799]: *** WARNING *** For more information see
Sep 02 21:26:20 keller-volumio node[799]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Sep 02 21:26:20 keller-volumio node[799]: *** WARNING *** Please fix your application to use the native API of Avahi!
Sep 02 21:26:20 keller-volumio node[799]: *** WARNING *** For more information see
Sep 02 21:26:20 keller-volumio volumio[799]: info: Applying required configuration parameters for plugin volumiodiscovery
Sep 02 21:26:20 keller-volumio volumio[799]: info: Discovery: Started advertising with name: Keller-Volumio
Sep 02 21:26:20 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Sep 02 21:26:20 keller-volumio volumio[799]: info: Loading plugin "spop"...
Sep 02 21:26:24 keller-volumio volumio-remote-updater[476]: [2024-09-02 21:26:24] [connect] Successful connection
Sep 02 21:26:25 keller-volumio volumio[799]: info: Loading plugin "ytmusic"...
Sep 02 21:26:29 keller-volumio volumio[799]: info: Loading plugin "outputs"...
Sep 02 21:26:29 keller-volumio volumio[799]: info: Loading plugin "albumart"...
Sep 02 21:26:29 keller-volumio volumio[799]: info: Plugin example_plugin is not enabled
Sep 02 21:26:29 keller-volumio volumio[799]: info: Loading plugin "inputs"...
Sep 02 21:26:29 keller-volumio volumio[799]: info: Loading plugin "updater_comm"...
Sep 02 21:26:30 keller-volumio volumio[799]: info: Plugin mpdemulation is not enabled
Sep 02 21:26:30 keller-volumio volumio[799]: info: Loading plugin "rest_api"...
Sep 02 21:26:30 keller-volumio volumio[799]: info: Loading plugin "websocket"...
Sep 02 21:26:30 keller-volumio volumio[799]: info: Starting Socket.io Server version 2.3.0
Sep 02 21:26:30 keller-volumio volumio[799]: info: Loading i18n strings for locale de
Sep 02 21:26:30 keller-volumio volumio[799]: Updating browse sources language
Sep 02 21:26:30 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Sep 02 21:26:30 keller-volumio volumio[799]: Forking 3 albumart workers
Sep 02 21:26:31 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Sep 02 21:26:31 keller-volumio volumio[799]: info: CoreCommandRouter::initPlayerControls
Sep 02 21:26:31 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 02 21:26:31 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 02 21:26:31 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 02 21:26:31 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 02 21:26:31 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 02 21:26:31 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 02 21:26:31 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 02 21:26:31 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 02 21:26:31 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Sep 02 21:26:31 keller-volumio volumio[799]: Express server listening on port 3000
Sep 02 21:26:31 keller-volumio volumio[799]: [Metrics] WebUI: 23s 366.35ms
Sep 02 21:26:31 keller-volumio volumio[799]: info: CoreStateMachine::resetVolumioState
Sep 02 21:26:31 keller-volumio volumio[799]: info: CoreStateMachine::getcurrentVolume
Sep 02 21:26:31 keller-volumio volumio[799]: info: CoreCommandRouter::volumioRetrievevolume
Sep 02 21:26:31 keller-volumio volumio[799]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan
Sep 02 21:26:31 keller-volumio volumio[799]: wlan0 Interface doesn't support scanning.
Sep 02 21:26:31 keller-volumio volumio[799]: info: Cannot use regular scanning, forcing with ap-force
Sep 02 21:26:31 keller-volumio sudo[896]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force
Sep 02 21:26:31 keller-volumio sudo[896]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 02 21:26:31 keller-volumio sudo[896]: pam_unix(sudo:session): session closed for user root
Sep 02 21:26:31 keller-volumio volumio[799]: command failed: No such device (-19)
Sep 02 21:26:31 keller-volumio volumio[799]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force
Sep 02 21:26:31 keller-volumio volumio[799]: command failed: No such device (-19)
Sep 02 21:26:32 keller-volumio volumio[799]: verbose: New Socket.io Connection to 192.168.2.56 from 192.168.2.45 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:129.0) Gecko/20100101 Firefox/129.0 Engine version: 3 Transport: polling Total Clients: 1
Sep 02 21:26:32 keller-volumio volumio[799]: verbose: New Socket.io Connection to 192.168.2.56 from 192.168.2.45 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:129.0) Gecko/20100101 Firefox/129.0 Engine version: 3 Transport: polling Total Clients: 2
Sep 02 21:26:32 keller-volumio volumio[799]: verbose: New Socket.io Connection to 192.168.2.56 from 192.168.2.45 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:129.0) Gecko/20100101 Firefox/129.0 Engine version: 3 Transport: polling Total Clients: 3
Sep 02 21:26:32 keller-volumio volumio[799]: verbose: New Socket.io Connection to 192.168.2.56:3000 from 192.168.2.30 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4
Sep 02 21:26:32 keller-volumio volumio[799]: verbose: New Socket.io Connection to 192.168.2.56:3000 from 192.168.2.98 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5
Sep 02 21:26:32 keller-volumio volumio[799]: verbose: New Socket.io Connection to 192.168.2.56:3000 from 192.168.2.54 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6
Sep 02 21:26:32 keller-volumio volumio[799]: verbose: New Socket.io Connection to 192.168.2.56 from 192.168.2.45 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:129.0) Gecko/20100101 Firefox/129.0 Engine version: 3 Transport: polling Total Clients: 7
Sep 02 21:26:32 keller-volumio volumio[799]: verbose: New Socket.io Connection to 192.168.2.56:3000 from 192.168.2.54 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8
Sep 02 21:26:32 keller-volumio volumio[799]: verbose: New Socket.io Connection to 192.168.2.56:3000 from 192.168.2.98 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9
Sep 02 21:26:32 keller-volumio volumio[799]: verbose: New Socket.io Connection to 192.168.2.56 from 192.168.2.45 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:129.0) Gecko/20100101 Firefox/129.0 Engine version: 3 Transport: polling Total Clients: 10
Sep 02 21:26:32 keller-volumio volumio[799]: info: CoreCommandRouter::volumioGetState
Sep 02 21:26:32 keller-volumio volumio[799]: info: CorePlayQueue::getTrack 0
Sep 02 21:26:32 keller-volumio volumio[799]: info: CoreCommandRouter::volumioGetState
Sep 02 21:26:32 keller-volumio volumio[799]: info: CorePlayQueue::getTrack 0
Sep 02 21:26:32 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Sep 02 21:26:32 keller-volumio volumio[799]: info: Reloading queue from file
Sep 02 21:26:32 keller-volumio volumio[799]: Starting albumart workers
Sep 02 21:26:32 keller-volumio volumio[799]: Starting albumart workers
Sep 02 21:26:32 keller-volumio volumio[799]: Starting albumart workers
Sep 02 21:26:32 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket
Sep 02 21:26:32 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket
Sep 02 21:26:32 keller-volumio volumio[799]: info: VolumeController:: Volume=49 Mute =false
Sep 02 21:26:32 keller-volumio volumio[799]: info: CoreStateMachine::pushState
Sep 02 21:26:32 keller-volumio volumio[799]: info: CorePlayQueue::getTrack 0
Sep 02 21:26:32 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 02 21:26:32 keller-volumio volumio[799]: info: CoreCommandRouter::volumioPushState
Sep 02 21:26:32 keller-volumio volumio[799]: info: CoreStateMachine::updateTrackBlock
Sep 02 21:26:32 keller-volumio volumio[799]: info: CorePlayQueue::getTrackBlock
Sep 02 21:26:32 keller-volumio volumio[799]: info: CoreCommandRouter::volumioRetrievevolume
Sep 02 21:26:33 keller-volumio volumio[799]: info: CoreStateMachine::setRepeat null single undefined
Sep 02 21:26:33 keller-volumio volumio[799]: info: CoreStateMachine::pushState
Sep 02 21:26:33 keller-volumio volumio[799]: info: CorePlayQueue::getTrack 0
Sep 02 21:26:33 keller-volumio volumio[799]: info: CoreCommandRouter::volumioPushState
Sep 02 21:26:33 keller-volumio volumio[799]: info: CoreStateMachine::setRandom false
Sep 02 21:26:33 keller-volumio volumio[799]: info: CoreStateMachine::pushState
Sep 02 21:26:33 keller-volumio volumio[799]: info: CorePlayQueue::getTrack 0
Sep 02 21:26:33 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 02 21:26:33 keller-volumio volumio[799]: info: CoreCommandRouter::volumioPushState
Sep 02 21:26:33 keller-volumio volumio[799]: info: Setting Device type: Raspberry PI
Sep 02 21:26:33 keller-volumio volumio[799]: info: Listing playlists
Sep 02 21:26:33 keller-volumio volumio[799]: info: Listing playlists
Sep 02 21:26:33 keller-volumio volumio[799]: info: Listing playlists
Sep 02 21:26:33 keller-volumio volumio[799]: info: Listing playlists
Sep 02 21:26:33 keller-volumio volumio[799]: info: Completed loading Core Plugins
Sep 02 21:26:33 keller-volumio volumio[799]: info: Preparing to generate the ALSA configuration file
Sep 02 21:26:33 keller-volumio volumio[799]: info: CoreCommandRouter::volumioGetState
Sep 02 21:26:33 keller-volumio volumio[799]: info: CorePlayQueue::getTrack 0
Sep 02 21:26:33 keller-volumio volumio[799]: info: CoreCommandRouter::volumioGetState
Sep 02 21:26:33 keller-volumio volumio[799]: info: CorePlayQueue::getTrack 0
Sep 02 21:26:33 keller-volumio volumio[799]: info: Discovery: adding 853421c5-acdf-42f9-9a35-4d1559efd0b7
Sep 02 21:26:33 keller-volumio volumio[799]: info: Discovery: Found device Keller-Volumio
Sep 02 21:26:33 keller-volumio volumio[799]: info: CoreCommandRouter::volumioGetState
Sep 02 21:26:33 keller-volumio volumio[799]: info: CorePlayQueue::getTrack 0
Sep 02 21:26:33 keller-volumio volumio[799]: info: Asound.conf file unchanged, so no further update is needed
Sep 02 21:26:33 keller-volumio volumio[799]: info: Output device has changed, restarting MPD
Sep 02 21:26:33 keller-volumio volumio[799]: info: ___________ START PLUGINS ___________
Sep 02 21:26:33 keller-volumio sudo[912]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Sep 02 21:26:33 keller-volumio sudo[912]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 02 21:26:33 keller-volumio volumio[799]: info: ControllerMpd::onStart: Initializing MPD
Sep 02 21:26:33 keller-volumio sudo[912]: pam_unix(sudo:session): session closed for user root
Sep 02 21:26:33 keller-volumio volumio[799]: info: Creating MPD Configuration file
Sep 02 21:26:33 keller-volumio sudo[914]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Sep 02 21:26:33 keller-volumio sudo[914]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 02 21:26:33 keller-volumio volumio[799]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Sep 02 21:26:33 keller-volumio volumio[799]: info: [1725305193762] CoreMusicLibrary::Adding element Last_100
Sep 02 21:26:33 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Sep 02 21:26:33 keller-volumio volumio[799]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Sep 02 21:26:33 keller-volumio volumio[799]: info: [1725305193802] CoreMusicLibrary::Adding element Webradio
Sep 02 21:26:33 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Sep 02 21:26:33 keller-volumio sudo[918]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Sep 02 21:26:33 keller-volumio sudo[918]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 02 21:26:33 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Sep 02 21:26:33 keller-volumio volumio[799]: info: Initializing BBC Radios
Sep 02 21:26:33 keller-volumio sudo[920]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Sep 02 21:26:33 keller-volumio sudo[920]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 02 21:26:33 keller-volumio sudo[918]: pam_unix(sudo:session): session closed for user root
Sep 02 21:26:33 keller-volumio systemd[1]: Listening on mpd.socket.
Sep 02 21:26:33 keller-volumio systemd[1]: Starting Music Player Daemon...
Sep 02 21:26:33 keller-volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM
Sep 02 21:26:33 keller-volumio systemd[1]: mpd.service: Succeeded.
Sep 02 21:26:33 keller-volumio systemd[1]: Stopped Music Player Daemon.
Sep 02 21:26:33 keller-volumio systemd[1]: Starting Music Player Daemon...
Sep 02 21:26:34 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Sep 02 21:26:34 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Sep 02 21:26:34 keller-volumio volumio[799]: info: Checking for old spotify connect plugin installed
Sep 02 21:26:34 keller-volumio volumio[799]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Sep 02 21:26:34 keller-volumio volumio[799]: info: [1725305194193] CoreMusicLibrary::Adding element YouTube Music
Sep 02 21:26:34 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Sep 02 21:26:34 keller-volumio volumio[799]: Cannot find translation for source YouTube Music
Sep 02 21:26:34 keller-volumio volumio[799]: info: Volumio Calling Home
Sep 02 21:26:34 keller-volumio sudo[927]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Sep 02 21:26:34 keller-volumio sudo[927]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 02 21:26:34 keller-volumio sudo[927]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory
Sep 02 21:26:34 keller-volumio sudo[927]: pam_unix(sudo:session): session closed for user root
Sep 02 21:26:34 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Sep 02 21:26:34 keller-volumio volumio[799]: [SpotifyConnect] Creating VLS config file
Sep 02 21:26:34 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 02 21:26:34 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 02 21:26:34 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 02 21:26:34 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 02 21:26:34 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 02 21:26:34 keller-volumio volumio[799]: [SpotifyConnect] Starting metadata listener
Sep 02 21:26:34 keller-volumio sudo[966]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service
Sep 02 21:26:34 keller-volumio sudo[966]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 02 21:26:35 keller-volumio systemd[1]: Started Volspotconnect2 Daemon.
Sep 02 21:26:35 keller-volumio sudo[966]: pam_unix(sudo:session): session closed for user root
Sep 02 21:26:35 keller-volumio volumio[799]: info: Discovery: adding c3ea909a-5532-4d4b-9588-22e9a78b45ad
Sep 02 21:26:35 keller-volumio volumio[799]: info: Discovery: Found device Wohnzimmer Volumio
Sep 02 21:26:35 keller-volumio volumio[799]: info: Discovery: Connecting to remote: 192.168.2.30
Sep 02 21:26:35 keller-volumio volumio[799]: info: VolumeController:: Volume=49 Mute =false
Sep 02 21:26:35 keller-volumio volumio[799]: info: CoreStateMachine::pushState
Sep 02 21:26:35 keller-volumio volumio[799]: info: CorePlayQueue::getTrack 0
Sep 02 21:26:35 keller-volumio volumio[799]: info: CoreCommandRouter::volumioPushState
Sep 02 21:26:35 keller-volumio volumio[799]: info: Discovery: adding 87d13a72-253f-40d6-8a10-57e3966d97fb
Sep 02 21:26:35 keller-volumio volumio[799]: info: Discovery: Found device Garage Volumio
Sep 02 21:26:35 keller-volumio volumio[799]: info: Discovery: Connecting to remote: 192.168.2.98
Sep 02 21:26:35 keller-volumio volumio[799]: [SpotifyConnect] Vollibrespot Daemon service started!
Sep 02 21:26:35 keller-volumio volumio[799]: [Metrics] SpotifyConnect: 0s 535.30ms
Sep 02 21:26:35 keller-volumio volumio[799]: info: MPD Permissions set
Sep 02 21:26:35 keller-volumio volumio[799]: info: MPD Permissions set
Sep 02 21:26:35 keller-volumio volumio[799]: info: CoreCommandRouter::volumioGetState
Sep 02 21:26:35 keller-volumio volumio[799]: info: CorePlayQueue::getTrack 0
Sep 02 21:26:35 keller-volumio volumio[799]: info: CoreCommandRouter::volumioGetState
Sep 02 21:26:35 keller-volumio volumio[799]: info: CorePlayQueue::getTrack 0
Sep 02 21:26:35 keller-volumio volumio[799]: info: Discovery: adding 57f61bad-aa7c-4673-aeb3-5795bdd7e8c4
Sep 02 21:26:35 keller-volumio volumio[799]: info: Discovery: Found device Küche Volumio
Sep 02 21:26:35 keller-volumio volumio[799]: info: Discovery: Connecting to remote: 192.168.2.54
Sep 02 21:26:35 keller-volumio volumio[799]: verbose: New Socket.io Connection to 192.168.2.56:3000 from 192.168.2.54 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11
Sep 02 21:26:35 keller-volumio volumio[799]: info: Volumio called home
Sep 02 21:26:35 keller-volumio volumio[799]: info: Discovery: Connected to remote: 192.168.2.98
Sep 02 21:26:35 keller-volumio volumio[799]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12
Sep 02 21:26:35 keller-volumio volumio[799]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13
Sep 02 21:26:35 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 02 21:26:35 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 02 21:26:35 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 02 21:26:35 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 02 21:26:35 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 02 21:26:35 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 02 21:26:35 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 02 21:26:35 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 02 21:26:35 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Sep 02 21:26:35 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 02 21:26:35 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 02 21:26:35 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 02 21:26:35 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 02 21:26:35 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 02 21:26:35 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 02 21:26:35 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 02 21:26:35 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 02 21:26:35 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 02 21:26:35 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Sep 02 21:26:35 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 02 21:26:35 keller-volumio volumio[799]: info: Discovery: Connected to remote: 192.168.2.54
Sep 02 21:26:36 keller-volumio volumio[799]: info: Discovery: Connected to remote: 192.168.2.30
Sep 02 21:26:36 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket
Sep 02 21:26:36 keller-volumio volumio[968]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31
Sep 02 21:26:36 keller-volumio volumio[968]: Reading Config from "/tmp/volspotify.toml"
Sep 02 21:26:36 keller-volumio volumio[799]: info: CoreCommandRouter::volumioGetState
Sep 02 21:26:36 keller-volumio volumio[799]: info: CorePlayQueue::getTrack 0
Sep 02 21:26:36 keller-volumio volumio[799]: info: CoreCommandRouter::volumioGetState
Sep 02 21:26:36 keller-volumio volumio[799]: info: CorePlayQueue::getTrack 0
Sep 02 21:26:36 keller-volumio volumio[968]: [Vollibrespot] : Using Alsa backend with device: volumio
Sep 02 21:26:36 keller-volumio volumio[799]: verbose: New Socket.io Connection to 192.168.2.56 from 192.168.2.45 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:129.0) Gecko/20100101 Firefox/129.0 Engine version: 3 Transport: polling Total Clients: 14
Sep 02 21:26:37 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Sep 02 21:26:37 keller-volumio volumio[799]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined
Sep 02 21:26:37 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates
Sep 02 21:26:37 keller-volumio volumio[799]: info: Received Get System Version
Sep 02 21:26:37 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Sep 02 21:26:37 keller-volumio volumio[799]: info: Received Get System Info
Sep 02 21:26:37 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Sep 02 21:26:37 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Sep 02 21:26:37 keller-volumio volumio[799]: info: Discovery: Getting this device information
Sep 02 21:26:37 keller-volumio volumio[799]: info: CoreCommandRouter::volumioGetState
Sep 02 21:26:37 keller-volumio volumio[799]: info: CorePlayQueue::getTrack 0
Sep 02 21:26:37 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Sep 02 21:26:37 keller-volumio volumio[799]: info: CoreCommandRouter::volumioGetState
Sep 02 21:26:37 keller-volumio volumio[799]: info: CorePlayQueue::getTrack 0
Sep 02 21:26:37 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Sep 02 21:26:37 keller-volumio volumio[799]: info: CoreCommandRouter::volumioGetVisibleSources
Sep 02 21:26:37 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Sep 02 21:26:37 keller-volumio volumio[799]: info: Listing playlists
Sep 02 21:26:37 keller-volumio volumio[968]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Uncategorized, message: "No such device" }
Sep 02 21:26:37 keller-volumio volumio[968]: [Vollibrespot] : Connecting to AP "ap2-gew4.spotify.com:443"
Sep 02 21:26:38 keller-volumio volumio[968]: thread 'main' panicked at 'Authentication failed with reason: BadCredentials', /build/cache/git/checkouts/librespot-6f197fd632ef9380/08d8bcc/core/src/connection/mod.rs:93:21
Sep 02 21:26:38 keller-volumio volumio[968]: stack backtrace:
Sep 02 21:26:38 keller-volumio volumio[968]: 0: 0xa58444 - std::backtrace_rs::backtrace::libunwind::trace::h40a4eff66330a36d
Sep 02 21:26:38 keller-volumio volumio[968]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/../../backtrace/src/backtrace/libunwind.rs:93:5
Sep 02 21:26:38 keller-volumio volumio[968]: 1: 0xa58444 - std::backtrace_rs::backtrace::trace_unsynchronized::hbce01963451b0cd5
Sep 02 21:26:38 keller-volumio volumio[968]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5
Sep 02 21:26:38 keller-volumio volumio[968]: 2: 0xa58444 - std::sys_common::backtrace::_print_fmt::h95da650d09c7e00b
Sep 02 21:26:38 keller-volumio volumio[968]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:66:5
Sep 02 21:26:38 keller-volumio volumio[968]: 3: 0xa58444 - ::fmt::h33b23b1d14f668ff
Sep 02 21:26:38 keller-volumio volumio[968]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:45:22
Sep 02 21:26:39 keller-volumio volumio[968]: 4: 0xa810dc - core::fmt::write::h6320fb0e6ac90e58
Sep 02 21:26:39 keller-volumio volumio[968]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/fmt/mod.rs:1190:17
Sep 02 21:26:39 keller-volumio volumio[968]: 5: 0xa504c4 - std::io::Write::write_fmt::h33b25d4810ca481f
Sep 02 21:26:39 keller-volumio volumio[968]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/io/mod.rs:1657:15
Sep 02 21:26:39 keller-volumio volumio[968]: 6: 0xa5aef4 - std::sys_common::backtrace::_print::h7e61c5be69c715ef
Sep 02 21:26:39 keller-volumio volumio[968]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:48:5
Sep 02 21:26:39 keller-volumio volumio[968]: 7: 0xa5aef4 - std::sys_common::backtrace::print::h3cee176d7c8dab8b
Sep 02 21:26:39 keller-volumio volumio[968]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:35:9
Sep 02 21:26:39 keller-volumio volumio[968]: 8: 0xa5aef4 - std::panicking::default_hook::{{closure}}::h2d0211dd2e97dc43
Sep 02 21:26:39 keller-volumio volumio[968]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:295:22
Sep 02 21:26:39 keller-volumio volumio[968]: 9: 0xa5ab14 - std::panicking::default_hook::hcbdb3b7c40dca30c
Sep 02 21:26:39 keller-volumio volumio[968]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:314:9
Sep 02 21:26:39 keller-volumio volumio[968]: 10: 0xa5b694 - std::panicking::rust_panic_with_hook::h4ce49e7a343dc12b
Sep 02 21:26:39 keller-volumio volumio[968]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:698:17
Sep 02 21:26:39 keller-volumio volumio[968]: 11: 0xa5b304 - std::panicking::begin_panic_handler::{{closure}}::h4961100fae529fe9
Sep 02 21:26:39 keller-volumio volumio[968]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:588:13
Sep 02 21:26:39 keller-volumio volumio[968]: 12: 0xa589c4 - std::sys_common::backtrace::__rust_end_short_backtrace::hf2e68e62c7a80f1f
Sep 02 21:26:39 keller-volumio volumio[968]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:138:18
Sep 02 21:26:39 keller-volumio volumio[968]: 13: 0xa5b098 - rust_begin_unwind
Sep 02 21:26:39 keller-volumio volumio[968]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:584:5
Sep 02 21:26:39 keller-volumio volumio[968]: 14: 0x53e6f4 - core::panicking::panic_fmt::h84c42730e4a50809
Sep 02 21:26:39 keller-volumio volumio[968]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/panicking.rs:143:14
Sep 02 21:26:39 keller-volumio volumio[968]: 15: 0x72a930 - futures::future::chain::Chain::poll::hb08394d341f2da5e
Sep 02 21:26:39 keller-volumio volumio[968]: 16: 0x7a8d4c - as futures::future::Future>::poll::h0222f8b810b74ccb
Sep 02 21:26:39 keller-volumio volumio[968]: 17: 0x72b53c - futures::future::chain::Chain::poll::hd3215a5f3268adbc
Sep 02 21:26:39 keller-volumio volumio[968]: 18: 0x7a15f0 - as futures::future::Future>::poll::hc28eaa01d8d9029a
Sep 02 21:26:39 keller-volumio volumio[968]: 19: 0x565b34 - ::poll::h5fd9e3bcdeb25ad9
Sep 02 21:26:39 keller-volumio volumio[968]: 20: 0x5833dc - futures::task_impl::std::set::h8f754e80bd562d0a
Sep 02 21:26:39 keller-volumio volumio[968]: 21: 0x59a42c - std::thread::local::LocalKey::with::h71cfa1e374fcc0a0
Sep 02 21:26:39 keller-volumio volumio[968]: 22: 0x57f99c - tokio_current_thread::Entered::block_on::hde55cdf28eabe6d5
Sep 02 21:26:39 keller-volumio volumio[968]: 23: 0x59a6f0 - std::thread::local::LocalKey::with::hedba3cfe41326627
Sep 02 21:26:39 keller-volumio volumio[968]: 24: 0x582880 - tokio::runtime::current_thread::runtime::Runtime::block_on::h60d5b89560100ed8
Sep 02 21:26:39 keller-volumio volumio[968]: 25: 0x568eb0 - vollibrespot::main::h35555355ed947dd1
Sep 02 21:26:39 keller-volumio volumio[968]: 26: 0x588c50 - std::sys_common::backtrace::__rust_begin_short_backtrace::hef626a10d324c12a
Sep 02 21:26:39 keller-volumio volumio[968]: 27: 0x5ae52c - std::rt::lang_start::{{closure}}::hd80188e4bcac5072
Sep 02 21:26:39 keller-volumio volumio[968]: 28: 0xa57b28 - core::ops::function::impls:: for &F>::call_once::h0567da839a080232
Sep 02 21:26:39 keller-volumio volumio[968]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/ops/function.rs:259:13
Sep 02 21:26:39 keller-volumio volumio[968]: 29: 0xa57b28 - std::panicking::try::do_call::hdf194554a41aa3cc
Sep 02 21:26:39 keller-volumio volumio[968]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:492:40
Sep 02 21:26:39 keller-volumio volumio[968]: 30: 0xa57b28 - std::panicking::try::hbf4b7be9f9ffa5f8
Sep 02 21:26:39 keller-volumio volumio[968]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:456:19
Sep 02 21:26:39 keller-volumio volumio[968]: 31: 0xa57b28 - std::panic::catch_unwind::h45f392a39f2fe48c
Sep 02 21:26:39 keller-volumio volumio[968]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panic.rs:137:14
Sep 02 21:26:39 keller-volumio volumio[968]: 32: 0xa57b28 - std::rt::lang_start_internal::{{closure}}::hd772cb03c2060671
Sep 02 21:26:39 keller-volumio volumio[968]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/rt.rs:128:48
Sep 02 21:26:39 keller-volumio volumio[968]: 33: 0xa57b28 - std::panicking::try::do_call::hd126f715b519ff2a
Sep 02 21:26:39 keller-volumio volumio[968]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:492:40
Sep 02 21:26:39 keller-volumio volumio[968]: 34: 0xa57b28 - std::panicking::try::h80b7148ce71893dc
Sep 02 21:26:39 keller-volumio volumio[968]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:456:19
Sep 02 21:26:39 keller-volumio volumio[968]: 35: 0xa57b28 - std::panic::catch_unwind::h1562f0b2e8a9cb0d
Sep 02 21:26:39 keller-volumio volumio[968]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panic.rs:137:14
Sep 02 21:26:39 keller-volumio volumio[968]: 36: 0xa57b28 - std::rt::lang_start_internal::h3daa586a0b835507
Sep 02 21:26:39 keller-volumio volumio[968]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/rt.rs:128:20
Sep 02 21:26:39 keller-volumio volumio[968]: 37: 0x56ae80 - main
Sep 02 21:26:39 keller-volumio volumio[968]: 38: 0x76c32768 - __libc_start_main
Sep 02 21:26:39 keller-volumio systemd[1]: volspotconnect.service: Main process exited, code=exited, status=101/n/a
Sep 02 21:26:39 keller-volumio systemd[1]: volspotconnect.service: Failed with result 'exit-code'.
Sep 02 21:26:39 keller-volumio volumio[799]: info: Checking Spotify Web API
Sep 02 21:26:39 keller-volumio volumio-remote-updater[476]: [2024-09-02 21:26:39] [connect] Successful connection
Sep 02 21:26:39 keller-volumio volumio-remote-updater[476]: [2024-09-02 21:26:39] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1725305199 101
Sep 02 21:26:39 keller-volumio volumio[799]: 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: 15
Sep 02 21:26:41 keller-volumio systemd[1]: volspotconnect.service: Service RestartSec=2s expired, scheduling restart.
Sep 02 21:26:41 keller-volumio systemd[1]: volspotconnect.service: Scheduled restart job, restart counter is at 1.
Sep 02 21:26:41 keller-volumio systemd[1]: Stopped Volspotconnect2 Daemon.
Sep 02 21:26:41 keller-volumio systemd[1]: Started Volspotconnect2 Daemon.
Sep 02 21:26:41 keller-volumio volumio[983]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31
Sep 02 21:26:41 keller-volumio volumio[983]: Reading Config from "/tmp/volspotify.toml"
Sep 02 21:26:41 keller-volumio volumio[983]: [Vollibrespot] : Using Alsa backend with device: volumio
Sep 02 21:26:41 keller-volumio volumio[983]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Uncategorized, message: "No such device" }
Sep 02 21:26:41 keller-volumio volumio[983]: [Vollibrespot] : Connecting to AP "ap2-gew4.spotify.com:443"
Sep 02 21:26:41 keller-volumio volumio[983]: thread 'main' panicked at 'Authentication failed with reason: BadCredentials', /build/cache/git/checkouts/librespot-6f197fd632ef9380/08d8bcc/core/src/connection/mod.rs:93:21
Sep 02 21:26:41 keller-volumio volumio[983]: stack backtrace:
Sep 02 21:26:41 keller-volumio volumio[983]: 0: 0xa18444 - std::backtrace_rs::backtrace::libunwind::trace::h40a4eff66330a36d
Sep 02 21:26:41 keller-volumio volumio[983]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/../../backtrace/src/backtrace/libunwind.rs:93:5
Sep 02 21:26:41 keller-volumio volumio[983]: 1: 0xa18444 - std::backtrace_rs::backtrace::trace_unsynchronized::hbce01963451b0cd5
Sep 02 21:26:41 keller-volumio volumio[983]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5
Sep 02 21:26:41 keller-volumio volumio[983]: 2: 0xa18444 - std::sys_common::backtrace::_print_fmt::h95da650d09c7e00b
Sep 02 21:26:41 keller-volumio volumio[983]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:66:5
Sep 02 21:26:41 keller-volumio volumio[983]: 3: 0xa18444 - ::fmt::h33b23b1d14f668ff
Sep 02 21:26:41 keller-volumio volumio[983]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:45:22
Sep 02 21:26:41 keller-volumio volumio[983]: 4: 0xa410dc - core::fmt::write::h6320fb0e6ac90e58
Sep 02 21:26:41 keller-volumio volumio[983]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/fmt/mod.rs:1190:17
Sep 02 21:26:41 keller-volumio volumio[983]: 5: 0xa104c4 - std::io::Write::write_fmt::h33b25d4810ca481f
Sep 02 21:26:41 keller-volumio volumio[983]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/io/mod.rs:1657:15
Sep 02 21:26:41 keller-volumio volumio[983]: 6: 0xa1aef4 - std::sys_common::backtrace::_print::h7e61c5be69c715ef
Sep 02 21:26:41 keller-volumio volumio[983]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:48:5
Sep 02 21:26:41 keller-volumio volumio[983]: 7: 0xa1aef4 - std::sys_common::backtrace::print::h3cee176d7c8dab8b
Sep 02 21:26:41 keller-volumio volumio[983]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:35:9
Sep 02 21:26:41 keller-volumio volumio[983]: 8: 0xa1aef4 - std::panicking::default_hook::{{closure}}::h2d0211dd2e97dc43
Sep 02 21:26:41 keller-volumio volumio[983]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:295:22
Sep 02 21:26:41 keller-volumio volumio[983]: 9: 0xa1ab14 - std::panicking::default_hook::hcbdb3b7c40dca30c
Sep 02 21:26:41 keller-volumio volumio[983]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:314:9
Sep 02 21:26:41 keller-volumio volumio[983]: 10: 0xa1b694 - std::panicking::rust_panic_with_hook::h4ce49e7a343dc12b
Sep 02 21:26:41 keller-volumio volumio[983]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:698:17
Sep 02 21:26:41 keller-volumio volumio[983]: 11: 0xa1b304 - std::panicking::begin_panic_handler::{{closure}}::h4961100fae529fe9
Sep 02 21:26:41 keller-volumio volumio[983]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:588:13
Sep 02 21:26:41 keller-volumio volumio[983]: 12: 0xa189c4 - std::sys_common::backtrace::__rust_end_short_backtrace::hf2e68e62c7a80f1f
Sep 02 21:26:41 keller-volumio volumio[983]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:138:18
Sep 02 21:26:41 keller-volumio volumio[983]: 13: 0xa1b098 - rust_begin_unwind
Sep 02 21:26:41 keller-volumio volumio[983]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:584:5
Sep 02 21:26:41 keller-volumio volumio[983]: 14: 0x4fe6f4 - core::panicking::panic_fmt::h84c42730e4a50809
Sep 02 21:26:41 keller-volumio volumio[983]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/panicking.rs:143:14
Sep 02 21:26:41 keller-volumio volumio[983]: 15: 0x6ea930 - futures::future::chain::Chain::poll::hb08394d341f2da5e
Sep 02 21:26:41 keller-volumio volumio[983]: 16: 0x768d4c - as futures::future::Future>::poll::h0222f8b810b74ccb
Sep 02 21:26:41 keller-volumio volumio[983]: 17: 0x6eb53c - futures::future::chain::Chain::poll::hd3215a5f3268adbc
Sep 02 21:26:41 keller-volumio volumio[983]: 18: 0x7615f0 - as futures::future::Future>::poll::hc28eaa01d8d9029a
Sep 02 21:26:41 keller-volumio volumio[983]: 19: 0x525b34 - ::poll::h5fd9e3bcdeb25ad9
Sep 02 21:26:41 keller-volumio volumio[983]: 20: 0x5433dc - futures::task_impl::std::set::h8f754e80bd562d0a
Sep 02 21:26:41 keller-volumio volumio[983]: 21: 0x55a42c - std::thread::local::LocalKey::with::h71cfa1e374fcc0a0
Sep 02 21:26:41 keller-volumio volumio[983]: 22: 0x53f99c - tokio_current_thread::Entered::block_on::hde55cdf28eabe6d5
Sep 02 21:26:41 keller-volumio volumio[983]: 23: 0x55a6f0 - std::thread::local::LocalKey::with::hedba3cfe41326627
Sep 02 21:26:41 keller-volumio volumio[983]: 24: 0x542880 - tokio::runtime::current_thread::runtime::Runtime::block_on::h60d5b89560100ed8
Sep 02 21:26:41 keller-volumio volumio[983]: 25: 0x528eb0 - vollibrespot::main::h35555355ed947dd1
Sep 02 21:26:41 keller-volumio volumio[983]: 26: 0x548c50 - std::sys_common::backtrace::__rust_begin_short_backtrace::hef626a10d324c12a
Sep 02 21:26:41 keller-volumio volumio[983]: 27: 0x56e52c - std::rt::lang_start::{{closure}}::hd80188e4bcac5072
Sep 02 21:26:41 keller-volumio volumio[983]: 28: 0xa17b28 - core::ops::function::impls:: for &F>::call_once::h0567da839a080232
Sep 02 21:26:41 keller-volumio volumio[983]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/ops/function.rs:259:13
Sep 02 21:26:41 keller-volumio volumio[983]: 29: 0xa17b28 - std::panicking::try::do_call::hdf194554a41aa3cc
Sep 02 21:26:41 keller-volumio volumio[983]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:492:40
Sep 02 21:26:41 keller-volumio volumio[983]: 30: 0xa17b28 - std::panicking::try::hbf4b7be9f9ffa5f8
Sep 02 21:26:41 keller-volumio volumio[983]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:456:19
Sep 02 21:26:41 keller-volumio volumio[983]: 31: 0xa17b28 - std::panic::catch_unwind::h45f392a39f2fe48c
Sep 02 21:26:41 keller-volumio volumio[983]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panic.rs:137:14
Sep 02 21:26:41 keller-volumio volumio[983]: 32: 0xa17b28 - std::rt::lang_start_internal::{{closure}}::hd772cb03c2060671
Sep 02 21:26:41 keller-volumio volumio[983]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/rt.rs:128:48
Sep 02 21:26:41 keller-volumio volumio[983]: 33: 0xa17b28 - std::panicking::try::do_call::hd126f715b519ff2a
Sep 02 21:26:41 keller-volumio volumio[983]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:492:40
Sep 02 21:26:41 keller-volumio volumio[983]: 34: 0xa17b28 - std::panicking::try::h80b7148ce71893dc
Sep 02 21:26:41 keller-volumio volumio[983]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:456:19
Sep 02 21:26:41 keller-volumio volumio[983]: 35: 0xa17b28 - std::panic::catch_unwind::h1562f0b2e8a9cb0d
Sep 02 21:26:41 keller-volumio volumio[983]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panic.rs:137:14
Sep 02 21:26:41 keller-volumio volumio[983]: 36: 0xa17b28 - std::rt::lang_start_internal::h3daa586a0b835507
Sep 02 21:26:41 keller-volumio volumio[983]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/rt.rs:128:20
Sep 02 21:26:41 keller-volumio volumio[983]: 37: 0x52ae80 - main
Sep 02 21:26:41 keller-volumio volumio[983]: 38: 0x76c38768 - __libc_start_main
Sep 02 21:26:41 keller-volumio systemd[1]: volspotconnect.service: Main process exited, code=exited, status=101/n/a
Sep 02 21:26:41 keller-volumio systemd[1]: volspotconnect.service: Failed with result 'exit-code'.
Sep 02 21:26:42 keller-volumio mpd[938]: Sep 02 21:26 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Sep 02 21:26:42 keller-volumio systemd[1]: Started Music Player Daemon.
Sep 02 21:26:42 keller-volumio sudo[920]: pam_unix(sudo:session): session closed for user root
Sep 02 21:26:42 keller-volumio sudo[914]: pam_unix(sudo:session): session closed for user root
Sep 02 21:26:42 keller-volumio volumio[799]: info: Completed starting Core Plugins
Sep 02 21:26:42 keller-volumio volumio[799]: info: -------------------------------------------
Sep 02 21:26:42 keller-volumio volumio[799]: info: ----- MyVolumio plugins startup ----
Sep 02 21:26:42 keller-volumio volumio[799]: info: -------------------------------------------
Sep 02 21:26:42 keller-volumio volumio[799]: info: [MyVolumio PluginManager] Fetching plans data....
Sep 02 21:26:42 keller-volumio volumio[799]: info: MPD running with PID938
Sep 02 21:26:42 keller-volumio volumio[799]: ,establishing connection
Sep 02 21:26:43 keller-volumio volumio[799]: error: MPD error: The expression evaluated to a falsy value:
Sep 02 21:26:43 keller-volumio volumio[799]: assert.ok(self.idling)
Sep 02 21:26:43 keller-volumio volumio[799]: error: The expression evaluated to a falsy value:
Sep 02 21:26:43 keller-volumio volumio[799]: assert.ok(self.idling)
Sep 02 21:26:43 keller-volumio volumio[799]: error: MPD error: The expression evaluated to a falsy value:
Sep 02 21:26:43 keller-volumio volumio[799]: assert.ok(self.idling)
Sep 02 21:26:43 keller-volumio volumio[799]: error: The expression evaluated to a falsy value:
Sep 02 21:26:43 keller-volumio volumio[799]: assert.ok(self.idling)
Sep 02 21:26:43 keller-volumio volumio[799]: error: updateQueue error: null
Sep 02 21:26:44 keller-volumio systemd[1]: volspotconnect.service: Service RestartSec=2s expired, scheduling restart.
Sep 02 21:26:44 keller-volumio systemd[1]: volspotconnect.service: Scheduled restart job, restart counter is at 2.
Sep 02 21:26:44 keller-volumio systemd[1]: Stopped Volspotconnect2 Daemon.
Sep 02 21:26:44 keller-volumio systemd[1]: Started Volspotconnect2 Daemon.
Sep 02 21:26:44 keller-volumio volumio[993]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31
Sep 02 21:26:44 keller-volumio volumio[993]: Reading Config from "/tmp/volspotify.toml"
Sep 02 21:26:44 keller-volumio volumio[993]: [Vollibrespot] : Using Alsa backend with device: volumio
Sep 02 21:26:44 keller-volumio volumio[993]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Uncategorized, message: "No such device" }
Sep 02 21:26:44 keller-volumio volumio[993]: [Vollibrespot] : Connecting to AP "ap2-gew4.spotify.com:443"
Sep 02 21:26:44 keller-volumio volumio[993]: [Vollibrespot] : Could not connect to server: Connection refused (os error 111)
Sep 02 21:26:45 keller-volumio volumio[799]: info: CoreCommandRouter::volumioGetState
Sep 02 21:26:45 keller-volumio volumio[799]: info: CorePlayQueue::getTrack 0
Sep 02 21:26:45 keller-volumio volumio[799]: info: CoreCommandRouter::volumioGetState
Sep 02 21:26:45 keller-volumio volumio[799]: info: CorePlayQueue::getTrack 0
Sep 02 21:26:46 keller-volumio volumio[799]: info: CoreCommandRouter::volumioPlay
Sep 02 21:26:46 keller-volumio volumio[799]: info: CoreStateMachine::play index undefined
Sep 02 21:26:46 keller-volumio volumio[799]: info: CoreStateMachine::setConsumeUpdateService undefined
Sep 02 21:26:46 keller-volumio volumio[799]: info: CorePlayQueue::getTrack 0
Sep 02 21:26:46 keller-volumio volumio[799]: info: CoreStateMachine::startPlaybackTimer
Sep 02 21:26:46 keller-volumio volumio[799]: info: CorePlayQueue::getTrack 0
Sep 02 21:26:46 keller-volumio volumio[799]: info: [1725305206847] ControllerWebradio::clearAddPlayTrack
Sep 02 21:26:46 keller-volumio volumio[799]: verbose: ControllerMpd::sendMpdCommand stop
Sep 02 21:26:46 keller-volumio volumio[799]: info: sendMpdCommand stop took 5 milliseconds
Sep 02 21:26:46 keller-volumio volumio[799]: verbose: ControllerMpd::sendMpdCommand clear
Sep 02 21:26:46 keller-volumio volumio[799]: info:
Sep 02 21:26:46 keller-volumio volumio[799]: ---------------------------- MPD announces system playlist update
Sep 02 21:26:46 keller-volumio volumio[799]: info: Ignoring MPD Status Update
Sep 02 21:26:46 keller-volumio volumio[799]: info: sendMpdCommand clear took 7 milliseconds
Sep 02 21:26:46 keller-volumio volumio[799]: verbose: ControllerMpd::sendMpdCommand load "http://wdr-wdr4-live.icecastssl.wdr.de/wdr/wdr4/live/mp3/128/stream.mp3?aggregator=tunein"
Sep 02 21:26:46 keller-volumio volumio[799]: info:
Sep 02 21:26:46 keller-volumio volumio[799]: ---------------------------- MPD announces system playlist update
Sep 02 21:26:46 keller-volumio volumio[799]: info: Ignoring MPD Status Update
Sep 02 21:26:46 keller-volumio volumio[799]: info:
Sep 02 21:26:46 keller-volumio volumio[799]: ---------------------------- MPD announces system playlist update
Sep 02 21:26:46 keller-volumio volumio[799]: info: Ignoring MPD Status Update
Sep 02 21:26:46 keller-volumio volumio[799]: error: updateQueue error: null
Sep 02 21:26:46 keller-volumio volumio[799]: info: ------------------------------ 22ms
Sep 02 21:26:48 keller-volumio volumio[799]: error: updateQueue error: null
Sep 02 21:26:48 keller-volumio volumio[799]: error: updateQueue error: null
Sep 02 21:26:48 keller-volumio volumio[799]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory
Sep 02 21:26:48 keller-volumio volumio[799]: verbose: ControllerMpd::sendMpdCommand add "http://wdr-wdr4-live.icecastssl.wdr.de/wdr/wdr4/live/mp3/128/stream.mp3?aggregator=tunein"
Sep 02 21:26:48 keller-volumio volumio[799]: info: ------------------------------ 2030ms
Sep 02 21:26:48 keller-volumio volumio[799]: info: ------------------------------ 2027ms
Sep 02 21:26:48 keller-volumio volumio[799]: info:
Sep 02 21:26:48 keller-volumio volumio[799]: ---------------------------- MPD announces system playlist update
Sep 02 21:26:48 keller-volumio volumio[799]: info: Ignoring MPD Status Update
Sep 02 21:26:48 keller-volumio volumio[799]: info: sendMpdCommand add "http://wdr-wdr4-live.icecastssl.wdr.de/wdr/wdr4/live/mp3/128/stream.mp3?aggregator=tunein" took 9 milliseconds
Sep 02 21:26:48 keller-volumio volumio[799]: info: CoreStateMachine::setConsumeUpdateService mpd
Sep 02 21:26:48 keller-volumio volumio[799]: verbose: ControllerMpd::sendMpdCommand play
Sep 02 21:26:48 keller-volumio volumio[799]: info:
Sep 02 21:26:48 keller-volumio volumio[799]: ---------------------------- MPD announces system playlist update
Sep 02 21:26:48 keller-volumio volumio[799]: info: Ignoring MPD Status Update
Sep 02 21:26:48 keller-volumio volumio[799]: info:
Sep 02 21:26:48 keller-volumio volumio[799]: ---------------------------- MPD announces system playlist update
Sep 02 21:26:48 keller-volumio volumio[799]: info: Ignoring MPD Status Update
Sep 02 21:26:48 keller-volumio volumio[799]: info: ------------------------------ 40ms
Sep 02 21:26:48 keller-volumio volumio[799]: info: sendMpdCommand play took 19 milliseconds
Sep 02 21:26:48 keller-volumio volumio[799]: info: ------------------------------ 18ms
Sep 02 21:26:48 keller-volumio volumio[799]: info: ------------------------------ 15ms
Sep 02 21:26:48 keller-volumio volumio[799]: info:
Sep 02 21:26:48 keller-volumio volumio[799]: ---------------------------- MPD announces state update: player
Sep 02 21:26:48 keller-volumio volumio[799]: info: ControllerMpd::getState
Sep 02 21:26:48 keller-volumio volumio[799]: verbose: ControllerMpd::sendMpdCommand status
Sep 02 21:26:48 keller-volumio volumio[799]: info:
Sep 02 21:26:48 keller-volumio volumio[799]: ---------------------------- MPD announces state update: player
Sep 02 21:26:48 keller-volumio volumio[799]: info: ControllerMpd::getState
Sep 02 21:26:48 keller-volumio volumio[799]: verbose: ControllerMpd::sendMpdCommand status
Sep 02 21:26:49 keller-volumio volumio[799]: info:
Sep 02 21:26:49 keller-volumio volumio[799]: ---------------------------- MPD announces state update: player
Sep 02 21:26:49 keller-volumio volumio[799]: info: ControllerMpd::getState
Sep 02 21:26:49 keller-volumio volumio[799]: verbose: ControllerMpd::sendMpdCommand status
Sep 02 21:26:49 keller-volumio volumio[799]: info: sendMpdCommand status took 63 milliseconds
Sep 02 21:26:49 keller-volumio volumio[799]: verbose: ControllerMpd::parseState
Sep 02 21:26:49 keller-volumio volumio[799]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Sep 02 21:26:49 keller-volumio volumio[799]: info:
Sep 02 21:26:49 keller-volumio volumio[799]: ---------------------------- MPD announces state update: player
Sep 02 21:26:49 keller-volumio volumio[799]: info: ControllerMpd::getState
Sep 02 21:26:49 keller-volumio volumio[799]: verbose: ControllerMpd::sendMpdCommand status
Sep 02 21:26:49 keller-volumio volumio[799]: info: sendMpdCommand status took 68 milliseconds
Sep 02 21:26:49 keller-volumio volumio[799]: info: sendMpdCommand status took 64 milliseconds
Sep 02 21:26:49 keller-volumio volumio[799]: info: sendMpdCommand playlistinfo took 7 milliseconds
Sep 02 21:26:49 keller-volumio volumio[799]: verbose: ControllerMpd::parseState
Sep 02 21:26:49 keller-volumio volumio[799]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Sep 02 21:26:49 keller-volumio volumio[799]: verbose: ControllerMpd::parseState
Sep 02 21:26:49 keller-volumio volumio[799]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Sep 02 21:26:49 keller-volumio volumio[799]: verbose: ControllerMpd::parseTrackInfo
Sep 02 21:26:49 keller-volumio volumio[799]: info: ControllerMpd::pushState
Sep 02 21:26:49 keller-volumio volumio[799]: info: CoreCommandRouter::servicePushState
Sep 02 21:26:49 keller-volumio volumio[799]: info: CorePlayQueue::getTrack 0
Sep 02 21:26:49 keller-volumio volumio[799]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.mp3?aggregator=tunein","artist":null,"album":null,"uri":"http://wdr-wdr4-live.icecastssl.wdr.de/wdr/wdr4/live/mp3/128/stream.mp3?aggregator=tunein","trackType":""}
Sep 02 21:26:49 keller-volumio volumio[799]: verbose: CURRENT POSITION 0
Sep 02 21:26:49 keller-volumio volumio[799]: info: CoreStateMachine::syncState stateService play
Sep 02 21:26:49 keller-volumio volumio[799]: info: CoreStateMachine::syncState currentStatus stop
Sep 02 21:26:49 keller-volumio volumio[799]: info: ------------------------------ 99ms
Sep 02 21:26:49 keller-volumio volumio[799]: info:
Sep 02 21:26:49 keller-volumio volumio[799]: ---------------------------- MPD announces state update: player
Sep 02 21:26:49 keller-volumio volumio[799]: info: ControllerMpd::getState
Sep 02 21:26:49 keller-volumio volumio[799]: verbose: ControllerMpd::sendMpdCommand status
Sep 02 21:26:49 keller-volumio volumio[799]: info:
Sep 02 21:26:49 keller-volumio volumio[799]: ---------------------------- MPD announces state update: player
Sep 02 21:26:49 keller-volumio volumio[799]: info: ControllerMpd::getState
Sep 02 21:26:49 keller-volumio volumio[799]: verbose: ControllerMpd::sendMpdCommand status
Sep 02 21:26:49 keller-volumio volumio[799]: info: sendMpdCommand status took 32 milliseconds
Sep 02 21:26:49 keller-volumio volumio[799]: info: sendMpdCommand playlistinfo took 28 milliseconds
Sep 02 21:26:49 keller-volumio volumio[799]: info: sendMpdCommand playlistinfo took 28 milliseconds
Sep 02 21:26:49 keller-volumio volumio[799]: info: sendMpdCommand status took 8 milliseconds
Sep 02 21:26:49 keller-volumio volumio[799]: info: sendMpdCommand status took 6 milliseconds
Sep 02 21:26:49 keller-volumio volumio[799]: verbose: ControllerMpd::parseState
Sep 02 21:26:49 keller-volumio volumio[799]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Sep 02 21:26:49 keller-volumio volumio[799]: verbose: ControllerMpd::parseTrackInfo
Sep 02 21:26:49 keller-volumio volumio[799]: verbose: ControllerMpd::parseTrackInfo
Sep 02 21:26:49 keller-volumio volumio[799]: verbose: ControllerMpd::parseState
Sep 02 21:26:49 keller-volumio volumio[799]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Sep 02 21:26:49 keller-volumio volumio[799]: verbose: ControllerMpd::parseState
Sep 02 21:26:49 keller-volumio volumio[799]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Sep 02 21:26:49 keller-volumio volumio[799]: info: ControllerMpd::pushState
Sep 02 21:26:49 keller-volumio volumio[799]: info: CoreCommandRouter::servicePushState
Sep 02 21:26:49 keller-volumio volumio[799]: info: CorePlayQueue::getTrack 0
Sep 02 21:26:49 keller-volumio volumio[799]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.mp3?aggregator=tunein","artist":null,"album":null,"uri":"http://wdr-wdr4-live.icecastssl.wdr.de/wdr/wdr4/live/mp3/128/stream.mp3?aggregator=tunein","trackType":""}
Sep 02 21:26:49 keller-volumio volumio[799]: verbose: CURRENT POSITION 0
Sep 02 21:26:49 keller-volumio volumio[799]: info: CoreStateMachine::syncState stateService play
Sep 02 21:26:49 keller-volumio volumio[799]: info: CoreStateMachine::syncState currentStatus play
Sep 02 21:26:49 keller-volumio volumio[799]: info: Received an update from plugin. extracting info from payload
Sep 02 21:26:49 keller-volumio volumio[799]: info: CoreStateMachine::pushState
Sep 02 21:26:49 keller-volumio volumio[799]: info: CorePlayQueue::getTrack 0
Sep 02 21:26:49 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 02 21:26:49 keller-volumio volumio[799]: info: CoreCommandRouter::volumioPushState
Sep 02 21:26:49 keller-volumio volumio[799]: info: CoreStateMachine::pushState
Sep 02 21:26:49 keller-volumio volumio[799]: info: CoreCommandRouter::volumioPushState
Sep 02 21:26:49 keller-volumio volumio[799]: info: ControllerMpd::pushState
Sep 02 21:26:49 keller-volumio volumio[799]: info: CoreCommandRouter::servicePushState
Sep 02 21:26:49 keller-volumio volumio[799]: info: CorePlayQueue::getTrack 0
Sep 02 21:26:49 keller-volumio volumio[799]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.mp3?aggregator=tunein","artist":null,"album":null,"uri":"http://wdr-wdr4-live.icecastssl.wdr.de/wdr/wdr4/live/mp3/128/stream.mp3?aggregator=tunein","trackType":""}
Sep 02 21:26:49 keller-volumio volumio[799]: verbose: CURRENT POSITION 0
Sep 02 21:26:49 keller-volumio volumio[799]: info: CoreStateMachine::syncState stateService play
Sep 02 21:26:49 keller-volumio volumio[799]: info: CoreStateMachine::syncState currentStatus play
Sep 02 21:26:49 keller-volumio volumio[799]: info: Received an update from plugin. extracting info from payload
Sep 02 21:26:49 keller-volumio volumio[799]: info: CoreStateMachine::pushState
Sep 02 21:26:49 keller-volumio volumio[799]: info: CorePlayQueue::getTrack 0
Sep 02 21:26:49 keller-volumio volumio[799]: info: CoreCommandRouter::volumioPushState
Sep 02 21:26:49 keller-volumio volumio[799]: info: CoreStateMachine::pushState
Sep 02 21:26:49 keller-volumio volumio[799]: info: CoreCommandRouter::volumioPushState
Sep 02 21:26:49 keller-volumio volumio[799]: info: ------------------------------ 176ms
Sep 02 21:26:49 keller-volumio volumio[799]: info: ------------------------------ 173ms
Sep 02 21:26:49 keller-volumio volumio[799]: info: sendMpdCommand playlistinfo took 73 milliseconds
Sep 02 21:26:49 keller-volumio volumio[799]: info: sendMpdCommand playlistinfo took 71 milliseconds
Sep 02 21:26:49 keller-volumio volumio[799]: info: sendMpdCommand playlistinfo took 71 milliseconds
Sep 02 21:26:49 keller-volumio volumio[799]: verbose: ControllerMpd::parseTrackInfo
Sep 02 21:26:49 keller-volumio volumio[799]: verbose: ControllerMpd::parseTrackInfo
Sep 02 21:26:49 keller-volumio volumio[799]: verbose: ControllerMpd::parseTrackInfo
Sep 02 21:26:49 keller-volumio volumio[799]: info: ControllerMpd::pushState
Sep 02 21:26:49 keller-volumio volumio[799]: info: CoreCommandRouter::servicePushState
Sep 02 21:26:49 keller-volumio volumio[799]: info: CorePlayQueue::getTrack 0
Sep 02 21:26:49 keller-volumio volumio[799]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.mp3?aggregator=tunein","artist":null,"album":null,"uri":"http://wdr-wdr4-live.icecastssl.wdr.de/wdr/wdr4/live/mp3/128/stream.mp3?aggregator=tunein","trackType":""}
Sep 02 21:26:49 keller-volumio volumio[799]: verbose: CURRENT POSITION 0
Sep 02 21:26:49 keller-volumio volumio[799]: info: CoreStateMachine::syncState stateService play
Sep 02 21:26:49 keller-volumio volumio[799]: info: CoreStateMachine::syncState currentStatus play
Sep 02 21:26:49 keller-volumio volumio[799]: info: Received an update from plugin. extracting info from payload
Sep 02 21:26:49 keller-volumio volumio[799]: info: CoreStateMachine::pushState
Sep 02 21:26:49 keller-volumio volumio[799]: info: CorePlayQueue::getTrack 0
Sep 02 21:26:49 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 02 21:26:49 keller-volumio volumio[799]: info: CoreCommandRouter::volumioPushState
Sep 02 21:26:49 keller-volumio volumio[799]: info: CoreStateMachine::pushState
Sep 02 21:26:49 keller-volumio volumio[799]: info: CoreCommandRouter::volumioPushState
Sep 02 21:26:49 keller-volumio volumio[799]: info: ControllerMpd::pushState
Sep 02 21:26:49 keller-volumio volumio[799]: info: CoreCommandRouter::servicePushState
Sep 02 21:26:49 keller-volumio volumio[799]: info: CorePlayQueue::getTrack 0
Sep 02 21:26:49 keller-volumio volumio[799]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.mp3?aggregator=tunein","artist":null,"album":null,"uri":"http://wdr-wdr4-live.icecastssl.wdr.de/wdr/wdr4/live/mp3/128/stream.mp3?aggregator=tunein","trackType":""}
Sep 02 21:26:49 keller-volumio volumio[799]: verbose: CURRENT POSITION 0
Sep 02 21:26:49 keller-volumio volumio[799]: info: CoreStateMachine::syncState stateService play
Sep 02 21:26:49 keller-volumio volumio[799]: info: CoreStateMachine::syncState currentStatus play
Sep 02 21:26:49 keller-volumio volumio[799]: info: Received an update from plugin. extracting info from payload
Sep 02 21:26:49 keller-volumio volumio[799]: info: CoreStateMachine::pushState
Sep 02 21:26:49 keller-volumio volumio[799]: info: CorePlayQueue::getTrack 0
Sep 02 21:26:49 keller-volumio volumio[799]: info: CoreCommandRouter::volumioPushState
Sep 02 21:26:49 keller-volumio volumio[799]: info: CoreStateMachine::pushState
Sep 02 21:26:49 keller-volumio volumio[799]: info: CoreCommandRouter::volumioPushState
Sep 02 21:26:49 keller-volumio volumio[799]: info: ControllerMpd::pushState
Sep 02 21:26:49 keller-volumio volumio[799]: info: CoreCommandRouter::servicePushState
Sep 02 21:26:49 keller-volumio volumio[799]: info: CorePlayQueue::getTrack 0
Sep 02 21:26:49 keller-volumio volumio[799]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.mp3?aggregator=tunein","artist":null,"album":null,"uri":"http://wdr-wdr4-live.icecastssl.wdr.de/wdr/wdr4/live/mp3/128/stream.mp3?aggregator=tunein","trackType":""}
Sep 02 21:26:49 keller-volumio volumio[799]: verbose: CURRENT POSITION 0
Sep 02 21:26:49 keller-volumio volumio[799]: info: CoreStateMachine::syncState stateService play
Sep 02 21:26:49 keller-volumio volumio[799]: info: CoreStateMachine::syncState currentStatus play
Sep 02 21:26:49 keller-volumio volumio[799]: info: Received an update from plugin. extracting info from payload
Sep 02 21:26:49 keller-volumio volumio[799]: info: CoreStateMachine::pushState
Sep 02 21:26:49 keller-volumio volumio[799]: info: CorePlayQueue::getTrack 0
Sep 02 21:26:49 keller-volumio volumio[799]: info: CoreCommandRouter::volumioPushState
Sep 02 21:26:49 keller-volumio volumio[799]: info: CoreStateMachine::pushState
Sep 02 21:26:49 keller-volumio volumio[799]: info: CoreCommandRouter::volumioPushState
Sep 02 21:26:49 keller-volumio volumio[799]: info: ------------------------------ 201ms
Sep 02 21:26:49 keller-volumio volumio[799]: info: ------------------------------ 177ms
Sep 02 21:26:49 keller-volumio volumio[799]: info: ------------------------------ 176ms
Sep 02 21:26:49 keller-volumio volumio[799]: info:
Sep 02 21:26:49 keller-volumio volumio[799]: ---------------------------- MPD announces system playlist update
Sep 02 21:26:49 keller-volumio volumio[799]: info: Ignoring MPD Status Update
Sep 02 21:26:49 keller-volumio volumio[799]: info:
Sep 02 21:26:49 keller-volumio volumio[799]: ---------------------------- MPD announces state update: player
Sep 02 21:26:49 keller-volumio volumio[799]: info: ControllerMpd::getState
Sep 02 21:26:49 keller-volumio volumio[799]: verbose: ControllerMpd::sendMpdCommand status
Sep 02 21:26:49 keller-volumio volumio[799]: info:
Sep 02 21:26:49 keller-volumio volumio[799]: ---------------------------- MPD announces system playlist update
Sep 02 21:26:49 keller-volumio volumio[799]: info: Ignoring MPD Status Update
Sep 02 21:26:49 keller-volumio volumio[799]: info:
Sep 02 21:26:49 keller-volumio volumio[799]: ---------------------------- MPD announces state update: player
Sep 02 21:26:49 keller-volumio volumio[799]: info: ControllerMpd::getState
Sep 02 21:26:49 keller-volumio volumio[799]: verbose: ControllerMpd::sendMpdCommand status
Sep 02 21:26:49 keller-volumio volumio[799]: info:
Sep 02 21:26:49 keller-volumio volumio[799]: ---------------------------- MPD announces system playlist update
Sep 02 21:26:49 keller-volumio volumio[799]: info: Ignoring MPD Status Update
Sep 02 21:26:49 keller-volumio volumio[799]: info:
Sep 02 21:26:49 keller-volumio volumio[799]: ---------------------------- MPD announces state update: player
Sep 02 21:26:49 keller-volumio volumio[799]: info: ControllerMpd::getState
Sep 02 21:26:49 keller-volumio volumio[799]: verbose: ControllerMpd::sendMpdCommand status
Sep 02 21:26:49 keller-volumio volumio[799]: info:
Sep 02 21:26:49 keller-volumio volumio[799]: ---------------------------- MPD announces system playlist update
Sep 02 21:26:49 keller-volumio volumio[799]: info: Ignoring MPD Status Update
Sep 02 21:26:49 keller-volumio volumio[799]: info:
Sep 02 21:26:49 keller-volumio volumio[799]: ---------------------------- MPD announces state update: player
Sep 02 21:26:49 keller-volumio volumio[799]: info: ControllerMpd::getState
Sep 02 21:26:49 keller-volumio volumio[799]: verbose: ControllerMpd::sendMpdCommand status
Sep 02 21:26:49 keller-volumio volumio[799]: info: ------------------------------ 54ms
Sep 02 21:26:49 keller-volumio volumio[799]: info: sendMpdCommand status took 52 milliseconds
Sep 02 21:26:49 keller-volumio volumio[799]: info: ------------------------------ 49ms
Sep 02 21:26:49 keller-volumio volumio[799]: info: sendMpdCommand status took 38 milliseconds
Sep 02 21:26:49 keller-volumio volumio[799]: info: ------------------------------ 36ms
Sep 02 21:26:49 keller-volumio volumio[799]: info: sendMpdCommand status took 34 milliseconds
Sep 02 21:26:49 keller-volumio volumio[799]: verbose: ControllerMpd::parseState
Sep 02 21:26:49 keller-volumio volumio[799]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Sep 02 21:26:49 keller-volumio volumio[799]: verbose: ControllerMpd::parseState
Sep 02 21:26:49 keller-volumio volumio[799]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Sep 02 21:26:49 keller-volumio volumio[799]: verbose: ControllerMpd::parseState
Sep 02 21:26:49 keller-volumio volumio[799]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Sep 02 21:26:49 keller-volumio volumio[799]: info:
Sep 02 21:26:49 keller-volumio volumio[799]: ---------------------------- MPD announces system playlist update
Sep 02 21:26:49 keller-volumio volumio[799]: info: Ignoring MPD Status Update
Sep 02 21:26:49 keller-volumio volumio[799]: info:
Sep 02 21:26:49 keller-volumio volumio[799]: ---------------------------- MPD announces state update: player
Sep 02 21:26:49 keller-volumio volumio[799]: info: ControllerMpd::getState
Sep 02 21:26:49 keller-volumio volumio[799]: verbose: ControllerMpd::sendMpdCommand status
Sep 02 21:26:49 keller-volumio volumio[799]: info:
Sep 02 21:26:49 keller-volumio volumio[799]: ---------------------------- MPD announces system playlist update
Sep 02 21:26:49 keller-volumio volumio[799]: info: Ignoring MPD Status Update
Sep 02 21:26:49 keller-volumio volumio[799]: info:
Sep 02 21:26:49 keller-volumio volumio[799]: ---------------------------- MPD announces state update: player
Sep 02 21:26:49 keller-volumio volumio[799]: info: ControllerMpd::getState
Sep 02 21:26:49 keller-volumio volumio[799]: verbose: ControllerMpd::sendMpdCommand status
Sep 02 21:26:49 keller-volumio volumio[799]: info: ------------------------------ 33ms
Sep 02 21:26:49 keller-volumio volumio[799]: info: sendMpdCommand status took 28 milliseconds
Sep 02 21:26:49 keller-volumio volumio[799]: info: sendMpdCommand playlistinfo took 23 milliseconds
Sep 02 21:26:49 keller-volumio volumio[799]: info: sendMpdCommand playlistinfo took 22 milliseconds
Sep 02 21:26:49 keller-volumio volumio[799]: info: sendMpdCommand playlistinfo took 22 milliseconds
Sep 02 21:26:49 keller-volumio volumio[799]: info: ------------------------------ 20ms
Sep 02 21:26:49 keller-volumio volumio[799]: info: sendMpdCommand status took 17 milliseconds
Sep 02 21:26:49 keller-volumio volumio[799]: info: ------------------------------ 16ms
Sep 02 21:26:49 keller-volumio volumio[799]: info: sendMpdCommand status took 13 milliseconds
Sep 02 21:26:49 keller-volumio volumio[799]: verbose: ControllerMpd::parseState
Sep 02 21:26:49 keller-volumio volumio[799]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Sep 02 21:26:49 keller-volumio volumio[799]: verbose: ControllerMpd::parseTrackInfo
Sep 02 21:26:49 keller-volumio volumio[799]: verbose: ControllerMpd::parseTrackInfo
Sep 02 21:26:49 keller-volumio volumio[799]: verbose: ControllerMpd::parseTrackInfo
Sep 02 21:26:49 keller-volumio volumio[799]: verbose: ControllerMpd::parseState
Sep 02 21:26:49 keller-volumio volumio[799]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Sep 02 21:26:49 keller-volumio volumio[799]: verbose: ControllerMpd::parseState
Sep 02 21:26:49 keller-volumio volumio[799]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Sep 02 21:26:49 keller-volumio volumio[799]: info: ControllerMpd::pushState
Sep 02 21:26:49 keller-volumio volumio[799]: info: CoreCommandRouter::servicePushState
Sep 02 21:26:49 keller-volumio volumio[799]: info: CorePlayQueue::getTrack 0
Sep 02 21:26:49 keller-volumio volumio[799]: verbose: STATE SERVICE {"status":"play","position":0,"seek":980,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"WDR 4 - Wir sind Heimat","artist":"WDR 4, Westdeutscher Rundfunk Koeln","album":null,"uri":"http://wdr-wdr4-live.icecastssl.wdr.de/wdr/wdr4/live/mp3/128/stream.mp3?aggregator=tunein","trackType":""}
Sep 02 21:26:49 keller-volumio volumio[799]: verbose: CURRENT POSITION 0
Sep 02 21:26:49 keller-volumio volumio[799]: info: CoreStateMachine::syncState stateService play
Sep 02 21:26:49 keller-volumio volumio[799]: info: CoreStateMachine::syncState currentStatus play
Sep 02 21:26:49 keller-volumio volumio[799]: info: Received an update from plugin. extracting info from payload
Sep 02 21:26:49 keller-volumio volumio[799]: info: CoreStateMachine::pushState
Sep 02 21:26:49 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 02 21:26:49 keller-volumio volumio[799]: info: CoreCommandRouter::volumioPushState
Sep 02 21:26:49 keller-volumio volumio[799]: info: CoreStateMachine::pushState
Sep 02 21:26:49 keller-volumio volumio[799]: info: CoreCommandRouter::volumioPushState
Sep 02 21:26:49 keller-volumio volumio[799]: info: ControllerMpd::pushState
Sep 02 21:26:49 keller-volumio volumio[799]: info: CoreCommandRouter::servicePushState
Sep 02 21:26:49 keller-volumio volumio[799]: info: CorePlayQueue::getTrack 0
Sep 02 21:26:49 keller-volumio volumio[799]: verbose: STATE SERVICE {"status":"play","position":0,"seek":980,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"WDR 4 - Wir sind Heimat","artist":"WDR 4, Westdeutscher Rundfunk Koeln","album":null,"uri":"http://wdr-wdr4-live.icecastssl.wdr.de/wdr/wdr4/live/mp3/128/stream.mp3?aggregator=tunein","trackType":""}
Sep 02 21:26:49 keller-volumio volumio[799]: verbose: CURRENT POSITION 0
Sep 02 21:26:49 keller-volumio volumio[799]: info: CoreStateMachine::syncState stateService play
Sep 02 21:26:49 keller-volumio volumio[799]: info: CoreStateMachine::syncState currentStatus play
Sep 02 21:26:49 keller-volumio volumio[799]: info: Received an update from plugin. extracting info from payload
Sep 02 21:26:49 keller-volumio volumio[799]: info: CoreStateMachine::pushState
Sep 02 21:26:49 keller-volumio volumio[799]: info: CoreCommandRouter::volumioPushState
Sep 02 21:26:49 keller-volumio volumio[799]: info: CoreStateMachine::pushState
Sep 02 21:26:49 keller-volumio volumio[799]: info: CoreCommandRouter::volumioPushState
Sep 02 21:26:49 keller-volumio volumio[799]: info: ControllerMpd::pushState
Sep 02 21:26:49 keller-volumio volumio[799]: info: CoreCommandRouter::servicePushState
Sep 02 21:26:49 keller-volumio volumio[799]: info: CorePlayQueue::getTrack 0
Sep 02 21:26:49 keller-volumio volumio[799]: verbose: STATE SERVICE {"status":"play","position":0,"seek":980,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"WDR 4 - Wir sind Heimat","artist":"WDR 4, Westdeutscher Rundfunk Koeln","album":null,"uri":"http://wdr-wdr4-live.icecastssl.wdr.de/wdr/wdr4/live/mp3/128/stream.mp3?aggregator=tunein","trackType":""}
Sep 02 21:26:49 keller-volumio volumio[799]: verbose: CURRENT POSITION 0
Sep 02 21:26:49 keller-volumio volumio[799]: info: CoreStateMachine::syncState stateService play
Sep 02 21:26:49 keller-volumio volumio[799]: info: CoreStateMachine::syncState currentStatus play
Sep 02 21:26:49 keller-volumio volumio[799]: info: Received an update from plugin. extracting info from payload
Sep 02 21:26:49 keller-volumio volumio[799]: info: CoreStateMachine::pushState
Sep 02 21:26:49 keller-volumio volumio[799]: info: CoreCommandRouter::volumioPushState
Sep 02 21:26:49 keller-volumio volumio[799]: info: CoreStateMachine::pushState
Sep 02 21:26:49 keller-volumio volumio[799]: info: CoreCommandRouter::volumioPushState
Sep 02 21:26:49 keller-volumio volumio[799]: info: ------------------------------ 175ms
Sep 02 21:26:49 keller-volumio volumio[799]: info: ------------------------------ 164ms
Sep 02 21:26:49 keller-volumio volumio[799]: info: ------------------------------ 160ms
Sep 02 21:26:49 keller-volumio volumio[799]: info: sendMpdCommand playlistinfo took 163 milliseconds
Sep 02 21:26:49 keller-volumio volumio[799]: info: sendMpdCommand playlistinfo took 160 milliseconds
Sep 02 21:26:49 keller-volumio volumio[799]: info: sendMpdCommand playlistinfo took 159 milliseconds
Sep 02 21:26:49 keller-volumio volumio[799]: verbose: ControllerMpd::parseTrackInfo
Sep 02 21:26:49 keller-volumio volumio[799]: verbose: ControllerMpd::parseTrackInfo
Sep 02 21:26:49 keller-volumio volumio[799]: verbose: ControllerMpd::parseTrackInfo
Sep 02 21:26:49 keller-volumio volumio[799]: info: ControllerMpd::pushState
Sep 02 21:26:49 keller-volumio volumio[799]: info: CoreCommandRouter::servicePushState
Sep 02 21:26:49 keller-volumio volumio[799]: info: CorePlayQueue::getTrack 0
Sep 02 21:26:49 keller-volumio volumio[799]: verbose: STATE SERVICE {"status":"play","position":0,"seek":980,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"WDR 4 - Wir sind Heimat","artist":"WDR 4, Westdeutscher Rundfunk Koeln","album":null,"uri":"http://wdr-wdr4-live.icecastssl.wdr.de/wdr/wdr4/live/mp3/128/stream.mp3?aggregator=tunein","trackType":""}
Sep 02 21:26:49 keller-volumio volumio[799]: verbose: CURRENT POSITION 0
Sep 02 21:26:49 keller-volumio volumio[799]: info: CoreStateMachine::syncState stateService play
Sep 02 21:26:49 keller-volumio volumio[799]: info: CoreStateMachine::syncState currentStatus play
Sep 02 21:26:49 keller-volumio volumio[799]: info: Received an update from plugin. extracting info from payload
Sep 02 21:26:49 keller-volumio volumio[799]: info: CoreStateMachine::pushState
Sep 02 21:26:49 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 02 21:26:49 keller-volumio volumio[799]: info: CoreCommandRouter::volumioPushState
Sep 02 21:26:49 keller-volumio volumio[799]: info: CoreStateMachine::pushState
Sep 02 21:26:49 keller-volumio volumio[799]: info: CoreCommandRouter::volumioPushState
Sep 02 21:26:49 keller-volumio volumio[799]: info: ControllerMpd::pushState
Sep 02 21:26:49 keller-volumio volumio[799]: info: CoreCommandRouter::servicePushState
Sep 02 21:26:49 keller-volumio volumio[799]: info: CorePlayQueue::getTrack 0
Sep 02 21:26:49 keller-volumio volumio[799]: verbose: STATE SERVICE {"status":"play","position":0,"seek":980,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"WDR 4 - Wir sind Heimat","artist":"WDR 4, Westdeutscher Rundfunk Koeln","album":null,"uri":"http://wdr-wdr4-live.icecastssl.wdr.de/wdr/wdr4/live/mp3/128/stream.mp3?aggregator=tunein","trackType":""}
Sep 02 21:26:49 keller-volumio volumio[799]: verbose: CURRENT POSITION 0
Sep 02 21:26:49 keller-volumio volumio[799]: info: CoreStateMachine::syncState stateService play
Sep 02 21:26:49 keller-volumio volumio[799]: info: CoreStateMachine::syncState currentStatus play
Sep 02 21:26:49 keller-volumio volumio[799]: info: Received an update from plugin. extracting info from payload
Sep 02 21:26:49 keller-volumio volumio[799]: info: CoreStateMachine::pushState
Sep 02 21:26:49 keller-volumio volumio[799]: info: CoreCommandRouter::volumioPushState
Sep 02 21:26:49 keller-volumio volumio[799]: info: CoreStateMachine::pushState
Sep 02 21:26:49 keller-volumio volumio[799]: info: CoreCommandRouter::volumioPushState
Sep 02 21:26:49 keller-volumio volumio[799]: info: ControllerMpd::pushState
Sep 02 21:26:49 keller-volumio volumio[799]: info: CoreCommandRouter::servicePushState
Sep 02 21:26:49 keller-volumio volumio[799]: info: CorePlayQueue::getTrack 0
Sep 02 21:26:49 keller-volumio volumio[799]: verbose: STATE SERVICE {"status":"play","position":0,"seek":980,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"WDR 4 - Wir sind Heimat","artist":"WDR 4, Westdeutscher Rundfunk Koeln","album":null,"uri":"http://wdr-wdr4-live.icecastssl.wdr.de/wdr/wdr4/live/mp3/128/stream.mp3?aggregator=tunein","trackType":""}
Sep 02 21:26:49 keller-volumio volumio[799]: verbose: CURRENT POSITION 0
Sep 02 21:26:49 keller-volumio volumio[799]: info: CoreStateMachine::syncState stateService play
Sep 02 21:26:49 keller-volumio volumio[799]: info: CoreStateMachine::syncState currentStatus play
Sep 02 21:26:49 keller-volumio volumio[799]: info: Received an update from plugin. extracting info from payload
Sep 02 21:26:49 keller-volumio volumio[799]: info: CoreStateMachine::pushState
Sep 02 21:26:49 keller-volumio volumio[799]: info: CoreCommandRouter::volumioPushState
Sep 02 21:26:49 keller-volumio volumio[799]: info: CoreStateMachine::pushState
Sep 02 21:26:49 keller-volumio volumio[799]: info: CoreCommandRouter::volumioPushState
Sep 02 21:26:49 keller-volumio volumio[799]: info: ------------------------------ 283ms
Sep 02 21:26:49 keller-volumio volumio[799]: info: ------------------------------ 269ms
Sep 02 21:26:49 keller-volumio volumio[799]: info: ------------------------------ 265ms
Sep 02 21:26:51 keller-volumio volumio[799]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium
Sep 02 21:26:51 keller-volumio volumio[799]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium
Sep 02 21:26:51 keller-volumio volumio[799]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium
Sep 02 21:26:51 keller-volumio volumio[799]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium
Sep 02 21:26:51 keller-volumio volumio[799]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium
Sep 02 21:26:51 keller-volumio volumio[799]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium
Sep 02 21:26:51 keller-volumio volumio[799]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium
Sep 02 21:26:51 keller-volumio volumio[799]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium
Sep 02 21:26:51 keller-volumio volumio[799]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium
Sep 02 21:26:51 keller-volumio volumio[799]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium
Sep 02 21:26:51 keller-volumio volumio[799]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar
Sep 02 21:26:51 keller-volumio volumio[799]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar
Sep 02 21:26:51 keller-volumio volumio[799]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar
Sep 02 21:26:51 keller-volumio volumio[799]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar
Sep 02 21:26:51 keller-volumio volumio[799]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar
Sep 02 21:26:51 keller-volumio volumio[799]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar
Sep 02 21:26:51 keller-volumio volumio[799]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar
Sep 02 21:26:51 keller-volumio volumio[799]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar
Sep 02 21:26:51 keller-volumio volumio[799]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar
Sep 02 21:26:51 keller-volumio volumio[799]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar
Sep 02 21:26:51 keller-volumio volumio[799]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso
Sep 02 21:26:51 keller-volumio volumio[799]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso
Sep 02 21:26:51 keller-volumio volumio[799]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso
Sep 02 21:26:51 keller-volumio volumio[799]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso
Sep 02 21:26:51 keller-volumio volumio[799]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso
Sep 02 21:26:51 keller-volumio volumio[799]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso
Sep 02 21:26:51 keller-volumio volumio[799]: info: Adding plugin bluetooth to MyMusic Plugins
Sep 02 21:26:51 keller-volumio volumio[799]: info: Adding plugin multiroom to MyMusic Plugins
Sep 02 21:26:51 keller-volumio volumio[799]: info: Adding plugin metavolumio to MyMusic Plugins
Sep 02 21:26:51 keller-volumio volumio[799]: info: Adding plugin cd_controller to MyMusic Plugins
Sep 02 21:26:51 keller-volumio volumio[799]: info: Adding plugin smart_inputs to MyMusic Plugins
Sep 02 21:26:51 keller-volumio volumio[799]: info: Adding plugin tidalconnect to MyMusic Plugins
Sep 02 21:26:51 keller-volumio volumio[799]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"...
Sep 02 21:26:53 keller-volumio volumio[799]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded
Sep 02 21:26:53 keller-volumio volumio[799]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio
Sep 02 21:26:53 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Sep 02 21:26:53 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Sep 02 21:26:53 keller-volumio volumio[799]: info: Starting MyVolumio Remote Streaming Endpoints
Sep 02 21:26:54 keller-volumio volumio[799]: info: MyVolumio login type: Token
Sep 02 21:26:54 keller-volumio volumio[799]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started
Sep 02 21:26:54 keller-volumio volumio[799]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"...
Sep 02 21:26:56 keller-volumio volumio[799]: info: Starting Streaming Service Transparent Proxy
Sep 02 21:26:56 keller-volumio volumio[799]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded
Sep 02 21:26:56 keller-volumio volumio[799]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services
Sep 02 21:26:56 keller-volumio volumio[799]: info: Streaming services startup
Sep 02 21:26:56 keller-volumio volumio[799]: info: Starting Streaming Daemon
Sep 02 21:26:56 keller-volumio sudo[1049]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Sep 02 21:26:56 keller-volumio sudo[1049]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 02 21:26:56 keller-volumio volumio[799]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started
Sep 02 21:26:56 keller-volumio sudo[1049]: pam_unix(sudo:session): session closed for user root
Sep 02 21:26:57 keller-volumio volumio[799]: error: Cannot start Volumio Streaming Daemon
Sep 02 21:26:57 keller-volumio volumio[799]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Sep 02 21:26:57 keller-volumio volumio[799]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Sep 02 21:26:57 keller-volumio volumio[799]: info: CoreCommandRouter::volumioGetState
Sep 02 21:26:57 keller-volumio volumio[799]: info: CoreCommandRouter::volumioGetState
Sep 02 21:26:57 keller-volumio volumio[799]: info: Listing playlists
Sep 02 21:26:57 keller-volumio volumio[799]: info: Listing playlists
Sep 02 21:26:57 keller-volumio volumio[799]: info: Listing playlists
Sep 02 21:26:57 keller-volumio volumio[799]: info: Listing playlists
Sep 02 21:26:57 keller-volumio volumio[799]: error: MyVolumio Custom Token format not valid, refreshing it
Sep 02 21:26:58 keller-volumio volumio[799]: info: MyVolumio login type: Token
Sep 02 21:26:58 keller-volumio volumio[799]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN
Sep 02 21:26:59 keller-volumio volumio[799]: STREAMING PROXY: Starting server on port 3245
Sep 02 21:26:59 keller-volumio volumio[799]: Node JS runtime: 14
Sep 02 21:26:59 keller-volumio volumio[799]: info: MyVolumio token set successfully
Sep 02 21:26:59 keller-volumio volumio[799]: info: MYVOLUMIO: Adding device
Sep 02 21:26:59 keller-volumio volumio[799]: info: MYVOLUMIO: Evaluating Server
Sep 02 21:26:59 keller-volumio volumio[799]: info: MyVolumio Plan changed: superstar
Sep 02 21:26:59 keller-volumio volumio[799]: info: [MyVolumio PluginManager] Subscribed plan changed to superstar
Sep 02 21:26:59 keller-volumio volumio[799]: info: Adding audio output:
Sep 02 21:26:59 keller-volumio volumio[799]: info: MYVOLUMIO: Adding device
Sep 02 21:26:59 keller-volumio volumio[799]: info: MYVOLUMIO: Evaluating Server
Sep 02 21:26:59 keller-volumio volumio[799]: info: Remote config written successfully
Sep 02 21:26:59 keller-volumio volumio[799]: info: Starting Tunnel 1
Sep 02 21:26:59 keller-volumio volumio[799]: info: Starting Tunnel Connection Checker
Sep 02 21:26:59 keller-volumio volumio[799]: info: Completed starting MyVolumio Plugin
Sep 02 21:26:59 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Sep 02 21:26:59 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken
Sep 02 21:27:00 keller-volumio volumio[799]: info: MYVolumio Device enabled
Sep 02 21:27:00 keller-volumio volumio[799]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins...
Sep 02 21:27:00 keller-volumio volumio[799]: info: MyVolumio status changed
Sep 02 21:27:00 keller-volumio volumio[799]: info: Streaming services startup
Sep 02 21:27:00 keller-volumio volumio[799]: info: Starting Streaming Daemon
Sep 02 21:27:00 keller-volumio volumio[799]: info: Setting Geolocation for MyVolumio to eu4
Sep 02 21:27:00 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Sep 02 21:27:00 keller-volumio sudo[1089]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Sep 02 21:27:00 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Sep 02 21:27:00 keller-volumio sudo[1089]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 02 21:27:00 keller-volumio sudo[1089]: pam_unix(sudo:session): session closed for user root
Sep 02 21:27:00 keller-volumio volumio[799]: error: Cannot start Volumio Streaming Daemon
Sep 02 21:27:00 keller-volumio volumio[799]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Sep 02 21:27:00 keller-volumio volumio[799]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Sep 02 21:27:00 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid
Sep 02 21:27:00 keller-volumio volumio[799]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"...
Sep 02 21:27:01 keller-volumio volumio[799]: info: [1725305221000] Starting BluetoothController
Sep 02 21:27:01 keller-volumio volumio[799]: info: [MyVolumio PluginManager] Loading plugin "multiroom"...
Sep 02 21:27:04 keller-volumio volumio[799]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom
Sep 02 21:27:04 keller-volumio sudo[1092]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom
Sep 02 21:27:04 keller-volumio sudo[1092]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 02 21:27:04 keller-volumio sudo[1092]: pam_unix(sudo:session): session closed for user root
Sep 02 21:27:04 keller-volumio volumio[799]: info: MRS: MultiRoom plugin initialized
Sep 02 21:27:04 keller-volumio volumio[799]: info: MRS: STOPPING SNAPCLIENT
Sep 02 21:27:04 keller-volumio volumio[799]: info: MRS: Snap server stop
Sep 02 21:27:04 keller-volumio sudo[1108]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient
Sep 02 21:27:04 keller-volumio volumio[799]: info: MRS: STOPPING volumioStreaming
Sep 02 21:27:04 keller-volumio sudo[1108]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 02 21:27:04 keller-volumio sudo[1110]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver
Sep 02 21:27:04 keller-volumio sudo[1110]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 02 21:27:04 keller-volumio volumio[799]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"...
Sep 02 21:27:04 keller-volumio sudo[1113]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming
Sep 02 21:27:04 keller-volumio sudo[1113]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 02 21:27:04 keller-volumio sudo[1117]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/*
Sep 02 21:27:04 keller-volumio sudo[1117]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 02 21:27:04 keller-volumio sudo[1108]: pam_unix(sudo:session): session closed for user root
Sep 02 21:27:04 keller-volumio sudo[1117]: pam_unix(sudo:session): session closed for user root
Sep 02 21:27:04 keller-volumio sudo[1110]: pam_unix(sudo:session): session closed for user root
Sep 02 21:27:04 keller-volumio sudo[1113]: pam_unix(sudo:session): session closed for user root
Sep 02 21:27:05 keller-volumio volumio[799]: info: [MyVolumio PluginManager] Loading plugin "manifestui"...
Sep 02 21:27:05 keller-volumio volumio[799]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"...
Sep 02 21:27:05 keller-volumio volumio[799]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"...
Sep 02 21:27:05 keller-volumio volumio[799]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"...
Sep 02 21:27:05 keller-volumio volumio[799]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio
Sep 02 21:27:05 keller-volumio volumio[799]: info: [MyVolumio PluginManager] Loading plugin "tidal"...
Sep 02 21:27:05 keller-volumio volumio[799]: info: [MyVolumio PluginManager] Loading plugin "qobuz"...
Sep 02 21:27:05 keller-volumio volumio[799]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"...
Sep 02 21:27:05 keller-volumio volumio[799]: info: Preparing to generate the ALSA configuration file
Sep 02 21:27:05 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Sep 02 21:27:05 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Sep 02 21:27:05 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Sep 02 21:27:05 keller-volumio volumio[799]: info: Updating MyVolumio device info
Sep 02 21:27:05 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Sep 02 21:27:05 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Sep 02 21:27:05 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Sep 02 21:27:05 keller-volumio volumio[799]: info: Discovery: Disconnected from remote: 192.168.2.98
Sep 02 21:27:05 keller-volumio volumio[799]: info: MRS: Pushing multiroomSync output for this device
Sep 02 21:27:05 keller-volumio volumio[799]: info: MRS: Pushing multiroomSync output
Sep 02 21:27:05 keller-volumio volumio[799]: info: Adding audio output:
Sep 02 21:27:05 keller-volumio volumio[799]: info: Adding audio output:
Sep 02 21:27:05 keller-volumio volumio-remote-updater[476]: No test mode
Sep 02 21:27:05 keller-volumio volumio-remote-updater[476]: No alpha test mode
Sep 02 21:27:05 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
Sep 02 21:27:05 keller-volumio volumio[799]: info: Setting Geolocation for MyVolumio to eu7
Sep 02 21:27:05 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Sep 02 21:27:05 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Sep 02 21:27:05 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Sep 02 21:27:05 keller-volumio volumio[799]: info: MRS: Adding multiroomSync output
Sep 02 21:27:05 keller-volumio volumio[799]: info: Adding audio output:
Sep 02 21:27:05 keller-volumio volumio[799]: info: MRS: Pushing multiroomSync output
Sep 02 21:27:05 keller-volumio volumio[799]: info: MRS: Removed streaming files
Sep 02 21:27:05 keller-volumio volumio[799]: info: MRS: volumioStreaming STOPPED
Sep 02 21:27:05 keller-volumio volumio[799]: info: MRS: SNAPSERVER STOPPED
Sep 02 21:27:05 keller-volumio volumio[799]: info: MRS: SNAPCLIENT STOPPED
Sep 02 21:27:06 keller-volumio volumio[799]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
- Improved translations
- Fix for controlling Volumio device from another one
- Fix for slow playback on RPi5 with HifiBerry DAC
- Improved speed for default albumart
- Fix for HifiBerry DAC detection
- Fix for Supersearch bar on Iphone
- Fix for Network connection on Wizard
NEW ADDITIONS
- Facility for third party UIs
","title":"Update v3.742","updateavailable":true}
Sep 02 21:27:06 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Sep 02 21:27:06 keller-volumio volumio[799]: info: CoreCommandRouter::volumioGetState
Sep 02 21:27:06 keller-volumio volumio[799]: info: CoreCommandRouter::volumioGetState
Sep 02 21:27:06 keller-volumio volumio[799]: info: Asound.conf file unchanged, so no further update is needed
Sep 02 21:27:06 keller-volumio volumio[799]: info: Output device has changed, restarting MPD
Sep 02 21:27:06 keller-volumio volumio[799]: [SpotifyConnect] Creating VLS config file
Sep 02 21:27:06 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 02 21:27:06 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 02 21:27:06 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 02 21:27:06 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 02 21:27:06 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 02 21:27:06 keller-volumio sudo[1123]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Sep 02 21:27:06 keller-volumio volumio[799]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration
Sep 02 21:27:06 keller-volumio sudo[1123]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 02 21:27:06 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 02 21:27:06 keller-volumio sudo[1123]: pam_unix(sudo:session): session closed for user root
Sep 02 21:27:06 keller-volumio sudo[1125]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Sep 02 21:27:06 keller-volumio sudo[1125]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 02 21:27:06 keller-volumio volumio[799]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth
Sep 02 21:27:06 keller-volumio volumio[799]: info: [MyVolumio PluginManager] Plugin bluetooth disabled by user. Not starting
Sep 02 21:27:06 keller-volumio volumio[799]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom
Sep 02 21:27:06 keller-volumio volumio[799]: info: [MyVolumio PluginManager] Plugin multiroom disabled by user. Not starting
Sep 02 21:27:06 keller-volumio volumio[799]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio
Sep 02 21:27:06 keller-volumio volumio[799]: info: Adding METAVOLUMIO REST API Endpoints
Sep 02 21:27:06 keller-volumio volumio[799]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio
Sep 02 21:27:06 keller-volumio volumio[799]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio
Sep 02 21:27:06 keller-volumio volumio[799]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio
Sep 02 21:27:06 keller-volumio volumio[799]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio
Sep 02 21:27:06 keller-volumio volumio[799]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui
Sep 02 21:27:06 keller-volumio volumio[799]: info: Adding Manifest REST API Endpoints
Sep 02 21:27:06 keller-volumio volumio[799]: info: Adding manifestui REST Endpoint for plugin: miscellanea/manifestui
Sep 02 21:27:06 keller-volumio volumio[799]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller
Sep 02 21:27:06 keller-volumio volumio[799]: info: [MyVolumio PluginManager] Plugin cd_controller disabled by user. Not starting
Sep 02 21:27:06 keller-volumio volumio[799]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs
Sep 02 21:27:06 keller-volumio volumio[799]: info: [MyVolumio PluginManager] Plugin smart_inputs disabled by user. Not starting
Sep 02 21:27:06 keller-volumio volumio[799]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio
Sep 02 21:27:06 keller-volumio systemd[1]: Stopping Music Player Daemon...
Sep 02 21:27:06 keller-volumio volumio[799]: error: Hi Res Audio Failed Login: Missing Login Data
Sep 02 21:27:06 keller-volumio volumio[799]: info: Adding HIGHRESAUDIO REST API Endpoints
Sep 02 21:27:06 keller-volumio volumio[799]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio
Sep 02 21:27:06 keller-volumio volumio[799]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal
Sep 02 21:27:06 keller-volumio volumio[799]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz
Sep 02 21:27:06 keller-volumio volumio[799]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect
Sep 02 21:27:06 keller-volumio volumio[799]: info: [MyVolumio PluginManager] Plugin tidalconnect disabled by user. Not starting
Sep 02 21:27:06 keller-volumio volumio[799]: info: Stopping AccessToken refresher cron
Sep 02 21:27:06 keller-volumio volumio[799]: info: AccessToken refresher cron started
Sep 02 21:27:06 keller-volumio volumio[799]: info: Stopping AccessToken refresher cron for QOBUZ
Sep 02 21:27:06 keller-volumio volumio[799]: info: AccessToken refresher cron started for QOBUZ
Sep 02 21:27:06 keller-volumio volumio[799]: info: Adding TIDAL REST API Endpoints
Sep 02 21:27:06 keller-volumio volumio[799]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal
Sep 02 21:27:06 keller-volumio volumio[799]: info: Adding QOBUZ REST API Endpoints
Sep 02 21:27:06 keller-volumio volumio[799]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz
Sep 02 21:27:06 keller-volumio volumio[799]: info: BOOT COMPLETED
Sep 02 21:27:06 keller-volumio volumio[799]: [Metrics] CommandRouter: 55s 675.57ms
Sep 02 21:27:06 keller-volumio volumio[799]: info: CoreCommandRouter::volumiosetStartupVolume
Sep 02 21:27:06 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 02 21:27:06 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Sep 02 21:27:06 keller-volumio systemd[1]: mpd.service: Succeeded.
Sep 02 21:27:06 keller-volumio systemd[1]: Stopped Music Player Daemon.
Sep 02 21:27:06 keller-volumio volumio[799]: info: CoreCommandRouter::Close All Modals sent
Sep 02 21:27:06 keller-volumio volumio[799]: [SpotifyConnect] Restarting Vollibrespot Daemon
Sep 02 21:27:06 keller-volumio systemd[1]: Starting Music Player Daemon...
Sep 02 21:27:06 keller-volumio sudo[1134]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect.service
Sep 02 21:27:06 keller-volumio sudo[1134]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 02 21:27:06 keller-volumio systemd[1]: Stopping Volspotconnect2 Daemon...
Sep 02 21:27:06 keller-volumio systemd[1]: volspotconnect.service: Main process exited, code=killed, status=15/TERM
Sep 02 21:27:06 keller-volumio systemd[1]: volspotconnect.service: Succeeded.
Sep 02 21:27:06 keller-volumio systemd[1]: Stopped Volspotconnect2 Daemon.
Sep 02 21:27:06 keller-volumio systemd[1]: Started Volspotconnect2 Daemon.
Sep 02 21:27:06 keller-volumio sudo[1134]: pam_unix(sudo:session): session closed for user root
Sep 02 21:27:06 keller-volumio volumio[1139]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31
Sep 02 21:27:06 keller-volumio volumio[1139]: Reading Config from "/tmp/volspotify.toml"
Sep 02 21:27:06 keller-volumio volumio[1139]: Malformed config key: missing field `Authentication`
Sep 02 21:27:06 keller-volumio systemd[1]: volspotconnect.service: Main process exited, code=exited, status=1/FAILURE
Sep 02 21:27:06 keller-volumio systemd[1]: volspotconnect.service: Failed with result 'exit-code'.
Sep 02 21:27:06 keller-volumio volumio[799]: [SpotifyConnect] Vollibrespot Daemon service restarted!
Sep 02 21:27:06 keller-volumio sudo[1131]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Sep 02 21:27:06 keller-volumio sudo[1131]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 02 21:27:06 keller-volumio volumio[799]: info: MPD Permissions set
Sep 02 21:27:06 keller-volumio volumio[799]: info: Updating MyVolumio device info
Sep 02 21:27:06 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Sep 02 21:27:06 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Sep 02 21:27:06 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Sep 02 21:27:06 keller-volumio sudo[1131]: pam_unix(sudo:session): session closed for user root
Sep 02 21:27:06 keller-volumio sudo[1143]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service
Sep 02 21:27:06 keller-volumio sudo[1143]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 02 21:27:07 keller-volumio volumio[799]: verbose: New Socket.io Connection to 192.168.2.56:3000 from 192.168.2.54 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7
Sep 02 21:27:07 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 02 21:27:07 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 02 21:27:07 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 02 21:27:07 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 02 21:27:07 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 02 21:27:07 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 02 21:27:07 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 02 21:27:07 keller-volumio systemd[1]: Started MyVolumio SSH Tunnel.
Sep 02 21:27:07 keller-volumio sudo[1143]: pam_unix(sudo:session): session closed for user root
Sep 02 21:27:07 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Sep 02 21:27:07 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 02 21:27:07 keller-volumio volumio[799]: info: MRS: Adding multiroomSync output
Sep 02 21:27:07 keller-volumio volumio[799]: info: Adding audio output:
Sep 02 21:27:07 keller-volumio autossh[1149]: port set to 0, monitoring disabled
Sep 02 21:27:07 keller-volumio autossh[1149]: starting ssh (count 1)
Sep 02 21:27:07 keller-volumio autossh[1149]: ssh child pid is 1155
Sep 02 21:27:07 keller-volumio volumio[799]: info: MRS: Pushing multiroomSync output
Sep 02 21:27:07 keller-volumio volumio[799]: info: Successfully Updated MyVolumio device
Sep 02 21:27:07 keller-volumio volumio[799]: info: Successfully Added MyVolumio device
Sep 02 21:27:07 keller-volumio volumio[799]: info: Remote SSH Started
Sep 02 21:27:07 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
Sep 02 21:27:07 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Sep 02 21:27:07 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
Sep 02 21:27:07 keller-volumio volumio[799]: info: Successfully Added MyVolumio device
Sep 02 21:27:07 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket
Sep 02 21:27:07 keller-volumio volumio[799]: info: MRS: Pushing audio output on request
Sep 02 21:27:07 keller-volumio volumio[799]: info: MRS: Pushing multiroomSync output
Sep 02 21:27:07 keller-volumio volumio[799]: info: Discovery: Connected to remote: 192.168.2.98
Sep 02 21:27:07 keller-volumio volumio[799]: info: Preparing to generate the ALSA configuration file
Sep 02 21:27:08 keller-volumio volumio[799]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8
Sep 02 21:27:08 keller-volumio volumio[799]: info: CoreCommandRouter::volumioGetState
Sep 02 21:27:08 keller-volumio volumio[799]: info: Successfully Updated MyVolumio device
Sep 02 21:27:08 keller-volumio volumio[799]: info: Asound.conf file unchanged, so no further update is needed
Sep 02 21:27:08 keller-volumio volumio[799]: info: Output device has changed, restarting MPD
Sep 02 21:27:08 keller-volumio volumio[799]: [SpotifyConnect] Creating VLS config file
Sep 02 21:27:08 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 02 21:27:08 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 02 21:27:08 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 02 21:27:08 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 02 21:27:08 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 02 21:27:08 keller-volumio volumio[799]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration
Sep 02 21:27:08 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 02 21:27:08 keller-volumio sudo[1157]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Sep 02 21:27:08 keller-volumio sudo[1157]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 02 21:27:08 keller-volumio sudo[1157]: pam_unix(sudo:session): session closed for user root
Sep 02 21:27:08 keller-volumio sudo[1159]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Sep 02 21:27:08 keller-volumio sudo[1159]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 02 21:27:08 keller-volumio volumio[799]: [SpotifyConnect] Restarting Vollibrespot Daemon
Sep 02 21:27:08 keller-volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM
Sep 02 21:27:08 keller-volumio systemd[1]: mpd.service: Succeeded.
Sep 02 21:27:08 keller-volumio systemd[1]: Stopped Music Player Daemon.
Sep 02 21:27:08 keller-volumio sudo[1165]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect.service
Sep 02 21:27:08 keller-volumio sudo[1165]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 02 21:27:08 keller-volumio volumio[799]: info: CoreCommandRouter::volumioGetState
Sep 02 21:27:08 keller-volumio systemd[1]: Starting Music Player Daemon...
Sep 02 21:27:08 keller-volumio systemd[1]: Stopped Volspotconnect2 Daemon.
Sep 02 21:27:08 keller-volumio systemd[1]: Started Volspotconnect2 Daemon.
Sep 02 21:27:08 keller-volumio volumio[799]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined
Sep 02 21:27:08 keller-volumio volumio[799]: info: MPD Permissions set
Sep 02 21:27:08 keller-volumio sudo[1165]: pam_unix(sudo:session): session closed for user root
Sep 02 21:27:08 keller-volumio volumio[1168]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31
Sep 02 21:27:08 keller-volumio volumio[1168]: Reading Config from "/tmp/volspotify.toml"
Sep 02 21:27:08 keller-volumio volumio[1168]: [Vollibrespot] : Using Alsa backend with device: volumio
Sep 02 21:27:08 keller-volumio volumio[799]: [SpotifyConnect] Vollibrespot Daemon service restarted!
Sep 02 21:27:08 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 02 21:27:08 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 02 21:27:08 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 02 21:27:08 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 02 21:27:08 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 02 21:27:08 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 02 21:27:08 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Sep 02 21:27:08 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 02 21:27:08 keller-volumio volumio[1168]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Uncategorized, message: "No such device" }
Sep 02 21:27:08 keller-volumio volumio[799]: info: Preparing to generate the ALSA configuration file
Sep 02 21:27:08 keller-volumio sudo[1166]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Sep 02 21:27:08 keller-volumio sudo[1166]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 02 21:27:08 keller-volumio sudo[1166]: pam_unix(sudo:session): session closed for user root
Sep 02 21:27:08 keller-volumio volumio[1168]: [Vollibrespot] : Connecting to AP "ap2-gew4.spotify.com:443"
Sep 02 21:27:08 keller-volumio volumio[799]: info: Asound.conf file unchanged, so no further update is needed
Sep 02 21:27:08 keller-volumio volumio[799]: info: Output device has changed, restarting MPD
Sep 02 21:27:08 keller-volumio volumio[799]: [SpotifyConnect] Creating VLS config file
Sep 02 21:27:08 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 02 21:27:08 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 02 21:27:08 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 02 21:27:08 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 02 21:27:08 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 02 21:27:08 keller-volumio volumio[799]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration
Sep 02 21:27:08 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 02 21:27:08 keller-volumio sudo[1181]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Sep 02 21:27:08 keller-volumio sudo[1181]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 02 21:27:09 keller-volumio sudo[1181]: pam_unix(sudo:session): session closed for user root
Sep 02 21:27:09 keller-volumio volumio[799]: [SpotifyConnect] Restarting Vollibrespot Daemon
Sep 02 21:27:09 keller-volumio sudo[1183]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Sep 02 21:27:09 keller-volumio sudo[1183]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 02 21:27:09 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Sep 02 21:27:09 keller-volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM
Sep 02 21:27:09 keller-volumio systemd[1]: mpd.service: Succeeded.
Sep 02 21:27:09 keller-volumio systemd[1]: Stopped Music Player Daemon.
Sep 02 21:27:09 keller-volumio volumio[799]: info: MPD Permissions set
Sep 02 21:27:09 keller-volumio sudo[1189]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect.service
Sep 02 21:27:09 keller-volumio sudo[1189]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 02 21:27:09 keller-volumio volumio[799]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9
Sep 02 21:27:09 keller-volumio volumio[799]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined
Sep 02 21:27:09 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 02 21:27:09 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 02 21:27:09 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 02 21:27:09 keller-volumio systemd[1]: Starting Music Player Daemon...
Sep 02 21:27:09 keller-volumio systemd[1]: Stopping Volspotconnect2 Daemon...
Sep 02 21:27:09 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Sep 02 21:27:09 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 02 21:27:09 keller-volumio systemd[1]: volspotconnect.service: Main process exited, code=killed, status=15/TERM
Sep 02 21:27:09 keller-volumio systemd[1]: volspotconnect.service: Succeeded.
Sep 02 21:27:09 keller-volumio systemd[1]: Stopped Volspotconnect2 Daemon.
Sep 02 21:27:09 keller-volumio systemd[1]: Started Volspotconnect2 Daemon.
Sep 02 21:27:09 keller-volumio sudo[1189]: pam_unix(sudo:session): session closed for user root
Sep 02 21:27:09 keller-volumio volumio[799]: [SpotifyConnect] Vollibrespot Daemon service restarted!
Sep 02 21:27:09 keller-volumio volumio[1196]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31
Sep 02 21:27:09 keller-volumio volumio[1196]: Reading Config from "/tmp/volspotify.toml"
Sep 02 21:27:09 keller-volumio volumio[1196]: [Vollibrespot] : Using Alsa backend with device: volumio
Sep 02 21:27:09 keller-volumio volumio[799]: info: CoreCommandRouter::volumioGetState
Sep 02 21:27:09 keller-volumio sudo[1190]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Sep 02 21:27:09 keller-volumio volumio[1196]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Uncategorized, message: "No such device" }
Sep 02 21:27:09 keller-volumio sudo[1190]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 02 21:27:09 keller-volumio sudo[1190]: pam_unix(sudo:session): session closed for user root
Sep 02 21:27:09 keller-volumio volumio[1196]: [Vollibrespot] : Connecting to AP "ap2-gew4.spotify.com:443"
Sep 02 21:27:09 keller-volumio volumiossh-tunnel[1147]: Warning: Permanently added '[eu4.myvolumio.org]:2222,[167.172.103.77]:2222' (RSA) to the list of known hosts.
Sep 02 21:27:09 keller-volumio volumio[1196]: thread 'main' panicked at 'Authentication failed with reason: BadCredentials', /build/cache/git/checkouts/librespot-6f197fd632ef9380/08d8bcc/core/src/connection/mod.rs:93:21
Sep 02 21:27:09 keller-volumio volumio[1196]: stack backtrace:
Sep 02 21:27:09 keller-volumio volumio[1196]: 0: 0xa48444 - std::backtrace_rs::backtrace::libunwind::trace::h40a4eff66330a36d
Sep 02 21:27:09 keller-volumio volumio[1196]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/../../backtrace/src/backtrace/libunwind.rs:93:5
Sep 02 21:27:09 keller-volumio volumio[1196]: 1: 0xa48444 - std::backtrace_rs::backtrace::trace_unsynchronized::hbce01963451b0cd5
Sep 02 21:27:09 keller-volumio volumio[1196]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5
Sep 02 21:27:09 keller-volumio volumio[1196]: 2: 0xa48444 - std::sys_common::backtrace::_print_fmt::h95da650d09c7e00b
Sep 02 21:27:09 keller-volumio volumio[1196]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:66:5
Sep 02 21:27:09 keller-volumio volumio[1196]: 3: 0xa48444 - ::fmt::h33b23b1d14f668ff
Sep 02 21:27:09 keller-volumio volumio[1196]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:45:22
Sep 02 21:27:09 keller-volumio volumio[1196]: 4: 0xa710dc - core::fmt::write::h6320fb0e6ac90e58
Sep 02 21:27:09 keller-volumio volumio[1196]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/fmt/mod.rs:1190:17
Sep 02 21:27:09 keller-volumio volumio[1196]: 5: 0xa404c4 - std::io::Write::write_fmt::h33b25d4810ca481f
Sep 02 21:27:09 keller-volumio volumio[1196]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/io/mod.rs:1657:15
Sep 02 21:27:09 keller-volumio volumio[1196]: 6: 0xa4aef4 - std::sys_common::backtrace::_print::h7e61c5be69c715ef
Sep 02 21:27:09 keller-volumio volumio[1196]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:48:5
Sep 02 21:27:10 keller-volumio volumio[1196]: 7: 0xa4aef4 - std::sys_common::backtrace::print::h3cee176d7c8dab8b
Sep 02 21:27:10 keller-volumio volumio[1196]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:35:9
Sep 02 21:27:10 keller-volumio volumio[1196]: 8: 0xa4aef4 - std::panicking::default_hook::{{closure}}::h2d0211dd2e97dc43
Sep 02 21:27:10 keller-volumio volumio[1196]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:295:22
Sep 02 21:27:10 keller-volumio volumio[1196]: 9: 0xa4ab14 - std::panicking::default_hook::hcbdb3b7c40dca30c
Sep 02 21:27:10 keller-volumio volumio[1196]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:314:9
Sep 02 21:27:10 keller-volumio volumio[1196]: 10: 0xa4b694 - std::panicking::rust_panic_with_hook::h4ce49e7a343dc12b
Sep 02 21:27:10 keller-volumio volumio[1196]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:698:17
Sep 02 21:27:10 keller-volumio volumio[1196]: 11: 0xa4b304 - std::panicking::begin_panic_handler::{{closure}}::h4961100fae529fe9
Sep 02 21:27:10 keller-volumio volumio[1196]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:588:13
Sep 02 21:27:10 keller-volumio volumio[1196]: 12: 0xa489c4 - std::sys_common::backtrace::__rust_end_short_backtrace::hf2e68e62c7a80f1f
Sep 02 21:27:10 keller-volumio volumio[1196]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:138:18
Sep 02 21:27:10 keller-volumio volumio[1196]: 13: 0xa4b098 - rust_begin_unwind
Sep 02 21:27:10 keller-volumio volumio[1196]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:584:5
Sep 02 21:27:10 keller-volumio volumio[1196]: 14: 0x52e6f4 - core::panicking::panic_fmt::h84c42730e4a50809
Sep 02 21:27:10 keller-volumio volumio[1196]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/panicking.rs:143:14
Sep 02 21:27:10 keller-volumio volumio[1196]: 15: 0x71a930 - futures::future::chain::Chain::poll::hb08394d341f2da5e
Sep 02 21:27:10 keller-volumio volumio[1196]: 16: 0x798d4c - as futures::future::Future>::poll::h0222f8b810b74ccb
Sep 02 21:27:10 keller-volumio volumio[1196]: 17: 0x71b53c - futures::future::chain::Chain::poll::hd3215a5f3268adbc
Sep 02 21:27:10 keller-volumio volumio[1196]: 18: 0x7915f0 - as futures::future::Future>::poll::hc28eaa01d8d9029a
Sep 02 21:27:10 keller-volumio volumio[1196]: 19: 0x555b34 - ::poll::h5fd9e3bcdeb25ad9
Sep 02 21:27:10 keller-volumio volumio[1196]: 20: 0x5733dc - futures::task_impl::std::set::h8f754e80bd562d0a
Sep 02 21:27:10 keller-volumio volumio[1196]: 21: 0x58a42c - std::thread::local::LocalKey::with::h71cfa1e374fcc0a0
Sep 02 21:27:10 keller-volumio volumio[1196]: 22: 0x56f99c - tokio_current_thread::Entered::block_on::hde55cdf28eabe6d5
Sep 02 21:27:10 keller-volumio volumio[1196]: 23: 0x58a6f0 - std::thread::local::LocalKey::with::hedba3cfe41326627
Sep 02 21:27:10 keller-volumio volumio[1196]: 24: 0x572880 - tokio::runtime::current_thread::runtime::Runtime::block_on::h60d5b89560100ed8
Sep 02 21:27:10 keller-volumio volumio[1196]: 25: 0x558eb0 - vollibrespot::main::h35555355ed947dd1
Sep 02 21:27:10 keller-volumio volumio[1196]: 26: 0x578c50 - std::sys_common::backtrace::__rust_begin_short_backtrace::hef626a10d324c12a
Sep 02 21:27:10 keller-volumio volumio[1196]: 27: 0x59e52c - std::rt::lang_start::{{closure}}::hd80188e4bcac5072
Sep 02 21:27:10 keller-volumio volumio[1196]: 28: 0xa47b28 - core::ops::function::impls:: for &F>::call_once::h0567da839a080232
Sep 02 21:27:10 keller-volumio volumio[1196]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/ops/function.rs:259:13
Sep 02 21:27:10 keller-volumio volumio[1196]: 29: 0xa47b28 - std::panicking::try::do_call::hdf194554a41aa3cc
Sep 02 21:27:10 keller-volumio volumio[1196]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:492:40
Sep 02 21:27:10 keller-volumio volumio[1196]: 30: 0xa47b28 - std::panicking::try::hbf4b7be9f9ffa5f8
Sep 02 21:27:10 keller-volumio volumio[1196]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:456:19
Sep 02 21:27:10 keller-volumio volumio[1196]: 31: 0xa47b28 - std::panic::catch_unwind::h45f392a39f2fe48c
Sep 02 21:27:10 keller-volumio volumio[1196]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panic.rs:137:14
Sep 02 21:27:10 keller-volumio volumio[1196]: 32: 0xa47b28 - std::rt::lang_start_internal::{{closure}}::hd772cb03c2060671
Sep 02 21:27:10 keller-volumio volumio[1196]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/rt.rs:128:48
Sep 02 21:27:10 keller-volumio volumio[1196]: 33: 0xa47b28 - std::panicking::try::do_call::hd126f715b519ff2a
Sep 02 21:27:10 keller-volumio volumio[1196]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:492:40
Sep 02 21:27:10 keller-volumio volumio[1196]: 34: 0xa47b28 - std::panicking::try::h80b7148ce71893dc
Sep 02 21:27:10 keller-volumio volumio[1196]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:456:19
Sep 02 21:27:10 keller-volumio volumio[1196]: 35: 0xa47b28 - std::panic::catch_unwind::h1562f0b2e8a9cb0d
Sep 02 21:27:10 keller-volumio volumio[1196]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panic.rs:137:14
Sep 02 21:27:10 keller-volumio volumio[1196]: 36: 0xa47b28 - std::rt::lang_start_internal::h3daa586a0b835507
Sep 02 21:27:10 keller-volumio volumio[1196]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/rt.rs:128:20
Sep 02 21:27:10 keller-volumio volumio[1196]: 37: 0x55ae80 - main
Sep 02 21:27:10 keller-volumio volumio[1196]: 38: 0x76c43768 - __libc_start_main
Sep 02 21:27:10 keller-volumio systemd[1]: volspotconnect.service: Main process exited, code=exited, status=101/n/a
Sep 02 21:27:10 keller-volumio systemd[1]: volspotconnect.service: Failed with result 'exit-code'.
Sep 02 21:27:11 keller-volumio volumio[799]: info: Starting Uninstall of plugin music_service - spop
Sep 02 21:27:11 keller-volumio volumio[799]: info: Uninstalling plugin spop
Sep 02 21:27:11 keller-volumio volumio[799]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSpotify
Sep 02 21:27:11 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Sep 02 21:27:11 keller-volumio volumio[799]: Cannot find translation for source YouTube Music
Sep 02 21:27:11 keller-volumio volumio[799]: [SpotifyConnect] Closing metadata listener
Sep 02 21:27:11 keller-volumio volumio[799]: info: Disabling plugin spop
Sep 02 21:27:11 keller-volumio sudo[1205]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volspotconnect.service
Sep 02 21:27:11 keller-volumio sudo[1205]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 02 21:27:11 keller-volumio volumio[799]: info: Checking if uninstall.sh is present
Sep 02 21:27:11 keller-volumio volumio[799]: info: Executing uninstall.sh
Sep 02 21:27:11 keller-volumio systemd[1]: Stopped Volspotconnect2 Daemon.
Sep 02 21:27:11 keller-volumio sudo[1205]: pam_unix(sudo:session): session closed for user root
Sep 02 21:27:11 keller-volumio volumio[799]: [SpotifyConnect] Vollibrespot Daemon service stoped!
Sep 02 21:27:11 keller-volumio sudo[1209]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/music_service/spop/uninstall.sh
Sep 02 21:27:11 keller-volumio sudo[1209]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 02 21:27:11 keller-volumio sudo[1209]: pam_unix(sudo:session): session closed for user root
Sep 02 21:27:11 keller-volumio volumio[799]: info: Uninstall script completed
Sep 02 21:27:11 keller-volumio volumio[799]: info: Removing plugin spop from configuration
Sep 02 21:27:11 keller-volumio volumio[799]: info: Successfully removed spop configuration files
Sep 02 21:27:11 keller-volumio volumio[799]: info: Plugin folders cleanup
Sep 02 21:27:11 keller-volumio volumio[799]: info: Scanning into folder /volumio/app/plugins/
Sep 02 21:27:11 keller-volumio volumio[799]: info: Scanning category audio_interface
Sep 02 21:27:11 keller-volumio volumio[799]: info: Scanning category miscellanea
Sep 02 21:27:11 keller-volumio volumio[799]: info: Scanning category music_service
Sep 02 21:27:11 keller-volumio volumio[799]: info: Scanning category plugins.json
Sep 02 21:27:11 keller-volumio volumio[799]: info: Scanning category system_controller
Sep 02 21:27:11 keller-volumio volumio[799]: info: Scanning category user_interface
Sep 02 21:27:11 keller-volumio volumio[799]: info: Scanning into folder /data/plugins/
Sep 02 21:27:11 keller-volumio volumio[799]: info: Scanning category music_service
Sep 02 21:27:11 keller-volumio volumio[799]: info: Cleaning folder for spop
Sep 02 21:27:11 keller-volumio mpd[1203]: Sep 02 21:27 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Sep 02 21:27:11 keller-volumio systemd[1]: Started Music Player Daemon.
Sep 02 21:27:11 keller-volumio sudo[1183]: pam_unix(sudo:session): session closed for user root
Sep 02 21:27:11 keller-volumio sudo[1159]: pam_unix(sudo:session): session closed for user root
Sep 02 21:27:11 keller-volumio sudo[1125]: pam_unix(sudo:session): session closed for user root
Sep 02 21:27:13 keller-volumio volumio[799]: info: Plugin folders cleanup completed
Sep 02 21:27:13 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Sep 02 21:27:13 keller-volumio volumio[799]: info: [{"prettyName":"YouTube Music","name":"ytmusic","category":"music_service","version":"1.0.0","icon":"fa-youtube-play","isManuallyInstalled":false,"enabled":true,"active":true}]
Sep 02 21:27:13 keller-volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken
Sep 02 21:27:13 keller-volumio volumio[799]: info: Checking Spotify Web API
Sep 02 21:27:13 keller-volumio volumio[799]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Sep 02 21:27:13 keller-volumio volumio[799]: Error [ERR_SOCKET_DGRAM_NOT_RUNNING]: Not running
Sep 02 21:27:13 keller-volumio volumio[799]: at healthCheck (dgram.js:897:11)
Sep 02 21:27:13 keller-volumio volumio[799]: at Socket.send (dgram.js:622:3)
Sep 02 21:27:13 keller-volumio volumio[799]: at SpotConnEvents.sendmsg (/data/plugins/music_service/spop/SpotConnController.js:121:21)
Sep 02 21:27:13 keller-volumio volumio[799]: at ControllerSpotify.checkWebApi (/data/plugins/music_service/spop/index.js:2383:23)
Sep 02 21:27:13 keller-volumio volumio[799]: at Timeout._onTimeout (/data/plugins/music_service/spop/index.js:2723:18)
Sep 02 21:27:13 keller-volumio volumio[799]: at listOnTimeout (internal/timers.js:554:17)
Sep 02 21:27:13 keller-volumio volumio[799]: at processTimers (internal/timers.js:497:7) {
Sep 02 21:27:13 keller-volumio volumio[799]: code: 'ERR_SOCKET_DGRAM_NOT_RUNNING'
Sep 02 21:27:13 keller-volumio volumio[799]: }
Sep 02 21:27:13 keller-volumio volumio[799]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Sep 02 21:27:14 keller-volumio sudo[1226]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-02 21:26
Sep 02 21:27:14 keller-volumio sudo[1226]: 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="4b122b2365bb8c09786cd63b50d1ea6db780eb4e"
VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6"
VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a"
VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 08:41:12 PM CEST"
VOLUMIO_VERSION="3.703"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="8e78a6e3f56f1bcbbd1760812129a8e9"