Dec 16 15:36:24 waldis-volumio volumio[827]: info: ------------------------------------------- Dec 16 15:36:24 waldis-volumio volumio[827]: info: ----- Volumio3 ---- Dec 16 15:36:24 waldis-volumio volumio[827]: info: ------------------------------------------- Dec 16 15:36:24 waldis-volumio volumio[827]: info: ----- System startup ---- Dec 16 15:36:24 waldis-volumio volumio[827]: info: ------------------------------------------- Dec 16 15:36:24 waldis-volumio volumio[827]: info: MYVOLUMIO Environment detected Dec 16 15:36:24 waldis-volumio volumio[827]: info: Plugin folders cleanup Dec 16 15:36:24 waldis-volumio volumio[827]: info: Scanning into folder /volumio/app/plugins/ Dec 16 15:36:24 waldis-volumio volumio[827]: info: Scanning category audio_interface Dec 16 15:36:24 waldis-volumio volumio[827]: info: Scanning category miscellanea Dec 16 15:36:24 waldis-volumio volumio[827]: info: Scanning category music_service Dec 16 15:36:24 waldis-volumio volumio[827]: info: Scanning category plugins.json Dec 16 15:36:24 waldis-volumio volumio[827]: info: Scanning category system_controller Dec 16 15:36:24 waldis-volumio volumio[827]: info: Scanning category user_interface Dec 16 15:36:24 waldis-volumio volumio[827]: info: Scanning into folder /data/plugins/ Dec 16 15:36:24 waldis-volumio volumio[827]: info: Scanning category music_service Dec 16 15:36:24 waldis-volumio volumio[827]: info: Scanning category user_interface Dec 16 15:36:24 waldis-volumio volumio[827]: info: Plugin folders cleanup completed Dec 16 15:36:24 waldis-volumio volumio[827]: info: ------------------------------------------- Dec 16 15:36:24 waldis-volumio volumio[827]: info: ----- Core plugins startup ---- Dec 16 15:36:24 waldis-volumio volumio[827]: info: ------------------------------------------- Dec 16 15:36:24 waldis-volumio volumio[827]: info: Loading plugins from folder /volumio/app/plugins/ Dec 16 15:36:24 waldis-volumio volumio[827]: info: Adding plugin upnp to MyMusic Plugins Dec 16 15:36:24 waldis-volumio volumio[827]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 16 15:36:24 waldis-volumio volumio[827]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 16 15:36:24 waldis-volumio volumio[827]: info: Loading plugins from folder /data/plugins/ Dec 16 15:36:24 waldis-volumio volumio[827]: info: Loading plugin "system"... Dec 16 15:36:24 waldis-volumio volumio[827]: info: Loading plugin "appearance"... Dec 16 15:36:25 waldis-volumio volumio[827]: info: Loading plugin "network"... Dec 16 15:36:25 waldis-volumio volumio[827]: info: Refreshing Cached IP Addresses Dec 16 15:36:25 waldis-volumio sudo[865]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 16 15:36:25 waldis-volumio sudo[865]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:36:25 waldis-volumio sudo[865]: pam_unix(sudo:session): session closed for user root Dec 16 15:36:25 waldis-volumio volumio[827]: info: Loading plugin "services"... Dec 16 15:36:25 waldis-volumio sudo[867]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 16 15:36:25 waldis-volumio sudo[867]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:36:25 waldis-volumio volumio[827]: info: Loading plugin "alsa_controller"... Dec 16 15:36:25 waldis-volumio sudo[867]: pam_unix(sudo:session): session closed for user root Dec 16 15:36:25 waldis-volumio sudo[870]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 16 15:36:25 waldis-volumio sudo[870]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:36:26 waldis-volumio volumio[827]: info: Loading plugin "wizard"... Dec 16 15:36:26 waldis-volumio volumio[827]: info: Loading plugin "volumio_command_line_client"... Dec 16 15:36:26 waldis-volumio volumio[827]: info: Loading plugin "upnp"... Dec 16 15:36:26 waldis-volumio volumio[827]: info: [1639668986044] Starting Upmpd Daemon Dec 16 15:36:26 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 16 15:36:26 waldis-volumio volumio[827]: info: Loading plugin "my_music"... Dec 16 15:36:26 waldis-volumio volumio[827]: info: Loading plugin "mpd"... Dec 16 15:36:26 waldis-volumio volumio[827]: info: Creating MPD Configuration file Dec 16 15:36:26 waldis-volumio volumio[827]: info: Loading plugin "upnp_browser"... Dec 16 15:36:26 waldis-volumio sudo[878]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 16 15:36:26 waldis-volumio sudo[878]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:36:26 waldis-volumio sudo[878]: pam_unix(sudo:session): session closed for user root Dec 16 15:36:26 waldis-volumio sudo[880]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 16 15:36:26 waldis-volumio sudo[880]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:36:26 waldis-volumio systemd[1]: Stopping Music Player Daemon... Dec 16 15:36:26 waldis-volumio systemd[1]: mpd.service: Succeeded. Dec 16 15:36:26 waldis-volumio systemd[1]: Stopped Music Player Daemon. Dec 16 15:36:26 waldis-volumio systemd[1]: Starting Music Player Daemon... Dec 16 15:36:26 waldis-volumio volumio[827]: info: Loading plugin "networkfs"... Dec 16 15:36:26 waldis-volumio volumio[827]: info: Starting Udev Watcher for removable devices Dec 16 15:36:27 waldis-volumio volumio[827]: info: Ignoring mount for partition: boot Dec 16 15:36:27 waldis-volumio volumio[827]: info: Ignoring mount for partition: volumio Dec 16 15:36:27 waldis-volumio volumio[827]: info: Ignoring mount for partition: volumio_data Dec 16 15:36:27 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 16 15:36:27 waldis-volumio volumio[827]: info: Loading plugin "alarm-clock"... Dec 16 15:36:27 waldis-volumio mpd[885]: Dec 16 15:36 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 16 15:36:27 waldis-volumio mpd[885]: Dec 16 15:36 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Dec 16 15:36:27 waldis-volumio mpd[885]: Dec 16 15:36 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Dec 16 15:36:27 waldis-volumio systemd[1]: Started Music Player Daemon. Dec 16 15:36:27 waldis-volumio sudo[880]: pam_unix(sudo:session): session closed for user root Dec 16 15:36:27 waldis-volumio volumio[827]: info: Loading plugin "airplay_emulation"... Dec 16 15:36:27 waldis-volumio volumio[827]: info: Starting Shairport Sync Dec 16 15:36:27 waldis-volumio volumio[827]: info: Loading plugin "last_100"... Dec 16 15:36:27 waldis-volumio volumio[827]: info: Loading plugin "webradio"... Dec 16 15:36:27 waldis-volumio volumio[827]: info: Loading plugin "i2s_dacs"... Dec 16 15:36:27 waldis-volumio volumio[827]: info: Loading plugin "volumiodiscovery"... Dec 16 15:36:27 waldis-volumio volumio[827]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 16 15:36:27 waldis-volumio volumio[827]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 15:36:27 waldis-volumio node[827]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 16 15:36:27 waldis-volumio volumio[827]: *** WARNING *** For more information see Dec 16 15:36:27 waldis-volumio volumio[827]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 16 15:36:27 waldis-volumio volumio[827]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 15:36:27 waldis-volumio volumio[827]: *** WARNING *** For more information see Dec 16 15:36:27 waldis-volumio node[827]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 15:36:27 waldis-volumio node[827]: *** WARNING *** For more information see Dec 16 15:36:27 waldis-volumio node[827]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 16 15:36:27 waldis-volumio node[827]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 15:36:27 waldis-volumio node[827]: *** WARNING *** For more information see Dec 16 15:36:27 waldis-volumio volumio[827]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 16 15:36:27 waldis-volumio volumio[827]: info: Discovery: Started advertising with name: Waldis-Volumio Dec 16 15:36:27 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 16 15:36:27 waldis-volumio volumio[827]: info: Loading plugin "outputs"... Dec 16 15:36:27 waldis-volumio volumio[827]: info: Loading plugin "albumart"... Dec 16 15:36:27 waldis-volumio volumio[827]: info: Plugin example_plugin is not enabled Dec 16 15:36:27 waldis-volumio volumio[827]: info: Loading plugin "inputs"... Dec 16 15:36:27 waldis-volumio volumio[827]: info: Loading plugin "updater_comm"... Dec 16 15:36:27 waldis-volumio volumio[827]: info: Plugin mpdemulation is not enabled Dec 16 15:36:27 waldis-volumio volumio[827]: info: Loading plugin "rest_api"... Dec 16 15:36:27 waldis-volumio volumio[827]: info: Loading plugin "websocket"... Dec 16 15:36:27 waldis-volumio volumio[827]: info: Loading plugin "radio_paradise"... Dec 16 15:36:27 waldis-volumio volumio[827]: Forking 3 albumart workers Dec 16 15:36:28 waldis-volumio volumio[827]: info: Applying required configuration parameters for plugin radio_paradise Dec 16 15:36:28 waldis-volumio volumio[827]: info: [1639668988119] [RadioParadise] API delay: 5 Dec 16 15:36:28 waldis-volumio volumio[827]: info: Loading plugin "rotaryencoder2"... Dec 16 15:36:28 waldis-volumio volumio[827]: Starting albumart workers Dec 16 15:36:28 waldis-volumio volumio[827]: Starting albumart workers Dec 16 15:36:28 waldis-volumio volumio[827]: Starting albumart workers Dec 16 15:36:28 waldis-volumio volumio[827]: info: Loading plugin "touch_display"... Dec 16 15:36:29 waldis-volumio volumio[827]: info: Applying required configuration parameters for plugin touch_display Dec 16 15:36:29 waldis-volumio volumio[827]: info: Loading i18n strings for locale de Dec 16 15:36:29 waldis-volumio volumio[827]: Updating browse sources language Dec 16 15:36:29 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 15:36:29 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 15:36:29 waldis-volumio volumio[827]: info: CoreCommandRouter::initPlayerControls Dec 16 15:36:29 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:36:29 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:36:29 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:36:29 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:36:29 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:36:29 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:36:29 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:36:29 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:36:29 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 15:36:29 waldis-volumio volumio[827]: Express server listening on port 3000 Dec 16 15:36:29 waldis-volumio volumio[827]: [Metrics] WebUI: 5s 955.52ms Dec 16 15:36:29 waldis-volumio volumio[827]: info: CoreStateMachine::resetVolumioState Dec 16 15:36:29 waldis-volumio volumio[827]: info: CoreStateMachine::getcurrentVolume Dec 16 15:36:29 waldis-volumio volumio[827]: info: CoreCommandRouter::volumioRetrievevolume Dec 16 15:36:29 waldis-volumio volumio[827]: info: MPD Permissions set Dec 16 15:36:29 waldis-volumio volumio[827]: verbose: New Socket.io Connection to 192.168.1.186 from 192.168.1.237 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/15.1 Safari/605.1.15 Total Clients: 1 Dec 16 15:36:29 waldis-volumio volumio[827]: verbose: New Socket.io Connection to 192.168.1.186 from 192.168.1.237 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/15.1 Safari/605.1.15 Total Clients: 2 Dec 16 15:36:29 waldis-volumio volumio[827]: verbose: New Socket.io Connection to 192.168.1.186 from 192.168.1.237 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/15.1 Safari/605.1.15 Total Clients: 3 Dec 16 15:36:29 waldis-volumio volumio[827]: verbose: New Socket.io Connection to 192.168.1.186 from 192.168.1.237 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/15.1 Safari/605.1.15 Total Clients: 4 Dec 16 15:36:29 waldis-volumio sudo[870]: pam_unix(sudo:session): session closed for user root Dec 16 15:36:29 waldis-volumio volumio[827]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 5 Dec 16 15:36:29 waldis-volumio volumio[827]: info: MPD running with PID885 Dec 16 15:36:29 waldis-volumio volumio[827]: ,establishing connection Dec 16 15:36:29 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:36:29 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:36:29 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:36:29 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:36:29 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:36:29 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:36:29 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:36:29 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:36:29 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 15:36:29 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:36:29 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 15:36:29 waldis-volumio volumio[827]: info: Reloading queue from file Dec 16 15:36:29 waldis-volumio volumio[827]: error: updateQueue error: null Dec 16 15:36:29 waldis-volumio volumio[827]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Dec 16 15:36:29 waldis-volumio volumio[827]: info: CoreStateMachine::setRepeat null single undefined Dec 16 15:36:29 waldis-volumio volumio[827]: info: CoreStateMachine::pushState Dec 16 15:36:29 waldis-volumio volumio[827]: info: CorePlayQueue::getTrack 0 Dec 16 15:36:29 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 15:36:29 waldis-volumio volumio[827]: info: CoreCommandRouter::volumioPushState Dec 16 15:36:29 waldis-volumio volumio[827]: info: CoreStateMachine::setRandom null Dec 16 15:36:29 waldis-volumio volumio[827]: info: CoreStateMachine::pushState Dec 16 15:36:29 waldis-volumio volumio[827]: info: CorePlayQueue::getTrack 0 Dec 16 15:36:29 waldis-volumio volumio[827]: info: CoreCommandRouter::volumioPushState Dec 16 15:36:29 waldis-volumio volumio[827]: info: Setting Device type: Raspberry PI Dec 16 15:36:29 waldis-volumio volumio[827]: info: Completed loading Core Plugins Dec 16 15:36:29 waldis-volumio volumio[827]: info: Preparing to generate the ALSA configuration file Dec 16 15:36:29 waldis-volumio volumio[827]: info: VolumeController:: Volume=64 Mute =false Dec 16 15:36:29 waldis-volumio volumio[827]: info: CoreStateMachine::pushState Dec 16 15:36:29 waldis-volumio volumio[827]: info: CorePlayQueue::getTrack 0 Dec 16 15:36:29 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 15:36:29 waldis-volumio volumio[827]: info: CoreCommandRouter::volumioPushState Dec 16 15:36:29 waldis-volumio volumio[827]: info: CoreStateMachine::updateTrackBlock Dec 16 15:36:29 waldis-volumio volumio[827]: info: CorePlayQueue::getTrackBlock Dec 16 15:36:29 waldis-volumio volumio[827]: info: CoreCommandRouter::volumioRetrievevolume Dec 16 15:36:29 waldis-volumio volumio[827]: error: updateQueue error: null Dec 16 15:36:29 waldis-volumio volumio[827]: info: Discovery: adding e3c5553a-e0ac-413a-9ca7-5d49258514ff Dec 16 15:36:29 waldis-volumio volumio[827]: info: mDNS: Found device Waldis-Volumio Dec 16 15:36:29 waldis-volumio volumio[827]: info: CoreCommandRouter::volumioGetState Dec 16 15:36:29 waldis-volumio volumio[827]: info: CorePlayQueue::getTrack 0 Dec 16 15:36:29 waldis-volumio volumio[827]: info: VolumeController:: Volume=64 Mute =false Dec 16 15:36:29 waldis-volumio volumio[827]: info: CoreStateMachine::pushState Dec 16 15:36:29 waldis-volumio volumio[827]: info: CorePlayQueue::getTrack 0 Dec 16 15:36:29 waldis-volumio volumio[827]: info: CoreCommandRouter::volumioPushState Dec 16 15:36:29 waldis-volumio volumio[827]: info: Asound.conf file written Dec 16 15:36:29 waldis-volumio sudo[963]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 16 15:36:29 waldis-volumio sudo[963]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:36:29 waldis-volumio sudo[963]: pam_unix(sudo:session): session closed for user root Dec 16 15:36:29 waldis-volumio volumio[827]: info: Output device has changed, restarting MPD Dec 16 15:36:29 waldis-volumio sudo[968]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 16 15:36:29 waldis-volumio volumio[827]: info: Output device has changed, restarting Shairport Sync Dec 16 15:36:29 waldis-volumio sudo[968]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:36:29 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:36:29 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 15:36:29 waldis-volumio sudo[968]: pam_unix(sudo:session): session closed for user root Dec 16 15:36:29 waldis-volumio sudo[970]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 16 15:36:29 waldis-volumio sudo[970]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:36:29 waldis-volumio systemd[1]: Stopping Music Player Daemon... Dec 16 15:36:29 waldis-volumio volumio[827]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 16 15:36:29 waldis-volumio systemd[1]: mpd.service: Succeeded. Dec 16 15:36:29 waldis-volumio volumio[827]: info: ___________ START PLUGINS ___________ Dec 16 15:36:29 waldis-volumio systemd[1]: Stopped Music Player Daemon. Dec 16 15:36:29 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 15:36:29 waldis-volumio volumio[827]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 15:36:29 waldis-volumio volumio[827]: info: [1639668989908] CoreMusicLibrary::Adding element Media Servers Dec 16 15:36:29 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 15:36:29 waldis-volumio systemd[1]: Starting Music Player Daemon... Dec 16 15:36:29 waldis-volumio volumio[827]: Cannot find translation for sourceMedia Servers Dec 16 15:36:29 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:36:29 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 15:36:29 waldis-volumio volumio[827]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 16 15:36:29 waldis-volumio volumio-remote-updater[477]: [2021-12-16 15:36:29] [connect] Successful connection Dec 16 15:36:29 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:36:29 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 15:36:29 waldis-volumio volumio[827]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 16 15:36:29 waldis-volumio volumio[827]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 15:36:29 waldis-volumio volumio[827]: info: [1639668989996] CoreMusicLibrary::Adding element Last_100 Dec 16 15:36:29 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 15:36:29 waldis-volumio volumio[827]: Cannot find translation for sourceMedia Servers Dec 16 15:36:29 waldis-volumio volumio[827]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 15:36:29 waldis-volumio volumio[827]: info: [1639668989999] CoreMusicLibrary::Adding element Webradio Dec 16 15:36:29 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 15:36:30 waldis-volumio volumio[827]: Cannot find translation for sourceMedia Servers Dec 16 15:36:30 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 15:36:30 waldis-volumio volumio[827]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 15:36:30 waldis-volumio volumio[827]: info: [1639668990010] CoreMusicLibrary::Adding element Radio Paradise Dec 16 15:36:30 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 15:36:30 waldis-volumio volumio[827]: Cannot find translation for sourceMedia Servers Dec 16 15:36:30 waldis-volumio volumio[827]: Cannot find translation for sourceRadio Paradise Dec 16 15:36:30 waldis-volumio volumio[827]: info: [ROTARYENCODER2] loadI18nStrings: /data/plugins/user_interface/rotaryencoder2/i18n/strings_de.json Dec 16 15:36:30 waldis-volumio volumio[827]: info: [ROTARYENCODER2] loadI18nStrings: loaded: {"ROTARYENCODER2":{"CONFIG":"Rotary Encoder II Plugin Konfiguration","SAVE":"Speichern","ENCODER0":"Drehgeber 1","D_ENCODER0":"Einstellungen für den ersten Drehgeber.","ENCODER1":"Drehgeber 2","D_ENCODER1":"Einstellungen für den zweiten Drehgeber.","ENCODER2":"Drehgeber 3","D_ENCODER2":"Einstellungen für den dritten Drehgeber.","ROTARYTYPE":"Perioden pro Schritt","D_ROTARYTYPE":"Perioden pro Schritt (Voll: A und B ganze Periode, Halb: A und B halbe Periode, Viertel: A oder B halbe Periode","FULL":"Volle Perioden Typ","HALF":"Halbe Perioden Typ","QUARTER":"Viertel Perioden Typ","PINA":"Anschluss A GPIO","D_PINA":"GPIO Ein-/Ausgang der mit dem ersten Anschluss des Drehgebers verbunden ist.","PINB":"Anschluss B GPIO","D_PINB":"GPIO Ein-/Ausgang der mit dem zweiten Anschluss des Drehgebers verbunden ist.","DIALACTION":"Drehfunktion","D_DIALACTION":"Funktion, die durch Drehen des Drehgebers ausgelöst wird. Die Funktion 'Websocket Nachricht senden' kann z.B. verwendet werden, um Websock Kommandos an andere Plugins zu senden. (Siehe https://volumio.github.io/docs/API/WebSocket_APIs.html Abschnitt 'CallMethod on Plugin' für weitere Informationen.)","DOTS":"...","VOLUME":"Lautstärke","SKIP":"Titel vor/zurück","SEEK":"Suche im Titel","SCROLL":"Scrollen","EMIT":"Websocket Nachricht senden","SOCKCMDCCW":"Kommando GUZS","SOCKDATACCW":"Daten GUZS","SOCKCMDCW":"Kommando UZS","SOCKDATACW":"Daten UZS","SOCKCMD":"Kommando","SOCKDATA":"Daten","D_SOCKCMD":"Kommandos, die via Websocket gesendet werden sollen.","D_SOCKDATA":"Daten, die zusammen mit dem Websocket Kommando gesendet werden.","PINPUSH":"Taster GPIO","D_PINPUSH":"GPIO Ein-/Ausgang der mit dem Taster des Drehgebers verbunden ist. 0 oder leer zum deaktivieren.","PUSHACTION":"Funktion Tastendruck","D_PUSHACTION":"Funktion, die durch das Drücken des Tasters ausgelöst wird.","LONGPUSHACTION":"Funktion langer Tastendruck","D_LONGPUSHACTION":"Funktion, die durch das lange Drücken des Tasters ausgelöst wird.","PINPUSHDEBOUNCE":"Entprell-Zeit (ms)","D_PINPUSHDEBOUNCE":"Entprell-Zeit für den Taster. Falls der Taster Hardware-entprellt ist, 0 setzen.","PUSHSTATE":"Taster-Logikpegel low-aktiv","D_PUSHSTATE":"Aktivieren, wenn der Logikpegel am GPIO bei gedrücktem Taster 'low' ist.","SET_DEBUG":"Fehlerdiagnose Einstellungen","D_SET_DEBUG":"Einstellungen zur Unterstützung der Fehlersuche.","PLAY":"Wiedergabe starten","PAUSE":"Pause","PLAYPAUSE":"Pause an/aus","STOP":"Stop","REPEAT":"Wiederholen","RANDOM":"Zufällige Wiedergabe","CLEARQUEUE":"Wiedergabeliste löschen","MUTE":"Stumm","UNMUTE":"Stumm aufheben","TOGGLEMUTE":"Stumm an/aus","SHUTDOWN":"System herunterfahren","REBOOT":"System neu starten","RESTARTAPP":"Applikation neu starten","DUMPLOG":"Log Datei abspeichern","LOGGING":"Log","D_LOGGING":"Aktiviert das Schreiben von Log-Nachrichten. Fehlermeldungen werden immer geschrieben.","TOAST_START_SUCCESS":"Plugin erfolgreich gestartet.","TOAST_STOP_SUCCESS":"Plugin erfolgreich angehalten.","TOAST_START_FAIL":"Start des Plugins fehlgeschlagen.","TOAST_STOP_FAIL":"Anhalten des Plugins fehlgeschlagen.","TOAST_GPIO_BLOCKED":"GPIO Pin nicht frei","TOAST_MSG_OVERLAY_BLOCKING":"Blockiert durch weiteres Overlay.","TOAST_SAVE_SUCCESS":"Speichern erfolgreich","TOAST_MSG_SAVE":"Einstellungen für Drehgeber ","TOAST_DEBUG_SAVE":"Einstellungen für Fehlersuche","TOAST_SAVE_FAIL":"Speichern gescheitert","TOAST_WRONG_PARAMETER":"Fehler in Einstellungen","TOAST_NEEDS_INTEGER":"GPIO Pins müssen Ganzzahlen sein.","TOAST_PINS_DIFFERENT":"GPIO Pins für Anschlüsse A/B/Taster müssen verschieden sein.","TOAST_PINS_BLOCKED":"Pins werden in einem der anderen Drehgeber verwendet","TOAST_NO_TYPE":"Bitte 'Periode pro Schritt' angeben!"}} Dec 16 15:36:30 waldis-volumio volumio[827]: info: [ROTARYENCODER2] onStart: Config loaded: {"syncSave":true,"autosave":true,"autosaveDelay":1000,"saved":true,"atomicSave":false,"data":{"enabled0":{"type":"boolean","value":true},"rotaryType0":{"type":"number","value":1},"pinA0":{"type":"string","value":"22"},"pinB0":{"type":"string","value":"17"},"dialAction0":{"type":"number","value":1},"socketCmdCW0":{"type":"string","value":"volume"},"socketDataCW0":{"type":"string","value":"+"},"socketCmdCCW0":{"type":"string","value":"volume"},"socketDataCCW0":{"type":"string","value":"-"},"pinPush0":{"type":"number","value":27},"pinPushDebounce0":{"type":"number","value":0},"pushState0":{"type":"boolean","value":true},"pushAction0":{"type":"number","value":3},"socketCmdPush0":{"type":"string","value":""},"socketDataPush0":{"type":"string","value":""},"longPushAction0":{"type":"number","value":11},"socketCmdLongPush0":{"type":"string","value":""},"socketDataLongPush0":{"type":"string","value":""},"enabled1":{"type":"boolean","value":false},"rotaryType1":{"type":"number","value":0},"pinA1":{"type":"string","value":""},"pinB1":{"type":"string","value":""},"dialAction1":{"type":"number","value":0},"socketCmdCW1":{"type":"string","value":""},"socketDataCW1":{"type":"string","value":""},"socketCmdCCW1":{"type":"string","value":""},"socketDataCCW1":{"type":"string","value":""},"pinPush1":{"type":"number","value":0},"pinPushDebounce1":{"type":"number","value":0},"pushState1":{"type":"boolean","value":false},"pushAction1":{"type":"number","value":0},"socketCmdPush1":{"type":"string","value":""},"socketDataPush1":{"type":"string","value":""},"longPushAction1":{"type":"number","value":0},"socketCmdLongPush1":{"type":"string","value":""},"socketDataLongPush1":{"type":"string","value":""},"enabled2":{"type":"boolean","value":false},"rotaryType2":{"type":"number","value":0},"pinA2":{"type":"string","value":""},"pinB2":{"type":"string","value":""},"dialAction2":{"type":"number","value":0},"socketCmdCW2":{"type":"string","value":""},"socketDataCW2":{"type":"string","value":""},"socketCmdCCW2":{"type":"string","value":""},"socketDataCCW2":{"type":"string","value":""},"pinPush2":{"type":"number","value":0},"pinPushDebounce2":{"type":"number","value":0},"pushState2":{"type":"boolean","value":false},"pushAction2":{"type":"number","value":0},"socketCmdPush2":{"type":"string","value":""},"socketDataPush2":{"type":"string","value":""},"longPushAction2":{"type":"number","value":0},"socketCmdLongPush2":{"type":"string","value":""},"socketDataLongPush2":{"type":"string","value":""},"logging":{"type":"boolean","value":true}},"callbacks":{"_":{}},"filePath":"/data/configuration/user_interface/rotaryencoder2/config.json"} Dec 16 15:36:30 waldis-volumio volumio[827]: info: [ROTARYENCODER2] activateRotaries: 1,2,3 Dec 16 15:36:30 waldis-volumio volumio[827]: info: [ROTARYENCODER2] activateRotaries: 1,2 Dec 16 15:36:30 waldis-volumio volumio[827]: info: [ROTARYENCODER2] activateRotaries: 1 Dec 16 15:36:30 waldis-volumio volumio[827]: info: [ROTARYENCODER2] activateRotaries: Dec 16 15:36:30 waldis-volumio volumio[827]: info: [ROTARYENCODER2] activateRotaries: end of recursion. Dec 16 15:36:30 waldis-volumio volumio[827]: info: Loading i18n strings for locale de Dec 16 15:36:30 waldis-volumio volumio[827]: info: Volumio Calling Home Dec 16 15:36:30 waldis-volumio sudo[986]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Dec 16 15:36:30 waldis-volumio sudo[986]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:36:30 waldis-volumio systemd[1]: Reloading. Dec 16 15:36:30 waldis-volumio volumio[827]: info: [ROTARYENCODER2] addOverlay: 22 17 1 Dec 16 15:36:30 waldis-volumio sudo[992]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay rotary-encoder pin_a=22 pin_b=17 relative_axis=true steps-per-period=1 Dec 16 15:36:30 waldis-volumio sudo[992]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:36:30 waldis-volumio kernel: rotary-encoder rotary@16: gray Dec 16 15:36:30 waldis-volumio kernel: input: rotary@16 as /devices/platform/rotary@16/input/input2 Dec 16 15:36:30 waldis-volumio volumio[827]: info: MPD Permissions set Dec 16 15:36:30 waldis-volumio sudo[992]: pam_unix(sudo:session): session closed for user root Dec 16 15:36:30 waldis-volumio volumio-remote-updater[477]: [2021-12-16 15:36:30] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1639668989 101 Dec 16 15:36:30 waldis-volumio volumio[827]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 6 Dec 16 15:36:30 waldis-volumio volumio[827]: info: Volumio called home Dec 16 15:36:30 waldis-volumio volumio[827]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 7 Dec 16 15:36:30 waldis-volumio volumio[827]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 8 Dec 16 15:36:30 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:36:30 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:36:30 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:36:30 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:36:30 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:36:30 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:36:30 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:36:30 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:36:30 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 15:36:30 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:36:30 waldis-volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Dec 16 15:36:30 waldis-volumio volumio[827]: info: /dev/input: Dec 16 15:36:30 waldis-volumio volumio[827]: by-id Dec 16 15:36:30 waldis-volumio volumio[827]: by-path Dec 16 15:36:30 waldis-volumio volumio[827]: event0 Dec 16 15:36:30 waldis-volumio volumio[827]: event1 Dec 16 15:36:30 waldis-volumio volumio[827]: mice Dec 16 15:36:30 waldis-volumio volumio[827]: mouse0 Dec 16 15:36:30 waldis-volumio volumio[827]: /dev/input/by-id: Dec 16 15:36:30 waldis-volumio volumio[827]: usb-WaveShare_WaveShare_000000000089-event-if00 Dec 16 15:36:30 waldis-volumio volumio[827]: /dev/input/by-path: Dec 16 15:36:30 waldis-volumio volumio[827]: platform-fd500000.pcie-pci-0000:01:00.0-usb-0:1.1:1.0-event Dec 16 15:36:30 waldis-volumio volumio[827]: platform-rotary@16-event Dec 16 15:36:30 waldis-volumio systemd-udevd[1012]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Dec 16 15:36:30 waldis-volumio volumio[827]: info: Starting Shairport Sync Dec 16 15:36:30 waldis-volumio volumio[827]: info: Starting Shairport Sync Dec 16 15:36:30 waldis-volumio volumio[827]: info: Starting Shairport Sync Dec 16 15:36:30 waldis-volumio sudo[1031]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 16 15:36:30 waldis-volumio sudo[1031]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:36:30 waldis-volumio sudo[1035]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 16 15:36:30 waldis-volumio sudo[1035]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:36:30 waldis-volumio sudo[1036]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 16 15:36:30 waldis-volumio sudo[1036]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:36:30 waldis-volumio systemd[1]: ifup@eth0.service: Current command vanished from the unit file, execution of the command list won't be resumed. Dec 16 15:36:30 waldis-volumio systemd[1]: getty@tty1.service: Current command vanished from the unit file, execution of the command list won't be resumed. Dec 16 15:36:30 waldis-volumio volumio[827]: info: CoreCommandRouter::volumioGetState Dec 16 15:36:30 waldis-volumio volumio[827]: info: CorePlayQueue::getTrack 0 Dec 16 15:36:30 waldis-volumio volumio[827]: info: CoreCommandRouter::volumioGetState Dec 16 15:36:30 waldis-volumio volumio[827]: info: CorePlayQueue::getTrack 0 Dec 16 15:36:30 waldis-volumio mpd[984]: Dec 16 15:36 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 16 15:36:30 waldis-volumio mpd[984]: Dec 16 15:36 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Dec 16 15:36:30 waldis-volumio mpd[984]: Dec 16 15:36 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Dec 16 15:36:30 waldis-volumio systemd[1]: Started Music Player Daemon. Dec 16 15:36:30 waldis-volumio sudo[970]: pam_unix(sudo:session): session closed for user root Dec 16 15:36:30 waldis-volumio sudo[986]: pam_unix(sudo:session): session closed for user root Dec 16 15:36:30 waldis-volumio volumio[827]: info: touch_display: systemctl daemon-reload succeeded. Dec 16 15:36:30 waldis-volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 16 15:36:30 waldis-volumio systemd[1]: shairport-sync.service: Succeeded. Dec 16 15:36:30 waldis-volumio volumio[827]: error: updateQueue error: null Dec 16 15:36:30 waldis-volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 16 15:36:30 waldis-volumio sudo[1042]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Dec 16 15:36:30 waldis-volumio sudo[1042]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:36:30 waldis-volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 16 15:36:30 waldis-volumio sudo[1036]: pam_unix(sudo:session): session closed for user root Dec 16 15:36:30 waldis-volumio sudo[1031]: pam_unix(sudo:session): session closed for user root Dec 16 15:36:30 waldis-volumio volumio[827]: info: Shairport-Sync Started Dec 16 15:36:30 waldis-volumio sudo[1035]: pam_unix(sudo:session): session closed for user root Dec 16 15:36:30 waldis-volumio volumio[827]: Error adding Membership: Error: addMembership EINVAL Dec 16 15:36:30 waldis-volumio volumio[827]: info: Shairport-Sync Started Dec 16 15:36:30 waldis-volumio volumio[827]: info: Shairport-Sync Started Dec 16 15:36:30 waldis-volumio systemd[1]: Started Volumio Kiosk. Dec 16 15:36:30 waldis-volumio sudo[1042]: pam_unix(sudo:session): session closed for user root Dec 16 15:36:30 waldis-volumio volumio[827]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Dec 16 15:36:30 waldis-volumio volumio[827]: info: touch_display: Volumio Kiosk started Dec 16 15:36:30 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 15:36:30 waldis-volumio volumio[827]: info: CoreCommandRouter::volumioGetState Dec 16 15:36:30 waldis-volumio volumio[827]: info: CorePlayQueue::getTrack 0 Dec 16 15:36:30 waldis-volumio volumio[827]: info: CoreCommandRouter::volumioGetState Dec 16 15:36:30 waldis-volumio volumio[827]: info: CorePlayQueue::getTrack 0 Dec 16 15:36:30 waldis-volumio volumio[827]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Dec 16 15:36:30 waldis-volumio volumio[827]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X Dec 16 15:36:30 waldis-volumio sudo[1064]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sed -i -e /Option "TransformationMatrix"/d /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Dec 16 15:36:30 waldis-volumio sudo[1064]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:36:31 waldis-volumio sudo[1064]: pam_unix(sudo:session): session closed for user root Dec 16 15:36:31 waldis-volumio volumio[827]: info: touch_display: Touchscreen transformation matrix removed. Dec 16 15:36:31 waldis-volumio startx[1045]: X.Org X Server 1.20.4 Dec 16 15:36:31 waldis-volumio startx[1045]: X Protocol Version 11, Revision 0 Dec 16 15:36:31 waldis-volumio startx[1045]: Build Operating System: Linux 5.10.17-v8+ aarch64 Raspbian Dec 16 15:36:31 waldis-volumio startx[1045]: Current Operating System: Linux waldis-volumio 5.4.83-v7l+ #1379 SMP Mon Dec 14 13:11:54 GMT 2020 armv7l Dec 16 15:36:31 waldis-volumio startx[1045]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_compat_alsa=0 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=1280 bcm2708_fb.fbheight=720 bcm2708_fb.fbswap=1 smsc95xx.macaddr=DC:A6:32:3C:D1:61 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyS0,115200 console=tty1 imgpart=/dev/mmcblk0p2 imgfile=/volumio_current.sqsh rootwait bootdelay=5 elevator=noop logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd-bcm2835.enable_compat_alsa=0 snd_bcm2835.enable_hdmi=1 loglevel=0 Dec 16 15:36:31 waldis-volumio startx[1045]: Build Date: 19 April 2021 03:23:36PM Dec 16 15:36:31 waldis-volumio startx[1045]: xorg-server 2:1.20.4-1+rpt3+deb10u3 (https://www.debian.org/support) Dec 16 15:36:31 waldis-volumio startx[1045]: Current version of pixman: 0.36.0 Dec 16 15:36:31 waldis-volumio startx[1045]: Before reporting problems, check http://wiki.x.org Dec 16 15:36:31 waldis-volumio startx[1045]: to make sure that you have the latest version. Dec 16 15:36:31 waldis-volumio startx[1045]: Markers: (--) probed, (**) from config file, (==) default setting, Dec 16 15:36:31 waldis-volumio startx[1045]: (++) from command line, (!!) notice, (II) informational, Dec 16 15:36:31 waldis-volumio startx[1045]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Dec 16 15:36:31 waldis-volumio startx[1045]: (==) Log file: "/var/log/Xorg.0.log", Time: Thu Dec 16 15:36:31 2021 Dec 16 15:36:31 waldis-volumio startx[1045]: (==) Using config directory: "/etc/X11/xorg.conf.d" Dec 16 15:36:31 waldis-volumio startx[1045]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Dec 16 15:36:31 waldis-volumio volumio[827]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Dec 16 15:36:31 waldis-volumio volumio[827]: info: CoreCommandRouter::volumioGetState Dec 16 15:36:31 waldis-volumio volumio[827]: info: CorePlayQueue::getTrack 0 Dec 16 15:36:31 waldis-volumio volumio[827]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Dec 16 15:36:31 waldis-volumio volumio[827]: info: [ROTARYENCODER2] attachListener: /dev/input/by-path/platform-rotary@16-event Dec 16 15:36:31 waldis-volumio volumio[827]: info: [ROTARYENCODER2] addEventHandle for rotary: 1 Dec 16 15:36:31 waldis-volumio volumio[827]: info: [ROTARYENCODER2] activateButtons: 1,2,3 Dec 16 15:36:31 waldis-volumio volumio[827]: info: [ROTARYENCODER2] activateButtons: 1,2 Dec 16 15:36:31 waldis-volumio volumio[827]: info: [ROTARYENCODER2] activateButtons: 1 Dec 16 15:36:31 waldis-volumio volumio[827]: info: [ROTARYENCODER2] activateButtons: Dec 16 15:36:31 waldis-volumio volumio[827]: info: [ROTARYENCODER2] activateButtons: end of recursion. Dec 16 15:36:31 waldis-volumio volumio[827]: info: [ROTARYENCODER2] activateButtons: Now assign push button: 1 Dec 16 15:36:31 waldis-volumio volumio[827]: info: [ROTARYENCODER2] Push Button 1 now resolving. Dec 16 15:36:31 waldis-volumio volumio[827]: info: [ROTARYENCODER2] onStart: Plugin successfully started. Dec 16 15:36:31 waldis-volumio volumio[827]: info: Completed starting Core Plugins Dec 16 15:36:31 waldis-volumio volumio[827]: info: ------------------------------------------- Dec 16 15:36:31 waldis-volumio volumio[827]: info: ----- MyVolumio plugins startup ---- Dec 16 15:36:31 waldis-volumio volumio[827]: info: ------------------------------------------- Dec 16 15:36:31 waldis-volumio volumio[827]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 16 15:36:32 waldis-volumio volumio[827]: info: touch_display: Setting screensaver timeout to 121 seconds. Dec 16 15:36:34 waldis-volumio volumio[827]: verbose: New Socket.io Connection to 192.168.1.186 from 192.168.1.237 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/15.1 Safari/605.1.15 Total Clients: 9 Dec 16 15:36:34 waldis-volumio volumio[827]: info: CoreCommandRouter::volumioGetVisibleSources Dec 16 15:36:34 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 15:36:34 waldis-volumio volumio[827]: info: CoreCommandRouter::volumioGetState Dec 16 15:36:34 waldis-volumio volumio[827]: info: CorePlayQueue::getTrack 0 Dec 16 15:36:34 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 16 15:36:34 waldis-volumio volumio[827]: info: Listing playlists Dec 16 15:36:34 waldis-volumio volumio[827]: info: CoreCommandRouter::volumioGetQueue Dec 16 15:36:34 waldis-volumio volumio[827]: info: CoreStateMachine::getQueue Dec 16 15:36:34 waldis-volumio volumio[827]: info: CorePlayQueue::getQueue Dec 16 15:36:34 waldis-volumio volumio[827]: verbose: New Socket.io Connection to 192.168.1.186 from 192.168.1.237 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/15.1 Safari/605.1.15 Total Clients: 10 Dec 16 15:36:34 waldis-volumio volumio[827]: info: CoreCommandRouter::volumioGetVisibleSources Dec 16 15:36:34 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 15:36:34 waldis-volumio volumio[827]: info: CoreCommandRouter::volumioGetState Dec 16 15:36:34 waldis-volumio volumio[827]: info: CorePlayQueue::getTrack 0 Dec 16 15:36:34 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 16 15:36:34 waldis-volumio volumio[827]: info: Listing playlists Dec 16 15:36:35 waldis-volumio systemd[1]: systemd-fsckd.service: Succeeded. Dec 16 15:36:36 waldis-volumio sudo[1196]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 16 15:36:36 waldis-volumio sudo[1196]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:36:36 waldis-volumio sudo[1196]: pam_unix(sudo:session): session closed for user root Dec 16 15:36:36 waldis-volumio sudo[1199]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 16 15:36:36 waldis-volumio sudo[1199]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:36:36 waldis-volumio sudo[1199]: pam_unix(sudo:session): session closed for user root Dec 16 15:36:36 waldis-volumio sudo[1201]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 16 15:36:36 waldis-volumio sudo[1201]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:36:36 waldis-volumio sh[413]: timed out Dec 16 15:36:36 waldis-volumio dhcpcd[444]: timed out Dec 16 15:36:36 waldis-volumio sh[413]: dhcpcd exited Dec 16 15:36:36 waldis-volumio dhcpcd[444]: dhcpcd exited Dec 16 15:36:36 waldis-volumio sh[413]: ifup: failed to bring up eth0 Dec 16 15:36:36 waldis-volumio systemd[1]: Started UPnP Renderer front-end to MPD. Dec 16 15:36:36 waldis-volumio systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Dec 16 15:36:36 waldis-volumio systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Dec 16 15:36:36 waldis-volumio sudo[1201]: pam_unix(sudo:session): session closed for user root Dec 16 15:36:36 waldis-volumio volumio[827]: info: Upmpdcli Daemon Started Dec 16 15:36:36 waldis-volumio volumio[1205]: Generating RSA private key, 4096 bit long modulus (2 primes) Dec 16 15:36:39 waldis-volumio volumio[827]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Dec 16 15:36:39 waldis-volumio volumio[827]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Dec 16 15:36:39 waldis-volumio volumio[827]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Dec 16 15:36:39 waldis-volumio volumio[827]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Dec 16 15:36:39 waldis-volumio volumio[827]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Dec 16 15:36:39 waldis-volumio volumio[827]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Dec 16 15:36:39 waldis-volumio volumio[827]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Dec 16 15:36:39 waldis-volumio volumio[827]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Dec 16 15:36:39 waldis-volumio volumio[827]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Dec 16 15:36:39 waldis-volumio volumio[827]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Dec 16 15:36:39 waldis-volumio volumio[827]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Dec 16 15:36:39 waldis-volumio volumio[827]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Dec 16 15:36:39 waldis-volumio volumio[827]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Dec 16 15:36:39 waldis-volumio volumio[827]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Dec 16 15:36:39 waldis-volumio volumio[827]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Dec 16 15:36:39 waldis-volumio volumio[827]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Dec 16 15:36:39 waldis-volumio volumio[827]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Dec 16 15:36:39 waldis-volumio volumio[827]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Dec 16 15:36:39 waldis-volumio volumio[827]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Dec 16 15:36:39 waldis-volumio volumio[827]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Dec 16 15:36:39 waldis-volumio volumio[827]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Dec 16 15:36:39 waldis-volumio volumio[827]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Dec 16 15:36:39 waldis-volumio volumio[827]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Dec 16 15:36:39 waldis-volumio volumio[827]: info: Adding plugin bluetooth to MyMusic Plugins Dec 16 15:36:39 waldis-volumio volumio[827]: info: Adding plugin multiroom to MyMusic Plugins Dec 16 15:36:39 waldis-volumio volumio[827]: info: Adding plugin cd_controller to MyMusic Plugins Dec 16 15:36:39 waldis-volumio volumio[827]: info: Adding plugin smart_inputs to MyMusic Plugins Dec 16 15:36:39 waldis-volumio volumio[827]: info: Adding plugin tidalconnect to MyMusic Plugins Dec 16 15:36:39 waldis-volumio volumio[827]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Dec 16 15:36:40 waldis-volumio volumio[827]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Dec 16 15:36:40 waldis-volumio volumio[827]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Dec 16 15:36:40 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 15:36:40 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 15:36:40 waldis-volumio volumio[827]: info: Starting MyVolumio Remote Streaming Endpoints Dec 16 15:36:40 waldis-volumio volumio[827]: info: MyVolumio login type: Token Dec 16 15:36:40 waldis-volumio volumio[827]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Dec 16 15:36:40 waldis-volumio volumio[827]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Dec 16 15:36:40 waldis-volumio volumio[827]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Dec 16 15:36:40 waldis-volumio volumio[827]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Dec 16 15:36:40 waldis-volumio volumio[827]: info: Streaming services startup Dec 16 15:36:40 waldis-volumio volumio[827]: info: Starting Streaming Daemon Dec 16 15:36:40 waldis-volumio sudo[1314]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 16 15:36:40 waldis-volumio sudo[1314]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:36:40 waldis-volumio systemd[1]: Stopping Volumio Streaming Daemon... Dec 16 15:36:40 waldis-volumio systemd[1]: volumio-streaming-daemon.service: Main process exited, code=killed, status=15/TERM Dec 16 15:36:40 waldis-volumio systemd[1]: volumio-streaming-daemon.service: Succeeded. Dec 16 15:36:40 waldis-volumio systemd[1]: Stopped Volumio Streaming Daemon. Dec 16 15:36:40 waldis-volumio systemd[1]: Started Volumio Streaming Daemon. Dec 16 15:36:40 waldis-volumio sudo[1314]: pam_unix(sudo:session): session closed for user root Dec 16 15:36:40 waldis-volumio volumio-streaming-daemon[1320]: ############################ Dec 16 15:36:40 waldis-volumio volumio-streaming-daemon[1320]: # Volumio Streaming Daemon # Dec 16 15:36:40 waldis-volumio volumio-streaming-daemon[1320]: # Running on port 7777 # Dec 16 15:36:40 waldis-volumio volumio-streaming-daemon[1320]: ############################ Dec 16 15:36:40 waldis-volumio volumio-streaming-daemon[1320]: Environment: production Dec 16 15:36:41 waldis-volumio volumio[827]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Dec 16 15:36:41 waldis-volumio volumio[827]: info: MYVOLUMIO: Adding device Dec 16 15:36:41 waldis-volumio volumio[827]: info: MYVOLUMIO: Evaluating Server Dec 16 15:36:41 waldis-volumio volumio[827]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Total Clients: 11 Dec 16 15:36:41 waldis-volumio volumio[827]: info: [ROTARYENCODER2] Push Button 1 released after NaNms. Dec 16 15:36:41 waldis-volumio volumio[827]: info: [ROTARYENCODER2] emitPushCommand: 3for Rotary: 1 Dec 16 15:36:41 waldis-volumio volumio[827]: info: CoreCommandRouter::volumioPlay Dec 16 15:36:41 waldis-volumio volumio[827]: verbose: UNSET VOLATILE Dec 16 15:36:41 waldis-volumio volumio[827]: info: CoreStateMachine::play index undefined Dec 16 15:36:41 waldis-volumio volumio[827]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 16 15:36:41 waldis-volumio volumio[827]: info: CorePlayQueue::getTrack 0 Dec 16 15:36:41 waldis-volumio volumio[827]: info: CoreStateMachine::startPlaybackTimer Dec 16 15:36:41 waldis-volumio volumio[827]: info: CorePlayQueue::getTrack 0 Dec 16 15:36:41 waldis-volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand stop Dec 16 15:36:41 waldis-volumio volumio[827]: info: sendMpdCommand stop took 1 milliseconds Dec 16 15:36:41 waldis-volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand clear Dec 16 15:36:41 waldis-volumio volumio[827]: info: Dec 16 15:36:41 waldis-volumio volumio[827]: ---------------------------- MPD announces system playlist update Dec 16 15:36:41 waldis-volumio volumio[827]: info: Ignoring MPD Status Update Dec 16 15:36:41 waldis-volumio volumio[827]: info: sendMpdCommand clear took 2 milliseconds Dec 16 15:36:41 waldis-volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand consume 1 Dec 16 15:36:41 waldis-volumio volumio[827]: error: updateQueue error: null Dec 16 15:36:41 waldis-volumio volumio[827]: info: Dec 16 15:36:41 waldis-volumio volumio[827]: ---------------------------- MPD announces state update: options Dec 16 15:36:41 waldis-volumio volumio[827]: info: ------------------------------ 4ms Dec 16 15:36:41 waldis-volumio volumio[827]: info: sendMpdCommand consume 1 took 2 milliseconds Dec 16 15:36:41 waldis-volumio volumio[827]: info: ControllerMpd::getState Dec 16 15:36:41 waldis-volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand status Dec 16 15:36:41 waldis-volumio volumio[827]: info: [1639669001884] [RadioParadise] set to consume mode, adding url: https://stream.radioparadise.com/rock-flac Dec 16 15:36:41 waldis-volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand add "https://stream.radioparadise.com/rock-flac" Dec 16 15:36:41 waldis-volumio volumio[827]: info: Dec 16 15:36:41 waldis-volumio volumio[827]: ---------------------------- MPD announces system playlist update Dec 16 15:36:41 waldis-volumio volumio[827]: info: Ignoring MPD Status Update Dec 16 15:36:41 waldis-volumio volumio[827]: info: sendMpdCommand status took 5 milliseconds Dec 16 15:36:41 waldis-volumio volumio[827]: info: sendMpdCommand add "https://stream.radioparadise.com/rock-flac" took 4 milliseconds Dec 16 15:36:41 waldis-volumio volumio[827]: verbose: ControllerMpd::parseState Dec 16 15:36:41 waldis-volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand play Dec 16 15:36:41 waldis-volumio volumio[827]: info: ControllerMpd::pushState Dec 16 15:36:41 waldis-volumio volumio[827]: info: CoreCommandRouter::servicePushState Dec 16 15:36:41 waldis-volumio volumio[827]: info: CoreStateMachine::pushState Dec 16 15:36:41 waldis-volumio volumio[827]: info: CorePlayQueue::getTrack 0 Dec 16 15:36:41 waldis-volumio volumio[827]: info: CoreCommandRouter::volumioPushState Dec 16 15:36:41 waldis-volumio volumio[827]: info: CorePlayQueue::getTrack 0 Dec 16 15:36:41 waldis-volumio volumio[827]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Dec 16 15:36:41 waldis-volumio volumio[827]: info: ------------------------------ 47ms Dec 16 15:36:41 waldis-volumio volumio[827]: info: ------------------------------ 39ms Dec 16 15:36:41 waldis-volumio volumio[827]: info: sendMpdCommand play took 84 milliseconds Dec 16 15:36:41 waldis-volumio volumio[827]: info: [1639669001991] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=2 Dec 16 15:36:42 waldis-volumio kernel: Under-voltage detected! (0x00050005) Dec 16 15:36:42 waldis-volumio volumio[827]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Dec 16 15:36:42 waldis-volumio volumio[827]: info: Removing browser output: myVolumio user plan is not superstar Dec 16 15:36:42 waldis-volumio volumio[827]: info: Removing audio output: Dec 16 15:36:42 waldis-volumio volumio[827]: info: MYVOLUMIO: Adding device Dec 16 15:36:42 waldis-volumio volumio[827]: info: MYVOLUMIO: Evaluating Server Dec 16 15:36:42 waldis-volumio volumio[827]: info: Dec 16 15:36:42 waldis-volumio volumio[827]: ---------------------------- MPD announces state update: player Dec 16 15:36:42 waldis-volumio volumio[827]: info: ControllerMpd::getState Dec 16 15:36:42 waldis-volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand status Dec 16 15:36:43 waldis-volumio systemd[1]: systemd-hostnamed.service: Succeeded. Dec 16 15:36:43 waldis-volumio volumio[827]: info: Dec 16 15:36:43 waldis-volumio volumio[827]: ---------------------------- MPD announces system playlist update Dec 16 15:36:43 waldis-volumio volumio[827]: info: Ignoring MPD Status Update Dec 16 15:36:43 waldis-volumio volumio[827]: info: Dec 16 15:36:43 waldis-volumio volumio[827]: ---------------------------- MPD announces state update: player Dec 16 15:36:43 waldis-volumio volumio[827]: info: ControllerMpd::getState Dec 16 15:36:43 waldis-volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand status Dec 16 15:36:43 waldis-volumio volumio[827]: info: sendMpdCommand status took 672 milliseconds Dec 16 15:36:43 waldis-volumio volumio[827]: verbose: ControllerMpd::parseState Dec 16 15:36:43 waldis-volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 15:36:43 waldis-volumio volumio[827]: info: ------------------------------ 80ms Dec 16 15:36:43 waldis-volumio volumio[827]: info: sendMpdCommand status took 78 milliseconds Dec 16 15:36:43 waldis-volumio volumio[827]: info: sendMpdCommand playlistinfo took 61 milliseconds Dec 16 15:36:43 waldis-volumio volumio[827]: verbose: ControllerMpd::parseState Dec 16 15:36:43 waldis-volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 15:36:43 waldis-volumio volumio[827]: verbose: ControllerMpd::parseTrackInfo Dec 16 15:36:43 waldis-volumio volumio[827]: info: ControllerMpd::pushState Dec 16 15:36:43 waldis-volumio volumio[827]: info: CoreCommandRouter::servicePushState Dec 16 15:36:43 waldis-volumio volumio[827]: info: CorePlayQueue::getTrack 0 Dec 16 15:36:43 waldis-volumio volumio[827]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Dec 16 15:36:43 waldis-volumio volumio[827]: info: ------------------------------ 752ms Dec 16 15:36:43 waldis-volumio volumio[827]: info: Setting Geolocation for MyVolumio to eu1 Dec 16 15:36:43 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 15:36:43 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 15:36:43 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 15:36:43 waldis-volumio volumio[827]: info: [1639669003538] [RadioParadise] received new metadata: {"time":127,"artist":"Nine Inch Nails","title":"Down In It","album":"Pretty Hate Machine","year":"1991","cover":"https://img.radioparadise.com/covers/l/B000BWHE6K.jpg","cover_med":"https://img.radioparadise.com/covers/m/B000BWHE6K.jpg","cover_small":"https://img.radioparadise.com/covers/s/B000BWHE6K.jpg"} Dec 16 15:36:43 waldis-volumio volumio[827]: info: CorePlayQueue::getTrack 0 Dec 16 15:36:43 waldis-volumio volumio[827]: info: CoreCommandRouter::servicePushState Dec 16 15:36:43 waldis-volumio volumio[827]: info: CorePlayQueue::getTrack 0 Dec 16 15:36:43 waldis-volumio volumio[827]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/B000BWHE6K.jpg","uri":"https://stream.radioparadise.com/rock-flac","name":"Down In It","title":"Down In It","artist":"Nine Inch Nails","album":"Pretty Hate Machine","streaming":true,"disableUiControls":true,"duration":132,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Dec 16 15:36:43 waldis-volumio volumio[827]: verbose: CURRENT POSITION 0 Dec 16 15:36:43 waldis-volumio volumio[827]: info: CoreStateMachine::syncState stateService play Dec 16 15:36:43 waldis-volumio volumio[827]: info: CoreStateMachine::syncState currentStatus stop Dec 16 15:36:43 waldis-volumio volumio[827]: info: CoreStateMachine::pushState Dec 16 15:36:43 waldis-volumio volumio[827]: info: CorePlayQueue::getTrack 0 Dec 16 15:36:43 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 15:36:43 waldis-volumio volumio[827]: info: CoreCommandRouter::volumioPushState Dec 16 15:36:43 waldis-volumio volumio[827]: info: [1639669003580] [RadioParadise] setting new timer with duration of 132000 seconds. Dec 16 15:36:43 waldis-volumio volumio[827]: info: sendMpdCommand playlistinfo took 131 milliseconds Dec 16 15:36:43 waldis-volumio volumio[827]: verbose: ControllerMpd::parseTrackInfo Dec 16 15:36:43 waldis-volumio volumio[827]: info: ControllerMpd::pushState Dec 16 15:36:43 waldis-volumio volumio[827]: info: CoreCommandRouter::servicePushState Dec 16 15:36:43 waldis-volumio volumio[827]: info: CorePlayQueue::getTrack 0 Dec 16 15:36:43 waldis-volumio volumio[827]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Dec 16 15:36:43 waldis-volumio volumio[827]: info: ------------------------------ 216ms Dec 16 15:36:43 waldis-volumio volumio[827]: info: Remote config written successfully Dec 16 15:36:43 waldis-volumio volumio[827]: info: Starting Tunnel 1 Dec 16 15:36:43 waldis-volumio volumio[827]: info: Starting Tunnel Connection Checker Dec 16 15:36:43 waldis-volumio volumio[827]: info: Completed starting MyVolumio Plugin Dec 16 15:36:43 waldis-volumio volumio[827]: info: CoreCommandRouter::volumioGetQueue Dec 16 15:36:43 waldis-volumio volumio[827]: info: CoreStateMachine::getQueue Dec 16 15:36:43 waldis-volumio volumio[827]: info: CorePlayQueue::getQueue Dec 16 15:36:43 waldis-volumio volumio-streaming-daemon[1320]: Environment: production Dec 16 15:36:43 waldis-volumio volumio[827]: info: CorePlayQueue::getTrack 0 Dec 16 15:36:43 waldis-volumio volumio[827]: info: CorePlayQueue::getTrack 1 Dec 16 15:36:43 waldis-volumio volumio[827]: info: Setting Geolocation for MyVolumio to eu1 Dec 16 15:36:43 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 15:36:43 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 15:36:43 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 15:36:43 waldis-volumio volumio[827]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Dec 16 15:36:43 waldis-volumio volumio[827]: info: MyVolumio status changed Dec 16 15:36:43 waldis-volumio volumio[827]: info: Streaming services startup Dec 16 15:36:43 waldis-volumio volumio[827]: info: Re-Starting Streaming Daemon Dec 16 15:36:43 waldis-volumio volumio[827]: info: touch_display: Setting screensaver timeout to 0 seconds. Dec 16 15:36:43 waldis-volumio volumio-streaming-daemon[1320]: Restarting daemon Dec 16 15:36:43 waldis-volumio volumio-streaming-daemon[1320]: Environment: production Dec 16 15:36:44 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Dec 16 15:36:44 waldis-volumio volumio[827]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Dec 16 15:36:44 waldis-volumio volumio-streaming-daemon[1320]: Enabled services: Dec 16 15:36:44 waldis-volumio volumio-streaming-daemon[1320]: 1) tidal Dec 16 15:36:44 waldis-volumio volumio-streaming-daemon[1320]: 2) qobuz Dec 16 15:36:44 waldis-volumio volumio[827]: info: [1639669004561] Starting BluetoothController Dec 16 15:36:44 waldis-volumio volumio[827]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Dec 16 15:36:46 waldis-volumio volumio[1205]: .........................................................................................++++ Dec 16 15:36:46 waldis-volumio volumio[827]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Dec 16 15:36:46 waldis-volumio sudo[1545]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod +x /myvolumio/plugins/audio_interface/multiroom/scripts/check_snapcast Dec 16 15:36:46 waldis-volumio sudo[1545]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:36:46 waldis-volumio sudo[1545]: pam_unix(sudo:session): session closed for user root Dec 16 15:36:46 waldis-volumio sudo[1547]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Dec 16 15:36:46 waldis-volumio sudo[1547]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:36:46 waldis-volumio sudo[1547]: pam_unix(sudo:session): session closed for user root Dec 16 15:36:47 waldis-volumio volumio[827]: info: MultiRoom plugin initialized Dec 16 15:36:47 waldis-volumio volumio[827]: STOPPING SNAPCLIENT Dec 16 15:36:47 waldis-volumio volumio[827]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Dec 16 15:36:47 waldis-volumio sudo[1564]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Dec 16 15:36:47 waldis-volumio sudo[1564]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:36:47 waldis-volumio volumio[827]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Dec 16 15:36:47 waldis-volumio volumio[827]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Dec 16 15:36:47 waldis-volumio sudo[1564]: pam_unix(sudo:session): session closed for user root Dec 16 15:36:47 waldis-volumio volumio[827]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Dec 16 15:36:47 waldis-volumio volumio[827]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Dec 16 15:36:47 waldis-volumio volumio[827]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Dec 16 15:36:47 waldis-volumio volumio[827]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Dec 16 15:36:47 waldis-volumio volumio[827]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Dec 16 15:36:47 waldis-volumio volumio[827]: info: Preparing to generate the ALSA configuration file Dec 16 15:36:47 waldis-volumio volumio[827]: info: Updating MyVolumio device info Dec 16 15:36:47 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 15:36:47 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 15:36:47 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 15:36:47 waldis-volumio volumio[827]: info: Updating MyVolumio device info Dec 16 15:36:47 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 15:36:47 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 15:36:47 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 15:36:47 waldis-volumio volumio[827]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 16 15:36:47 waldis-volumio volumio[827]: info: Reading ALSA contributions from plugins. Dec 16 15:36:47 waldis-volumio volumio[827]: info: MRS: SNAPCLIENT STOPPED Dec 16 15:36:47 waldis-volumio volumio[827]: info: Snap server and client installed Dec 16 15:36:47 waldis-volumio sudo[1567]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Dec 16 15:36:47 waldis-volumio sudo[1567]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:36:47 waldis-volumio volumio[827]: info: Fetching Streaming Services browse cache Dec 16 15:36:47 waldis-volumio systemd[1]: Started MyVolumio SSH Tunnel. Dec 16 15:36:47 waldis-volumio sudo[1567]: pam_unix(sudo:session): session closed for user root Dec 16 15:36:47 waldis-volumio autossh[1571]: port set to 0, monitoring disabled Dec 16 15:36:47 waldis-volumio autossh[1571]: starting ssh (count 1) Dec 16 15:36:47 waldis-volumio autossh[1571]: ssh child pid is 1574 Dec 16 15:36:47 waldis-volumio volumio[827]: info: Remote SSH Started Dec 16 15:36:48 waldis-volumio volumio[827]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 12 Dec 16 15:36:48 waldis-volumio volumio[827]: info: Asound.conf file written Dec 16 15:36:48 waldis-volumio sudo[1580]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 16 15:36:48 waldis-volumio sudo[1580]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:36:48 waldis-volumio sudo[1580]: pam_unix(sudo:session): session closed for user root Dec 16 15:36:48 waldis-volumio volumio[827]: info: Output device has changed, restarting MPD Dec 16 15:36:48 waldis-volumio sudo[1585]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 16 15:36:48 waldis-volumio sudo[1585]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:36:48 waldis-volumio sudo[1585]: pam_unix(sudo:session): session closed for user root Dec 16 15:36:48 waldis-volumio volumio[827]: info: Output device has changed, restarting Shairport Sync Dec 16 15:36:48 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:36:48 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 15:36:48 waldis-volumio sudo[1588]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 16 15:36:48 waldis-volumio sudo[1588]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:36:48 waldis-volumio systemd[1]: Stopping Music Player Daemon... Dec 16 15:36:49 waldis-volumio volumio[827]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 16 15:36:49 waldis-volumio systemd[1]: mpd.service: Succeeded. Dec 16 15:36:49 waldis-volumio systemd[1]: Stopped Music Player Daemon. Dec 16 15:36:49 waldis-volumio systemd[1]: Starting Music Player Daemon... Dec 16 15:36:49 waldis-volumio volumio-streaming-daemon[1320]: Environment: production Dec 16 15:36:49 waldis-volumio volumio[827]: info: MPD Permissions set Dec 16 15:36:49 waldis-volumio volumio-streaming-daemon[1320]: Environment: production Dec 16 15:36:49 waldis-volumio volumio[827]: info: CoreCommandRouter::volumioGetState Dec 16 15:36:49 waldis-volumio volumio[827]: info: CorePlayQueue::getTrack 0 Dec 16 15:36:49 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:36:49 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:36:49 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:36:49 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:36:49 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:36:49 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:36:49 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:36:49 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:36:49 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 15:36:49 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:36:49 waldis-volumio volumio[827]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Total Clients: 13 Dec 16 15:36:49 waldis-volumio volumio[827]: info: Starting Shairport Sync Dec 16 15:36:49 waldis-volumio volumio[827]: error: Upnp client error: Error: This socket has been ended by the other party Dec 16 15:36:49 waldis-volumio volumio[827]: error: Upnp client error: Error: This socket has been ended by the other party Dec 16 15:36:49 waldis-volumio volumio[827]: info: Fetching Streaming Services browse cache Dec 16 15:36:49 waldis-volumio sudo[1601]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 16 15:36:49 waldis-volumio sudo[1601]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:36:49 waldis-volumio volumio[827]: Current plan: premium. Enabling manifest UI Dec 16 15:36:49 waldis-volumio volumio[827]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Dec 16 15:36:49 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 16 15:36:49 waldis-volumio volumio[827]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Dec 16 15:36:49 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Dec 16 15:36:49 waldis-volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 16 15:36:49 waldis-volumio volumio[827]: info: MRS: Pushing multiroomSync output for this device Dec 16 15:36:49 waldis-volumio systemd[1]: shairport-sync.service: Succeeded. Dec 16 15:36:49 waldis-volumio volumio[827]: info: MRS: Pushing multiroomSync output Dec 16 15:36:49 waldis-volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 16 15:36:49 waldis-volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 16 15:36:49 waldis-volumio volumio[827]: info: Adding audio output: Dec 16 15:36:49 waldis-volumio volumio[827]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Dec 16 15:36:49 waldis-volumio sudo[1601]: pam_unix(sudo:session): session closed for user root Dec 16 15:36:49 waldis-volumio volumio[827]: info: Adding METAVOLUMIO REST API Endpoints Dec 16 15:36:49 waldis-volumio volumio[827]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Dec 16 15:36:49 waldis-volumio volumio[827]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Dec 16 15:36:49 waldis-volumio volumio[827]: info: Preparing CD Folders Dec 16 15:36:49 waldis-volumio volumio[827]: info: Adding CD REST API Endpoints Dec 16 15:36:49 waldis-volumio volumio[827]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Dec 16 15:36:49 waldis-volumio volumio[827]: info: Starting UDEV Watcher for CD Dec 16 15:36:49 waldis-volumio volumio[827]: info: Detecting CD presence with UDEV Dec 16 15:36:49 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Dec 16 15:36:49 waldis-volumio sudo[1604]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Waldis-Volumio Dec 16 15:36:49 waldis-volumio sudo[1604]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:36:49 waldis-volumio sudo[1604]: pam_unix(sudo:session): session closed for user root Dec 16 15:36:50 waldis-volumio volumio[827]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Dec 16 15:36:50 waldis-volumio volumio[827]: info: Adding inputs REST Endpoints Dec 16 15:36:50 waldis-volumio volumio[827]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Dec 16 15:36:50 waldis-volumio volumio[827]: info: Scanning Audio Inputs Dec 16 15:36:50 waldis-volumio volumio[827]: info: Checking against Known Cards name Dec 16 15:36:50 waldis-volumio volumio[827]: info: Adding Server instance for streaming Dec 16 15:36:50 waldis-volumio volumio[827]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Dec 16 15:36:50 waldis-volumio volumio[827]: error: Hi Res Audio Failed Login: Missing Login Data Dec 16 15:36:50 waldis-volumio volumio[827]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Dec 16 15:36:50 waldis-volumio volumio[827]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Dec 16 15:36:50 waldis-volumio volumio[827]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Dec 16 15:36:50 waldis-volumio sudo[1612]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 16 15:36:50 waldis-volumio sudo[1612]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:36:50 waldis-volumio volumio[827]: info: Stopping AccessToken refresher cron Dec 16 15:36:50 waldis-volumio sudo[1612]: pam_unix(sudo:session): session closed for user root Dec 16 15:36:50 waldis-volumio volumio[827]: info: AccessToken refresher cron started Dec 16 15:36:50 waldis-volumio volumio[827]: info: Stopping AccessToken refresher cron for QOBUZ Dec 16 15:36:51 waldis-volumio volumio[827]: info: AccessToken refresher cron started for QOBUZ Dec 16 15:36:51 waldis-volumio mpd[1600]: Dec 16 15:36 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 16 15:36:51 waldis-volumio mpd[1600]: Dec 16 15:36 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Dec 16 15:36:51 waldis-volumio mpd[1600]: Dec 16 15:36 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Dec 16 15:36:51 waldis-volumio systemd[1]: Started Music Player Daemon. Dec 16 15:36:51 waldis-volumio sudo[1588]: pam_unix(sudo:session): session closed for user root Dec 16 15:36:51 waldis-volumio volumio[827]: info: Executing endpoint metavolumio Dec 16 15:36:51 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 16 15:36:51 waldis-volumio volumio[827]: info: Executing endpoint metavolumio Dec 16 15:36:51 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 16 15:36:51 waldis-volumio volumio[827]: info: Executing endpoint metavolumio Dec 16 15:36:51 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 16 15:36:51 waldis-volumio volumio[827]: info: Bluetooth name changed to Waldis-Volumio Dec 16 15:36:51 waldis-volumio volumio[827]: info: Shairport-Sync Started Dec 16 15:36:51 waldis-volumio volumio[827]: error: updateQueue error: null Dec 16 15:36:51 waldis-volumio volumio[827]: info: CoreCommandRouter::volumioGetVisibleSources Dec 16 15:36:51 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 15:36:51 waldis-volumio volumio[827]: info: CoreCommandRouter::volumioGetState Dec 16 15:36:51 waldis-volumio volumio[827]: info: CorePlayQueue::getTrack 0 Dec 16 15:36:51 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 16 15:36:51 waldis-volumio volumio[827]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Dec 16 15:36:51 waldis-volumio volumio[827]: info: Listing playlists Dec 16 15:36:51 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 16 15:36:51 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 16 15:36:51 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 16 15:36:51 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 16 15:36:51 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 16 15:36:51 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 16 15:36:52 waldis-volumio sudo[1638]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Dec 16 15:36:52 waldis-volumio sudo[1638]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:36:52 waldis-volumio volumio[1205]: ..........................................++++ Dec 16 15:36:52 waldis-volumio volumio[1205]: e is 65537 (0x010001) Dec 16 15:36:52 waldis-volumio systemd[1]: Started Volumio Bluetooth Module. Dec 16 15:36:52 waldis-volumio sudo[1638]: pam_unix(sudo:session): session closed for user root Dec 16 15:36:52 waldis-volumio volumio[1205]: writing RSA key Dec 16 15:36:52 waldis-volumio volumio[827]: info: Volumio BT Module successfully started Dec 16 15:36:52 waldis-volumio sudo[1642]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Dec 16 15:36:52 waldis-volumio sudo[1642]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:36:52 waldis-volumio volumiobt[1641]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Dec 16 15:36:52 waldis-volumio sudo[1642]: pam_unix(sudo:session): session closed for user root Dec 16 15:36:53 waldis-volumio volumio[827]: info: Executing endpoint metavolumio Dec 16 15:36:53 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 16 15:36:53 waldis-volumio volumio[827]: info: Executing endpoint metavolumio Dec 16 15:36:53 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 16 15:36:53 waldis-volumio volumio[827]: info: Executing endpoint metavolumio Dec 16 15:36:53 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 16 15:36:53 waldis-volumio sudo[1661]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Dec 16 15:36:53 waldis-volumio sudo[1661]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:36:53 waldis-volumio volumiobt[1641]: pulseaudio: no process found Dec 16 15:36:53 waldis-volumio sudo[1661]: pam_unix(sudo:session): session closed for user root Dec 16 15:36:53 waldis-volumio dbus-daemon[503]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.23' (uid=1000 pid=1665 comm="/usr/bin/pulseaudio ") Dec 16 15:36:53 waldis-volumio systemd[1]: Starting RealtimeKit Scheduling Policy Service... Dec 16 15:36:53 waldis-volumio volumio[827]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 16 15:36:53 waldis-volumio volumio[827]: BT PLUGIN MESSAGE: Playing: false Dec 16 15:36:53 waldis-volumio volumio[827]: BT PLUGIN MESSAGE: VOLATILE: undefined Dec 16 15:36:53 waldis-volumio volumio[827]: BT PLUGIN MESSAGE: STATE:undefined Dec 16 15:36:53 waldis-volumio dbus-daemon[503]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Dec 16 15:36:53 waldis-volumio systemd[1]: Started RealtimeKit Scheduling Policy Service. Dec 16 15:36:53 waldis-volumio rtkit-daemon[1666]: Successfully called chroot. Dec 16 15:36:53 waldis-volumio rtkit-daemon[1666]: Successfully dropped privileges. Dec 16 15:36:53 waldis-volumio rtkit-daemon[1666]: Successfully limited resources. Dec 16 15:36:53 waldis-volumio rtkit-daemon[1666]: Running. Dec 16 15:36:53 waldis-volumio rtkit-daemon[1666]: Canary thread running. Dec 16 15:36:53 waldis-volumio rtkit-daemon[1666]: Watchdog thread running. Dec 16 15:36:53 waldis-volumio dbus-daemon[503]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.24' (uid=0 pid=1666 comm="/usr/lib/rtkit/rtkit-daemon ") Dec 16 15:36:53 waldis-volumio systemd[1]: Starting Authorization Manager... Dec 16 15:36:53 waldis-volumio volumio[827]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 16 15:36:53 waldis-volumio volumio[827]: BT PLUGIN MESSAGE: Playing: false Dec 16 15:36:53 waldis-volumio volumio[827]: BT PLUGIN MESSAGE: VOLATILE: undefined Dec 16 15:36:53 waldis-volumio volumio[827]: BT PLUGIN MESSAGE: STATE:undefined Dec 16 15:36:53 waldis-volumio polkitd[1669]: started daemon version 0.105 using authority implementation `local' version `0.105' Dec 16 15:36:53 waldis-volumio dbus-daemon[503]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Dec 16 15:36:53 waldis-volumio systemd[1]: Started Authorization Manager. Dec 16 15:36:53 waldis-volumio volumio[827]: info: TidalConnect service stoped! Dec 16 15:36:54 waldis-volumio volumio[827]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 16 15:36:54 waldis-volumio volumio[827]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 16 15:36:54 waldis-volumio sudo[1678]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 16 15:36:54 waldis-volumio sudo[1678]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:36:54 waldis-volumio systemd[1]: Started Volumio Tidal Connect Service. Dec 16 15:36:54 waldis-volumio sudo[1678]: pam_unix(sudo:session): session closed for user root Dec 16 15:36:54 waldis-volumio pulseaudio[1665]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Dec 16 15:36:54 waldis-volumio pulseaudio[1665]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Dec 16 15:36:54 waldis-volumio bluetoothd[752]: Endpoint registered: sender=:1.44 path=/MediaEndpoint/A2DPSource Dec 16 15:36:54 waldis-volumio volumiobt[1641]: Applying permissions Dec 16 15:36:54 waldis-volumio volumiobt[1641]: Setting BT discoverable and pairable Dec 16 15:36:54 waldis-volumio bluetoothd[752]: Endpoint registered: sender=:1.44 path=/MediaEndpoint/A2DPSink Dec 16 15:36:54 waldis-volumio pulseaudio[1665]: Failed to register as a handsfree audio agent with ofono: org.freedesktop.DBus.Error.ServiceUnknown: The name org.ofono was not provided by any .service files Dec 16 15:36:54 waldis-volumio kernel: Bluetooth: RFCOMM TTY layer initialized Dec 16 15:36:54 waldis-volumio kernel: Bluetooth: RFCOMM socket layer initialized Dec 16 15:36:54 waldis-volumio kernel: Bluetooth: RFCOMM ver 1.11 Dec 16 15:36:54 waldis-volumio volumiobt[1641]: Agent registered Dec 16 15:36:54 waldis-volumio volumiobt[1641]: [bluetooth]# -e power on Dec 16 15:36:54 waldis-volumio volumiobt[1641]: Invalid command in menu main: -e Dec 16 15:36:54 waldis-volumio volumiobt[1641]: Dec 16 15:36:54 waldis-volumio volumiobt[1641]: Use "help" for a list of available commands in a menu. Dec 16 15:36:54 waldis-volumio volumiobt[1641]: Use "menu " if you want to enter any submenu. Dec 16 15:36:54 waldis-volumio volumiobt[1641]: Use "back" if you want to return to menu main. Dec 16 15:36:54 waldis-volumio volumiobt[1641]: [bluetooth]# agent on Dec 16 15:36:54 waldis-volumio volumiobt[1641]: Agent is already registered Dec 16 15:36:54 waldis-volumio volumiobt[1641]: [bluetooth]# discoverable on Dec 16 15:36:54 waldis-volumio volumiobt[1641]: [bluetooth]# pairable on Dec 16 15:36:54 waldis-volumio volumio[827]: info: Executing endpoint tc_getconfig Dec 16 15:36:54 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Dec 16 15:36:54 waldis-volumio volumiobt[1641]: [bluetooth]# agent NoInputNoOutput Dec 16 15:36:54 waldis-volumio volumiobt[1641]: Agent is already registered Dec 16 15:36:54 waldis-volumio volumiobt[1641]: [bluetooth]# default-agent Dec 16 15:36:54 waldis-volumio volumiobt[1641]: [bluetooth]# quit Dec 16 15:36:54 waldis-volumio vtcs[1680]: STARTING TidalConnect services, version: 1.1.7.5 Dec 16 15:36:54 waldis-volumio volumiobt[1641]: [59B blob data] Dec 16 15:36:54 waldis-volumio vtcs[1680]: STARTED TidalConnect services. Dec 16 15:36:54 waldis-volumio volumio[827]: info: Executing endpoint tc_connect Dec 16 15:36:54 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Dec 16 15:36:54 waldis-volumio volumio[827]: Connecting to TidalConnect. Dec 16 15:36:54 waldis-volumio volumio[827]: info: CoreCommandRouter::servicePushState Dec 16 15:36:54 waldis-volumio volumio[827]: info: CoreStateMachine::pushState Dec 16 15:36:54 waldis-volumio volumio[827]: info: CorePlayQueue::getTrack 0 Dec 16 15:36:54 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 15:36:54 waldis-volumio volumio[827]: info: CoreCommandRouter::volumioPushState Dec 16 15:36:54 waldis-volumio volumio[827]: info: CorePlayQueue::getTrack 0 Dec 16 15:36:54 waldis-volumio volumio[827]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received tidalconnect Dec 16 15:36:54 waldis-volumio volumio[827]: info: CoreCommandRouter::servicePushState Dec 16 15:36:54 waldis-volumio volumio[827]: info: CoreStateMachine::pushState Dec 16 15:36:54 waldis-volumio volumio[827]: info: CorePlayQueue::getTrack 0 Dec 16 15:36:54 waldis-volumio volumio[827]: info: CoreCommandRouter::volumioPushState Dec 16 15:36:54 waldis-volumio volumio[827]: info: CorePlayQueue::getTrack 0 Dec 16 15:36:54 waldis-volumio volumio[827]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received tidalconnect Dec 16 15:36:54 waldis-volumio volumio[827]: info: MRS: Getting audio outputs on start Dec 16 15:36:54 waldis-volumio volumio[827]: info: MRS: Requesting all other devices output Dec 16 15:36:54 waldis-volumio kernel: Voltage normalised (0x00000000) Dec 16 15:36:55 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 16 15:36:55 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 16 15:36:57 waldis-volumio volumio[827]: info: TidalConnect service started! Dec 16 15:36:57 waldis-volumio volumio[827]: info: BOOT COMPLETED Dec 16 15:36:57 waldis-volumio volumio[827]: [Metrics] CommandRouter: 33s 70.52ms Dec 16 15:36:57 waldis-volumio volumio[827]: info: CoreCommandRouter::volumiosetStartupVolume Dec 16 15:36:57 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:36:57 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 15:36:57 waldis-volumio volumio[827]: info: CoreCommandRouter::Close All Modals sent Dec 16 15:36:57 waldis-volumio volumio[827]: info: CoreCommandRouter::Close All Modals sent Dec 16 15:36:58 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Dec 16 15:36:58 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 16 15:36:58 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Dec 16 15:37:00 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 15:37:00 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 15:37:08 waldis-volumio volumiossh-tunnel[1570]: Warning: Permanently added '[eu1.myvolumio.org]:2222,[188.166.162.121]:2222' (RSA) to the list of known hosts. Dec 16 15:37:15 waldis-volumio ntpd[644]: Soliciting pool server 195.141.190.190 Dec 16 15:37:15 waldis-volumio ntpd[644]: Soliciting pool server 195.186.4.100 Dec 16 15:37:15 waldis-volumio ntpd[644]: Soliciting pool server 192.33.214.47 Dec 16 15:37:16 waldis-volumio ntpd[644]: Soliciting pool server 195.186.1.101 Dec 16 15:37:16 waldis-volumio ntpd[644]: Soliciting pool server 185.125.24.59 Dec 16 15:37:16 waldis-volumio ntpd[644]: Soliciting pool server 212.51.144.44 Dec 16 15:37:16 waldis-volumio ntpd[644]: Soliciting pool server 202.65.114.202 Dec 16 15:37:17 waldis-volumio ntpd[644]: Soliciting pool server 162.159.200.1 Dec 16 15:37:17 waldis-volumio ntpd[644]: Soliciting pool server 185.216.161.42 Dec 16 15:37:17 waldis-volumio ntpd[644]: Soliciting pool server 84.16.73.33 Dec 16 15:37:17 waldis-volumio ntpd[644]: Soliciting pool server 162.159.200.1 Dec 16 15:37:18 waldis-volumio ntpd[644]: Soliciting pool server 185.197.135.6 Dec 16 15:37:18 waldis-volumio ntpd[644]: Soliciting pool server 80.240.216.155 Dec 16 15:37:18 waldis-volumio ntpd[644]: Soliciting pool server 82.197.188.130 Dec 16 15:37:19 waldis-volumio ntpd[644]: Soliciting pool server 51.158.146.73 Dec 16 15:37:20 waldis-volumio ntpd[644]: Soliciting pool server 2a02:168:420b:d::12 Dec 16 15:38:33 waldis-volumio ntpd[644]: receive: Unexpected origin timestamp 0xe565dbb2.724ac4a1 does not match aorg 0000000000.00000000 from server@84.16.73.33 xmt 0xe565dbf9.1debf264 Dec 16 15:38:33 waldis-volumio ntpd[644]: receive: Unexpected origin timestamp 0xe565dbb2.724d8b50 does not match aorg 0000000000.00000000 from server@192.33.214.47 xmt 0xe565dbf9.1df25c1b Dec 16 15:38:33 waldis-volumio ntpd[644]: receive: Unexpected origin timestamp 0xe565dbb2.72463c64 does not match aorg 0000000000.00000000 from server@51.158.146.73 xmt 0xe565dbf9.1e0f1079 Dec 16 15:39:08 waldis-volumio sshd[1817]: Accepted password for volumio from 192.168.1.237 port 63981 ssh2 Dec 16 15:39:08 waldis-volumio sshd[1817]: pam_unix(sshd:session): session opened for user volumio by (uid=0) Dec 16 15:39:08 waldis-volumio systemd[1]: Created slice User Slice of UID 1000. Dec 16 15:39:08 waldis-volumio systemd[1]: Starting User Runtime Directory /run/user/1000... Dec 16 15:39:08 waldis-volumio systemd-logind[500]: New session 1 of user volumio. Dec 16 15:39:08 waldis-volumio volumio[827]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 16 15:39:08 waldis-volumio volumio[827]: BT PLUGIN MESSAGE: Playing: false Dec 16 15:39:08 waldis-volumio volumio[827]: BT PLUGIN MESSAGE: VOLATILE: undefined Dec 16 15:39:08 waldis-volumio volumio[827]: BT PLUGIN MESSAGE: STATE:undefined Dec 16 15:39:08 waldis-volumio volumio[827]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 16 15:39:08 waldis-volumio volumio[827]: BT PLUGIN MESSAGE: Playing: false Dec 16 15:39:08 waldis-volumio volumio[827]: BT PLUGIN MESSAGE: VOLATILE: undefined Dec 16 15:39:08 waldis-volumio volumio[827]: BT PLUGIN MESSAGE: STATE:undefined Dec 16 15:39:08 waldis-volumio volumio[827]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 16 15:39:08 waldis-volumio volumio[827]: BT PLUGIN MESSAGE: Playing: false Dec 16 15:39:08 waldis-volumio volumio[827]: BT PLUGIN MESSAGE: VOLATILE: undefined Dec 16 15:39:08 waldis-volumio volumio[827]: BT PLUGIN MESSAGE: STATE:undefined Dec 16 15:39:08 waldis-volumio systemd[1]: Started User Runtime Directory /run/user/1000. Dec 16 15:39:08 waldis-volumio systemd[1]: Starting User Manager for UID 1000... Dec 16 15:39:08 waldis-volumio volumio[827]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 16 15:39:08 waldis-volumio volumio[827]: BT PLUGIN MESSAGE: Playing: false Dec 16 15:39:08 waldis-volumio volumio[827]: BT PLUGIN MESSAGE: VOLATILE: undefined Dec 16 15:39:08 waldis-volumio volumio[827]: BT PLUGIN MESSAGE: STATE:undefined Dec 16 15:39:08 waldis-volumio systemd[1839]: pam_unix(systemd-user:session): session opened for user volumio by (uid=0) Dec 16 15:39:08 waldis-volumio systemd[1839]: Listening on GnuPG network certificate management daemon. Dec 16 15:39:08 waldis-volumio systemd[1839]: Listening on GnuPG cryptographic agent and passphrase cache (access for web browsers). Dec 16 15:39:08 waldis-volumio systemd[1839]: Listening on Sound System. Dec 16 15:39:08 waldis-volumio systemd[1839]: Listening on GnuPG cryptographic agent (ssh-agent emulation). Dec 16 15:39:08 waldis-volumio systemd[1839]: Starting D-Bus User Message Bus Socket. Dec 16 15:39:08 waldis-volumio systemd[1839]: Reached target Paths. Dec 16 15:39:08 waldis-volumio systemd[1839]: Listening on GnuPG cryptographic agent and passphrase cache. Dec 16 15:39:08 waldis-volumio systemd[1839]: Listening on GnuPG cryptographic agent and passphrase cache (restricted). Dec 16 15:39:08 waldis-volumio systemd[1839]: mpd.socket: Failed to create listening socket ([::]:6600): Address already in use Dec 16 15:39:08 waldis-volumio systemd[1839]: mpd.socket: Failed to listen on sockets: Address already in use Dec 16 15:39:08 waldis-volumio systemd[1839]: mpd.socket: Failed with result 'resources'. Dec 16 15:39:08 waldis-volumio systemd[1839]: Failed to listen on mpd.socket. Dec 16 15:39:08 waldis-volumio systemd[1839]: Reached target Timers. Dec 16 15:39:08 waldis-volumio systemd[1839]: Listening on D-Bus User Message Bus Socket. Dec 16 15:39:08 waldis-volumio systemd[1839]: Reached target Sockets. Dec 16 15:39:08 waldis-volumio systemd[1839]: Reached target Basic System. Dec 16 15:39:08 waldis-volumio systemd[1]: Started User Manager for UID 1000. Dec 16 15:39:08 waldis-volumio systemd[1839]: Starting Music Player Daemon... Dec 16 15:39:08 waldis-volumio systemd[1]: Started Session 1 of user volumio. Dec 16 15:39:09 waldis-volumio mpd[1852]: Dec 16 15:39 : exception: Failed to bind to '[::]:6600' Dec 16 15:39:09 waldis-volumio mpd[1852]: Dec 16 15:39 : exception: nested: Failed to bind socket: Address already in use Dec 16 15:39:09 waldis-volumio systemd[1839]: mpd.service: Main process exited, code=exited, status=1/FAILURE Dec 16 15:39:09 waldis-volumio systemd[1839]: mpd.service: Failed with result 'exit-code'. Dec 16 15:39:09 waldis-volumio systemd[1839]: Failed to start Music Player Daemon. Dec 16 15:39:09 waldis-volumio systemd[1839]: Reached target Default. Dec 16 15:39:09 waldis-volumio systemd[1839]: Startup finished in 646ms. Dec 16 15:40:06 waldis-volumio volumio[827]: info: [1639669206241] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=2 Dec 16 15:40:07 waldis-volumio volumio[827]: info: [1639669207188] [RadioParadise] received new metadata: {"time":260,"artist":"Tom Petty & The Heartbreakers","title":"Fault Lines","album":"Hypnotic Eye","year":"2014","cover":"https://img.radioparadise.com/covers/l/B00KIZ9F8K.jpg","cover_med":"https://img.radioparadise.com/covers/m/B00KIZ9F8K.jpg","cover_small":"https://img.radioparadise.com/covers/s/B00KIZ9F8K.jpg"} Dec 16 15:40:07 waldis-volumio volumio[827]: info: CorePlayQueue::getTrack 0 Dec 16 15:40:07 waldis-volumio volumio[827]: info: CoreCommandRouter::servicePushState Dec 16 15:40:07 waldis-volumio volumio[827]: info: CorePlayQueue::getTrack 0 Dec 16 15:40:07 waldis-volumio volumio[827]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/B00KIZ9F8K.jpg","uri":"https://stream.radioparadise.com/rock-flac","name":"Fault Lines","title":"Fault Lines","artist":"Tom Petty & The Heartbreakers","album":"Hypnotic Eye","streaming":true,"disableUiControls":true,"duration":265,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Dec 16 15:40:07 waldis-volumio volumio[827]: verbose: CURRENT POSITION 0 Dec 16 15:40:07 waldis-volumio volumio[827]: info: CoreStateMachine::syncState stateService play Dec 16 15:40:07 waldis-volumio volumio[827]: info: CoreStateMachine::syncState currentStatus play Dec 16 15:40:07 waldis-volumio volumio[827]: info: Received an update from plugin. extracting info from payload Dec 16 15:40:07 waldis-volumio volumio[827]: info: CoreStateMachine::pushState Dec 16 15:40:07 waldis-volumio volumio[827]: info: CorePlayQueue::getTrack 0 Dec 16 15:40:07 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 15:40:07 waldis-volumio volumio[827]: info: CoreCommandRouter::volumioPushState Dec 16 15:40:07 waldis-volumio volumio[827]: info: CoreStateMachine::pushState Dec 16 15:40:07 waldis-volumio volumio[827]: info: CorePlayQueue::getTrack 0 Dec 16 15:40:07 waldis-volumio volumio[827]: info: CoreCommandRouter::volumioPushState Dec 16 15:40:07 waldis-volumio volumio[827]: info: [1639669207210] [RadioParadise] setting new timer with duration of 265000 seconds. Dec 16 15:40:07 waldis-volumio volumio[827]: info: CorePlayQueue::getTrack 0 Dec 16 15:40:07 waldis-volumio volumio[827]: info: CorePlayQueue::getTrack 1 Dec 16 15:40:08 waldis-volumio volumio[827]: info: Executing endpoint metavolumio Dec 16 15:40:08 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 16 15:40:08 waldis-volumio volumio[827]: info: Executing endpoint metavolumio Dec 16 15:40:08 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 16 15:40:08 waldis-volumio volumio[827]: info: Executing endpoint metavolumio Dec 16 15:40:08 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 16 15:40:08 waldis-volumio volumio[827]: info: Executing endpoint metavolumio Dec 16 15:40:08 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 16 15:40:08 waldis-volumio volumio[827]: info: Executing endpoint metavolumio Dec 16 15:40:08 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 16 15:40:08 waldis-volumio volumio[827]: info: Executing endpoint metavolumio Dec 16 15:40:08 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 16 15:40:11 waldis-volumio volumio[827]: info: Executing endpoint metavolumio Dec 16 15:40:11 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 16 15:40:11 waldis-volumio volumio[827]: info: Executing endpoint metavolumio Dec 16 15:40:11 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 16 15:40:11 waldis-volumio volumio[827]: info: Executing endpoint metavolumio Dec 16 15:40:11 waldis-volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio