-- Logs begin at Sun 2025-12-07 16:25:40 UTC, end at Sun 2025-12-07 16:29:45 UTC. -- Dec 07 16:28:00 volumio volumio[757]: info: Plugin mpdemulation is not enabled Dec 07 16:28:00 volumio volumio[757]: info: Loading plugin "rest_api"... Dec 07 16:28:01 volumio volumio[757]: info: Loading plugin "websocket"... Dec 07 16:28:01 volumio volumio[757]: info: Loading plugin "Systeminfo"... Dec 07 16:28:02 volumio volumio[757]: Forking 1 albumart workers Dec 07 16:28:06 volumio volumio[757]: info: Loading plugin "80s80s"... Dec 07 16:28:07 volumio volumio-remote-updater[571]: [2025-12-07 16:28:07] [connect] Successful connection Dec 07 16:28:10 volumio volumio[757]: info: Applying required configuration parameters for plugin 80s80s Dec 07 16:28:10 volumio volumio[757]: info: [1765124890535] [80s80s] API delay: 30 Dec 07 16:28:10 volumio volumio[757]: info: Loading plugin "radio_paradise"... Dec 07 16:28:12 volumio volumio[757]: Starting albumart workers Dec 07 16:28:14 volumio volumio[757]: info: Applying required configuration parameters for plugin radio_paradise Dec 07 16:28:14 volumio volumio[757]: info: [1765124894356] [RadioParadise] API delay: 5 Dec 07 16:28:14 volumio volumio[757]: info: Loading plugin "backup_restore"... Dec 07 16:28:18 volumio volumio[757]: info: ___________ START PLUGINS ___________ Dec 07 16:28:18 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 07 16:28:18 volumio volumio[757]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 07 16:28:18 volumio volumio[757]: info: [1765124898323] CoreMusicLibrary::Adding element Media Servers Dec 07 16:28:18 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 07 16:28:18 volumio volumio[757]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 07 16:28:18 volumio volumio[757]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 07 16:28:18 volumio volumio[757]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 07 16:28:18 volumio volumio[757]: info: [1765124898980] CoreMusicLibrary::Adding element Last_100 Dec 07 16:28:18 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 07 16:28:19 volumio volumio[757]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 07 16:28:19 volumio volumio[757]: info: [1765124899011] CoreMusicLibrary::Adding element Webradio Dec 07 16:28:19 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 07 16:28:19 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 07 16:28:19 volumio volumio[757]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 07 16:28:19 volumio volumio[757]: info: [1765124899144] CoreMusicLibrary::Adding element 80s80s Radio Dec 07 16:28:19 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 07 16:28:19 volumio volumio[757]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 07 16:28:19 volumio volumio[757]: info: [1765124899189] CoreMusicLibrary::Adding element Radio Paradise Dec 07 16:28:19 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 07 16:28:19 volumio volumio[757]: info: ------------------------------------------- Dec 07 16:28:19 volumio volumio[757]: info: ----- MyVolumio plugins startup ---- Dec 07 16:28:19 volumio volumio[757]: info: ------------------------------------------- Dec 07 16:28:19 volumio volumio[757]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 07 16:28:19 volumio volumio[757]: info: Loading i18n strings for locale de Dec 07 16:28:19 volumio volumio[757]: Updating browse sources language Dec 07 16:28:19 volumio volumio[757]: Cannot find translation for sourceMedia Servers Dec 07 16:28:19 volumio volumio[757]: Cannot find translation for source80s80s Radio Dec 07 16:28:19 volumio volumio[757]: Cannot find translation for sourceRadio Paradise Dec 07 16:28:19 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 07 16:28:19 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 07 16:28:19 volumio volumio[757]: info: CoreCommandRouter::initPlayerControls Dec 07 16:28:19 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 16:28:19 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Dec 07 16:28:19 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 16:28:19 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 16:28:19 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 16:28:19 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 16:28:19 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 16:28:19 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 07 16:28:19 volumio volumio[757]: info: BOOT COMPLETED Dec 07 16:28:19 volumio volumio[757]: [Metrics] CommandRouter: 54s 382.73ms Dec 07 16:28:20 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 07 16:28:20 volumio volumio[757]: info: CoreCommandRouter::Close All Modals sent Dec 07 16:28:20 volumio volumio[757]: info: CoreCommandRouter::Close All Modals sent Dec 07 16:28:20 volumio volumio[757]: Express server listening on port 3000 Dec 07 16:28:20 volumio volumio[757]: [Metrics] WebUI: 58s 257.68ms Dec 07 16:28:20 volumio volumio[757]: Volumio Calling Home Dec 07 16:28:20 volumio volumio[757]: info: CoreStateMachine::resetVolumioState Dec 07 16:28:20 volumio volumio[757]: info: CoreStateMachine::getcurrentVolume Dec 07 16:28:20 volumio volumio[757]: info: CoreCommandRouter::volumioRetrievevolume Dec 07 16:28:21 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Dec 07 16:28:21 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 07 16:28:21 volumio sudo[951]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 07 16:28:21 volumio sudo[951]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 07 16:28:21 volumio sudo[953]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 07 16:28:21 volumio sudo[951]: pam_unix(sudo:session): session closed for user root Dec 07 16:28:21 volumio sudo[953]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 07 16:28:21 volumio sudo[953]: pam_unix(sudo:session): session closed for user root Dec 07 16:28:22 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Dec 07 16:28:22 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 07 16:28:22 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 16:28:22 volumio volumio-remote-updater[571]: [2025-12-07 16:28:22] [connect] Successful connection Dec 07 16:28:23 volumio volumio[757]: info: Volumio called home Dec 07 16:28:23 volumio volumio[757]: info: Setting Device type: Raspberry PI Dec 07 16:28:23 volumio volumio[757]: info: MPD running with PID627 Dec 07 16:28:23 volumio volumio[757]: ,establishing connection Dec 07 16:28:24 volumio volumio-remote-updater[571]: [2025-12-07 16:28:24] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1765124902 101 Dec 07 16:28:24 volumio volumio[757]: info: VolumeController:: Volume=100 Mute =false Dec 07 16:28:24 volumio volumio[757]: info: CoreStateMachine::pushState Dec 07 16:28:24 volumio volumio[757]: info: CorePlayQueue::getTrack 0 Dec 07 16:28:24 volumio volumio[757]: info: CoreCommandRouter::volumioPushState Dec 07 16:28:24 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 07 16:28:24 volumio volumio[757]: info: CoreStateMachine::updateTrackBlock Dec 07 16:28:24 volumio volumio[757]: info: CorePlayQueue::getTrackBlock Dec 07 16:28:24 volumio volumio[757]: info: CoreCommandRouter::volumioRetrievevolume Dec 07 16:28:25 volumio volumio[757]: info: CoreCommandRouter::volumioGetState Dec 07 16:28:25 volumio volumio[757]: info: CorePlayQueue::getTrack 0 Dec 07 16:28:26 volumio volumio[757]: info: Reloading queue from file Dec 07 16:28:26 volumio avahi-daemon[624]: Invalid response packet from host 192.168.1.1. Dec 07 16:28:26 volumio volumio[757]: error: updateQueue error: null Dec 07 16:28:26 volumio volumio[757]: error: Failed LSINFO: Error: [50@0] {} No such directory Dec 07 16:28:27 volumio volumio[757]: info: CoreStateMachine::setRepeat null single undefined Dec 07 16:28:27 volumio volumio[757]: info: CoreStateMachine::pushState Dec 07 16:28:27 volumio volumio[757]: info: CorePlayQueue::getTrack 0 Dec 07 16:28:27 volumio volumio[757]: info: CoreCommandRouter::volumioPushState Dec 07 16:28:27 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 07 16:28:27 volumio avahi-daemon[624]: Invalid response packet from host 192.168.1.1. Dec 07 16:28:27 volumio avahi-daemon[624]: Invalid response packet from host 192.168.1.88. Dec 07 16:28:27 volumio avahi-daemon[624]: Invalid response packet from host 192.168.1.88. Dec 07 16:28:27 volumio volumio[757]: info: CoreStateMachine::setRandom null Dec 07 16:28:27 volumio volumio[757]: info: CoreStateMachine::pushState Dec 07 16:28:27 volumio volumio[757]: info: CorePlayQueue::getTrack 0 Dec 07 16:28:27 volumio volumio[757]: info: CoreCommandRouter::volumioPushState Dec 07 16:28:27 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 07 16:28:27 volumio volumio[757]: info: Listing playlists Dec 07 16:28:27 volumio volumio[757]: info: Listing playlists Dec 07 16:28:28 volumio volumio[757]: info: VolumeController:: Volume=100 Mute =false Dec 07 16:28:28 volumio volumio[757]: info: CoreStateMachine::pushState Dec 07 16:28:28 volumio volumio[757]: info: CorePlayQueue::getTrack 0 Dec 07 16:28:28 volumio volumio[757]: info: CoreCommandRouter::volumioPushState Dec 07 16:28:28 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 07 16:28:28 volumio volumio[757]: info: Starting Shairport Sync Dec 07 16:28:29 volumio volumio[757]: info: Starting Shairport Sync Dec 07 16:28:29 volumio sudo[981]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 07 16:28:29 volumio sudo[981]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 07 16:28:29 volumio volumio[757]: Discovery: adding 7ab455c2-c56f-402c-ba33-5719c998aab7 Dec 07 16:28:29 volumio volumio[757]: info: mDNS: Found device Volumio Dec 07 16:28:29 volumio volumio[757]: info: CoreCommandRouter::volumioGetState Dec 07 16:28:29 volumio sudo[989]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 07 16:28:29 volumio volumio[757]: info: CorePlayQueue::getTrack 0 Dec 07 16:28:30 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Dec 07 16:28:30 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 07 16:28:30 volumio sudo[989]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 07 16:28:30 volumio sudo[981]: pam_unix(sudo:session): session closed for user root Dec 07 16:28:30 volumio sudo[1002]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 07 16:28:30 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 07 16:28:30 volumio sudo[1002]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 07 16:28:30 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Dec 07 16:28:30 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 07 16:28:30 volumio sudo[989]: pam_unix(sudo:session): session closed for user root Dec 07 16:28:30 volumio systemd[1]: Starting UPnP Renderer front-end to MPD... Dec 07 16:28:30 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Dec 07 16:28:31 volumio sudo[1002]: pam_unix(sudo:session): session closed for user root Dec 07 16:28:32 volumio volumio[757]: info: CoreCommandRouter::volumioGetState Dec 07 16:28:32 volumio volumio[757]: info: CorePlayQueue::getTrack 0 Dec 07 16:28:32 volumio volumio[757]: info: CoreCommandRouter::volumioGetState Dec 07 16:28:32 volumio volumio[757]: info: CorePlayQueue::getTrack 0 Dec 07 16:28:36 volumio volumio[757]: info: Upmpdcli Daemon Started Dec 07 16:28:36 volumio volumio[757]: info: Shairport-Sync Started Dec 07 16:28:36 volumio volumio[757]: Error adding Membership: Error: addMembership EINVAL Dec 07 16:28:36 volumio volumio[757]: info: Shairport-Sync Started Dec 07 16:28:36 volumio volumio[757]: info: CoreCommandRouter::volumioGetState Dec 07 16:28:36 volumio volumio[757]: info: CorePlayQueue::getTrack 0 Dec 07 16:28:36 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 07 16:28:36 volumio volumio[757]: info: CoreCommandRouter::volumioGetVisibleSources Dec 07 16:28:36 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 07 16:28:36 volumio volumio[757]: info: Listing playlists Dec 07 16:28:37 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 07 16:28:37 volumio volumio[757]: info: CoreCommandRouter::volumioGetVisibleSources Dec 07 16:28:37 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 07 16:28:37 volumio volumio[757]: info: Listing playlists Dec 07 16:28:37 volumio volumio[757]: info: CoreCommandRouter::volumioGetQueue Dec 07 16:28:37 volumio volumio[757]: info: CoreStateMachine::getQueue Dec 07 16:28:37 volumio volumio[757]: info: CorePlayQueue::getQueue Dec 07 16:28:37 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: radio_paradise , handleBrowseUri Dec 07 16:28:46 volumio volumio[757]: info: CoreCommandRouter::volumioGetState Dec 07 16:28:46 volumio volumio[757]: info: CorePlayQueue::getTrack 0 Dec 07 16:28:46 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: radio_paradise , handleBrowseUri Dec 07 16:28:46 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: 80s80s , handleBrowseUri Dec 07 16:28:46 volumio volumio[757]: info: [1765124926797] [80s80s] handleBrowseUri curUri: 80s80s/eighties Dec 07 16:28:46 volumio volumio[757]: info: [1765124926805] [80s80s] getRadioContent url: eighties Dec 07 16:28:46 volumio volumio[757]: info: CoreCommandRouter::volumioGetState Dec 07 16:28:46 volumio volumio[757]: info: CorePlayQueue::getTrack 0 Dec 07 16:28:46 volumio volumio[757]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 07 16:28:47 volumio volumio[757]: info: CoreStateMachine::ClearQueue Dec 07 16:28:47 volumio volumio[757]: info: CoreStateMachine::stop Dec 07 16:28:47 volumio volumio[757]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 07 16:28:47 volumio volumio[757]: verbose: UNSET VOLATILE Dec 07 16:28:47 volumio volumio[757]: info: CorePlayQueue::clearPlayQueue Dec 07 16:28:47 volumio volumio[757]: info: CorePlayQueue::saveQueue Dec 07 16:28:47 volumio volumio[757]: info: CoreCommandRouter::volumioPushState Dec 07 16:28:47 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 07 16:28:47 volumio volumio[757]: info: CoreCommandRouter::volumioPushQueue Dec 07 16:28:47 volumio volumio[757]: info: CoreStateMachine::addQueueItems Dec 07 16:28:47 volumio volumio[757]: info: CorePlayQueue::addQueueItems Dec 07 16:28:47 volumio volumio[757]: info: Adding Item to queue: 80s80s/eighties Dec 07 16:28:47 volumio volumio[757]: info: Exploding uri 80s80s/eighties in service 80s80s Dec 07 16:28:47 volumio volumio[757]: info: [1765124927133] [80s80s] explodeUri: 80s80s/eighties Dec 07 16:28:47 volumio volumio[757]: info: CorePlayQueue::saveQueue Dec 07 16:28:47 volumio volumio[757]: info: CoreCommandRouter::volumioPushQueue Dec 07 16:28:47 volumio volumio[757]: info: CoreStateMachine::updateTrackBlock Dec 07 16:28:47 volumio volumio[757]: info: CorePlayQueue::getTrackBlock Dec 07 16:28:47 volumio volumio[757]: info: CoreCommandRouter::volumioPlay Dec 07 16:28:47 volumio volumio[757]: verbose: UNSET VOLATILE Dec 07 16:28:47 volumio volumio[757]: info: CoreStateMachine::play index 0 Dec 07 16:28:47 volumio volumio[757]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 07 16:28:47 volumio volumio[757]: info: CoreStateMachine::stop Dec 07 16:28:47 volumio volumio[757]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 07 16:28:47 volumio volumio[757]: verbose: UNSET VOLATILE Dec 07 16:28:47 volumio volumio[757]: info: CoreStateMachine::play index undefined Dec 07 16:28:47 volumio volumio[757]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 07 16:28:47 volumio volumio[757]: info: CorePlayQueue::getTrack 0 Dec 07 16:28:48 volumio volumio[757]: info: Listing playlists Dec 07 16:28:48 volumio volumio[757]: info: Listing playlists Dec 07 16:28:49 volumio volumio[757]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Dec 07 16:28:49 volumio volumio[757]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Dec 07 16:28:49 volumio volumio[757]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Dec 07 16:28:49 volumio volumio[757]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Dec 07 16:28:49 volumio volumio[757]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Dec 07 16:28:49 volumio volumio[757]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Dec 07 16:28:49 volumio volumio[757]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Dec 07 16:28:49 volumio volumio[757]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Dec 07 16:28:49 volumio volumio[757]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Dec 07 16:28:49 volumio volumio[757]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Dec 07 16:28:49 volumio volumio[757]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Dec 07 16:28:49 volumio volumio[757]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Dec 07 16:28:49 volumio volumio[757]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Dec 07 16:28:49 volumio volumio[757]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Dec 07 16:28:49 volumio volumio[757]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Dec 07 16:28:49 volumio volumio[757]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Dec 07 16:28:49 volumio volumio[757]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Dec 07 16:28:49 volumio volumio[757]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Dec 07 16:28:49 volumio volumio[757]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Dec 07 16:28:49 volumio volumio[757]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Dec 07 16:28:49 volumio volumio[757]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Dec 07 16:28:49 volumio volumio[757]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Dec 07 16:28:49 volumio volumio[757]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Dec 07 16:28:49 volumio volumio[757]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Dec 07 16:28:49 volumio volumio[757]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Dec 07 16:28:49 volumio volumio[757]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Dec 07 16:28:49 volumio volumio[757]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Dec 07 16:28:49 volumio volumio[757]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Dec 07 16:28:49 volumio volumio[757]: info: Adding plugin bluetooth to MyMusic Plugins Dec 07 16:28:49 volumio volumio[757]: info: Adding plugin cd_controller to MyMusic Plugins Dec 07 16:28:49 volumio volumio[757]: info: Adding plugin smart_inputs to MyMusic Plugins Dec 07 16:28:49 volumio volumio[757]: info: Adding plugin tidalconnect to MyMusic Plugins Dec 07 16:28:49 volumio volumio[757]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Dec 07 16:28:53 volumio volumio[757]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Dec 07 16:28:54 volumio volumio[757]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Dec 07 16:28:54 volumio volumio[757]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Dec 07 16:28:54 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 07 16:28:54 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 07 16:28:54 volumio volumio[757]: info: Starting MyVolumio Remote Streaming Endpoints Dec 07 16:28:55 volumio volumio[757]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Dec 07 16:28:55 volumio volumio[757]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Dec 07 16:28:55 volumio volumio[757]: info: Streaming services startup Dec 07 16:28:55 volumio volumio[757]: info: Starting Streaming Daemon Dec 07 16:28:55 volumio sudo[1047]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 07 16:28:55 volumio sudo[1047]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 07 16:28:55 volumio volumio[757]: info: MyVolumio login type: Token Dec 07 16:28:55 volumio volumio[757]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Dec 07 16:28:55 volumio systemd[1]: Stopping Volumio Streaming Daemon... Dec 07 16:28:55 volumio systemd[1]: Starting Volumio Streaming Daemon... Dec 07 16:28:55 volumio systemd[1]: Started Volumio Streaming Daemon. Dec 07 16:28:55 volumio sudo[1047]: pam_unix(sudo:session): session closed for user root Dec 07 16:28:55 volumio volumio-streaming-daemon[1057]: ############################ Dec 07 16:28:55 volumio volumio-streaming-daemon[1057]: # Volumio Streaming Daemon # Dec 07 16:28:55 volumio volumio-streaming-daemon[1057]: # Running on port 7777 # Dec 07 16:28:55 volumio volumio-streaming-daemon[1057]: ############################ Dec 07 16:28:55 volumio volumio-streaming-daemon[1057]: Environment: production Dec 07 16:28:56 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 07 16:28:56 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 07 16:28:56 volumio volumio[757]: info: CoreCommandRouter::volumioGetState Dec 07 16:28:56 volumio volumio[757]: info: CorePlayQueue::getTrack 0 Dec 07 16:28:57 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: radio_paradise , handleBrowseUri Dec 07 16:28:57 volumio volumio[757]: error: MyVolumio Custom Token format not valid, refreshing it Dec 07 16:28:58 volumio volumio[757]: info: MyVolumio login type: Token Dec 07 16:28:59 volumio volumio-streaming-daemon[1057]: Environment: production Dec 07 16:28:59 volumio volumio[757]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Dec 07 16:28:59 volumio volumio[757]: info: MYVOLUMIO: Adding device Dec 07 16:28:59 volumio volumio[757]: info: MYVOLUMIO: Evaluating Server Dec 07 16:29:00 volumio volumio[757]: info: Fetching Streaming Services browse cache Dec 07 16:29:01 volumio volumio[757]: info: CoreCommandRouter::volumioGetState Dec 07 16:29:01 volumio volumio[757]: info: CorePlayQueue::getTrack 0 Dec 07 16:29:02 volumio volumio[757]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 07 16:29:02 volumio volumio[757]: info: CoreStateMachine::ClearQueue Dec 07 16:29:02 volumio volumio[757]: info: CoreStateMachine::stop Dec 07 16:29:02 volumio volumio[757]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 07 16:29:02 volumio volumio[757]: verbose: UNSET VOLATILE Dec 07 16:29:02 volumio volumio[757]: info: CorePlayQueue::clearPlayQueue Dec 07 16:29:02 volumio volumio[757]: info: CorePlayQueue::saveQueue Dec 07 16:29:02 volumio volumio[757]: info: CoreCommandRouter::volumioPushState Dec 07 16:29:02 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 07 16:29:02 volumio volumio[757]: info: CoreCommandRouter::volumioPushQueue Dec 07 16:29:02 volumio volumio[757]: info: CoreStateMachine::addQueueItems Dec 07 16:29:02 volumio volumio[757]: info: CorePlayQueue::addQueueItems Dec 07 16:29:02 volumio volumio[757]: info: Adding Item to queue: webrp/3 Dec 07 16:29:02 volumio volumio[757]: info: Exploding uri webrp/3 in service radio_paradise Dec 07 16:29:02 volumio volumio[757]: info: CorePlayQueue::saveQueue Dec 07 16:29:02 volumio volumio[757]: info: CoreCommandRouter::volumioPushQueue Dec 07 16:29:02 volumio volumio[757]: info: CoreStateMachine::updateTrackBlock Dec 07 16:29:02 volumio volumio[757]: info: CorePlayQueue::getTrackBlock Dec 07 16:29:02 volumio volumio[757]: info: CoreCommandRouter::volumioPlay Dec 07 16:29:02 volumio volumio[757]: verbose: UNSET VOLATILE Dec 07 16:29:02 volumio volumio[757]: info: CoreStateMachine::play index 0 Dec 07 16:29:02 volumio volumio[757]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 07 16:29:02 volumio volumio[757]: info: CoreStateMachine::stop Dec 07 16:29:02 volumio volumio[757]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 07 16:29:02 volumio volumio[757]: verbose: UNSET VOLATILE Dec 07 16:29:02 volumio volumio[757]: info: CoreStateMachine::play index undefined Dec 07 16:29:02 volumio volumio[757]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 07 16:29:02 volumio volumio[757]: info: CorePlayQueue::getTrack 0 Dec 07 16:29:02 volumio volumio[757]: info: CoreStateMachine::startPlaybackTimer Dec 07 16:29:02 volumio volumio[757]: info: CorePlayQueue::getTrack 0 Dec 07 16:29:02 volumio volumio[757]: verbose: ControllerMpd::sendMpdCommand stop Dec 07 16:29:04 volumio volumio[757]: info: MyVolumio status changed Dec 07 16:29:04 volumio volumio[757]: info: Streaming services startup Dec 07 16:29:04 volumio volumio[757]: info: Re-Starting Streaming Daemon Dec 07 16:29:04 volumio volumio[757]: info: Removing browser output: myVolumio user plan is not superstar Dec 07 16:29:04 volumio volumio[757]: info: Removing audio output: Dec 07 16:29:04 volumio volumio[757]: info: Stoppping Tunnel 1 Dec 07 16:29:04 volumio sudo[1097]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Dec 07 16:29:04 volumio volumio[757]: info: sendMpdCommand stop took 1823 milliseconds Dec 07 16:29:04 volumio volumio[757]: verbose: ControllerMpd::sendMpdCommand clear Dec 07 16:29:04 volumio sudo[1097]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 07 16:29:04 volumio volumio[757]: info: Dec 07 16:29:04 volumio volumio[757]: ---------------------------- MPD announces system playlist update Dec 07 16:29:04 volumio volumio[757]: info: Ignoring MPD Status Update Dec 07 16:29:04 volumio volumio[757]: info: sendMpdCommand clear took 136 milliseconds Dec 07 16:29:04 volumio volumio[757]: verbose: ControllerMpd::sendMpdCommand consume 1 Dec 07 16:29:04 volumio systemd[1]: Stopped MyVolumio SSH Tunnel. Dec 07 16:29:04 volumio sudo[1097]: pam_unix(sudo:session): session closed for user root Dec 07 16:29:05 volumio volumio[757]: info: Setting Geolocation for MyVolumio to eu10 Dec 07 16:29:05 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 07 16:29:05 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 07 16:29:05 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 07 16:29:05 volumio volumio-streaming-daemon[1057]: Restarting daemon Dec 07 16:29:05 volumio volumio-streaming-daemon[1057]: Environment: production Dec 07 16:29:05 volumio volumio[757]: error: updateQueue error: null Dec 07 16:29:05 volumio volumio[757]: info: Dec 07 16:29:05 volumio volumio[757]: ---------------------------- MPD announces state update: options Dec 07 16:29:05 volumio volumio[757]: info: ------------------------------ 556ms Dec 07 16:29:05 volumio volumio[757]: info: sendMpdCommand consume 1 took 502 milliseconds Dec 07 16:29:05 volumio volumio[757]: info: ControllerMpd::getState Dec 07 16:29:05 volumio volumio[757]: verbose: ControllerMpd::sendMpdCommand status Dec 07 16:29:05 volumio volumio[757]: info: [1765124945218] [RadioParadise] set to consume mode, adding url: https://stream.radioparadise.com/world-etc-flac Dec 07 16:29:05 volumio volumio[757]: verbose: ControllerMpd::sendMpdCommand add "https://stream.radioparadise.com/world-etc-flac" Dec 07 16:29:05 volumio volumio[757]: info: Remote SSH Stopped Dec 07 16:29:05 volumio volumio[757]: info: Dec 07 16:29:05 volumio volumio[757]: ---------------------------- MPD announces system playlist update Dec 07 16:29:05 volumio volumio[757]: info: Ignoring MPD Status Update Dec 07 16:29:05 volumio volumio[757]: info: sendMpdCommand status took 108 milliseconds Dec 07 16:29:05 volumio volumio[757]: info: sendMpdCommand add "https://stream.radioparadise.com/world-etc-flac" took 110 milliseconds Dec 07 16:29:05 volumio volumio[757]: verbose: ControllerMpd::parseState Dec 07 16:29:05 volumio volumio[757]: verbose: ControllerMpd::sendMpdCommand play Dec 07 16:29:05 volumio volumio[757]: info: ControllerMpd::pushState Dec 07 16:29:05 volumio volumio[757]: info: CoreCommandRouter::servicePushState Dec 07 16:29:05 volumio volumio[757]: info: CoreStateMachine::pushState Dec 07 16:29:05 volumio volumio[757]: info: CorePlayQueue::getTrack 0 Dec 07 16:29:05 volumio volumio[757]: info: CoreCommandRouter::volumioPushState Dec 07 16:29:05 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 07 16:29:05 volumio volumio[757]: info: CorePlayQueue::getTrack 0 Dec 07 16:29:05 volumio volumio[757]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Dec 07 16:29:05 volumio volumio[757]: info: ------------------------------ 335ms Dec 07 16:29:05 volumio volumio[757]: info: ------------------------------ 258ms Dec 07 16:29:05 volumio volumio[757]: info: sendMpdCommand play took 167 milliseconds Dec 07 16:29:05 volumio volumio[757]: info: [1765124945575] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=3 Dec 07 16:29:06 volumio volumio[757]: info: Updating MyVolumio device info Dec 07 16:29:06 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 07 16:29:06 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 07 16:29:06 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 07 16:29:06 volumio volumio-streaming-daemon[1057]: Environment: production Dec 07 16:29:06 volumio volumio[757]: info: Fetching Streaming Services browse cache Dec 07 16:29:08 volumio volumio[757]: info: Dec 07 16:29:08 volumio volumio[757]: ---------------------------- MPD announces state update: player Dec 07 16:29:08 volumio volumio[757]: info: ControllerMpd::getState Dec 07 16:29:08 volumio volumio[757]: verbose: ControllerMpd::sendMpdCommand status Dec 07 16:29:08 volumio volumio[757]: info: [1765124948684] [RadioParadise] received new metadata: {"time":15,"artist":"Garmarna","title":"Gamen (Vulture)","album":"CMJ New Music Monthly, Volume 71: July 1999","year":"1999","cover":"https://img.radioparadise.com/covers/l/B00080CN2K.jpg","cover_med":"https://img.radioparadise.com/covers/m/B00080CN2K.jpg","cover_small":"https://img.radioparadise.com/covers/s/B00080CN2K.jpg"} Dec 07 16:29:08 volumio volumio[757]: info: CorePlayQueue::getTrack 0 Dec 07 16:29:08 volumio volumio[757]: info: CoreCommandRouter::servicePushState Dec 07 16:29:08 volumio volumio[757]: info: CorePlayQueue::getTrack 0 Dec 07 16:29:08 volumio volumio[757]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/B00080CN2K.jpg","uri":"https://stream.radioparadise.com/world-etc-flac","name":"Gamen (Vulture)","title":"Gamen (Vulture)","artist":"Garmarna","album":"CMJ New Music Monthly, Volume 71: July 1999","streaming":true,"disableUiControls":true,"duration":20,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Dec 07 16:29:08 volumio volumio[757]: verbose: CURRENT POSITION 0 Dec 07 16:29:08 volumio volumio[757]: info: CoreStateMachine::syncState stateService play Dec 07 16:29:08 volumio volumio[757]: info: CoreStateMachine::syncState currentStatus stop Dec 07 16:29:08 volumio volumio[757]: info: CoreStateMachine::pushState Dec 07 16:29:08 volumio volumio[757]: info: CorePlayQueue::getTrack 0 Dec 07 16:29:08 volumio volumio[757]: info: CoreCommandRouter::volumioPushState Dec 07 16:29:08 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 07 16:29:08 volumio volumio[757]: info: [1765124948858] [RadioParadise] setting new timer with duration of 20000 seconds. Dec 07 16:29:09 volumio volumio[757]: info: CorePlayQueue::getTrack 0 Dec 07 16:29:09 volumio volumio[757]: info: CorePlayQueue::getTrack 1 Dec 07 16:29:09 volumio volumio[757]: info: Dec 07 16:29:09 volumio volumio[757]: ---------------------------- MPD announces system playlist update Dec 07 16:29:09 volumio volumio[757]: info: Ignoring MPD Status Update Dec 07 16:29:09 volumio volumio[757]: info: Dec 07 16:29:09 volumio volumio[757]: ---------------------------- MPD announces state update: player Dec 07 16:29:09 volumio volumio[757]: info: ControllerMpd::getState Dec 07 16:29:09 volumio volumio[757]: verbose: ControllerMpd::sendMpdCommand status Dec 07 16:29:09 volumio volumio[757]: info: sendMpdCommand status took 483 milliseconds Dec 07 16:29:09 volumio volumio[757]: verbose: ControllerMpd::parseState Dec 07 16:29:09 volumio volumio[757]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 16:29:09 volumio volumio[757]: info: CoreCommandRouter::volumioGetQueue Dec 07 16:29:09 volumio volumio[757]: info: CoreStateMachine::getQueue Dec 07 16:29:09 volumio volumio[757]: info: CorePlayQueue::getQueue Dec 07 16:29:09 volumio volumio[757]: info: ------------------------------ 449ms Dec 07 16:29:09 volumio volumio[757]: info: sendMpdCommand status took 416 milliseconds Dec 07 16:29:09 volumio volumio[757]: info: sendMpdCommand playlistinfo took 367 milliseconds Dec 07 16:29:09 volumio volumio[757]: verbose: ControllerMpd::parseState Dec 07 16:29:09 volumio volumio[757]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 16:29:09 volumio volumio[757]: verbose: ControllerMpd::parseTrackInfo Dec 07 16:29:09 volumio volumio[757]: info: ControllerMpd::pushState Dec 07 16:29:09 volumio volumio[757]: info: CoreCommandRouter::servicePushState Dec 07 16:29:09 volumio volumio[757]: info: CorePlayQueue::getTrack 0 Dec 07 16:29:09 volumio volumio[757]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Dec 07 16:29:09 volumio volumio[757]: info: ------------------------------ 1029ms Dec 07 16:29:10 volumio volumio[757]: info: sendMpdCommand playlistinfo took 623 milliseconds Dec 07 16:29:10 volumio volumio[757]: verbose: ControllerMpd::parseTrackInfo Dec 07 16:29:10 volumio volumio[757]: info: ControllerMpd::pushState Dec 07 16:29:10 volumio volumio[757]: info: CoreCommandRouter::servicePushState Dec 07 16:29:10 volumio volumio[757]: info: CorePlayQueue::getTrack 0 Dec 07 16:29:10 volumio volumio[757]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Dec 07 16:29:10 volumio volumio[757]: info: ------------------------------ 1135ms Dec 07 16:29:11 volumio volumio[757]: info: CoreCommandRouter::volumioGetState Dec 07 16:29:11 volumio volumio[757]: info: CorePlayQueue::getTrack 0 Dec 07 16:29:11 volumio volumio[757]: info: Listing playlists Dec 07 16:29:11 volumio volumio[757]: info: Listing playlists Dec 07 16:29:12 volumio volumio[757]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 07 16:29:12 volumio volumio[757]: info: CoreStateMachine::ClearQueue Dec 07 16:29:12 volumio volumio[757]: info: CoreStateMachine::stop Dec 07 16:29:12 volumio volumio[757]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 07 16:29:12 volumio volumio[757]: verbose: UNSET VOLATILE Dec 07 16:29:12 volumio volumio[757]: info: CoreStateMachine::stPlaybackTimer Dec 07 16:29:12 volumio volumio[757]: info: CoreStateMachine::updateTrackBlock Dec 07 16:29:12 volumio volumio[757]: info: CorePlayQueue::getTrackBlock Dec 07 16:29:12 volumio volumio[757]: info: CoreStateMachine::pushState Dec 07 16:29:12 volumio volumio[757]: info: CorePlayQueue::getTrack 0 Dec 07 16:29:12 volumio volumio[757]: info: CoreCommandRouter::volumioPushState Dec 07 16:29:12 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 07 16:29:12 volumio volumio[757]: info: CoreStateMachine::serviceStop Dec 07 16:29:12 volumio volumio[757]: info: CorePlayQueue::getTrack 0 Dec 07 16:29:12 volumio volumio[757]: info: CoreCommandRouter::serviceStop Dec 07 16:29:12 volumio volumio[757]: info: ControllerMpd::stop Dec 07 16:29:12 volumio volumio[757]: verbose: ControllerMpd::sendMpdCommand stop Dec 07 16:29:12 volumio volumio[757]: info: CorePlayQueue::clearPlayQueue Dec 07 16:29:12 volumio volumio[757]: info: CorePlayQueue::saveQueue Dec 07 16:29:12 volumio volumio[757]: info: CoreCommandRouter::volumioPushState Dec 07 16:29:12 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 07 16:29:12 volumio volumio[757]: info: CoreCommandRouter::volumioPushQueue Dec 07 16:29:12 volumio volumio[757]: info: CoreStateMachine::addQueueItems Dec 07 16:29:12 volumio volumio[757]: info: CorePlayQueue::addQueueItems Dec 07 16:29:12 volumio volumio[757]: info: Adding Item to queue: webrp/1 Dec 07 16:29:12 volumio volumio[757]: info: Exploding uri webrp/1 in service radio_paradise Dec 07 16:29:12 volumio volumio[757]: info: CorePlayQueue::saveQueue Dec 07 16:29:12 volumio volumio[757]: info: CoreCommandRouter::volumioPushQueue Dec 07 16:29:12 volumio volumio[757]: info: CoreStateMachine::updateTrackBlock Dec 07 16:29:12 volumio volumio[757]: info: CorePlayQueue::getTrackBlock Dec 07 16:29:12 volumio volumio[757]: info: CoreCommandRouter::volumioPlay Dec 07 16:29:12 volumio volumio[757]: verbose: UNSET VOLATILE Dec 07 16:29:12 volumio volumio[757]: info: CoreStateMachine::play index 0 Dec 07 16:29:12 volumio volumio[757]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 07 16:29:12 volumio volumio[757]: info: CoreStateMachine::stop Dec 07 16:29:12 volumio volumio[757]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 07 16:29:12 volumio volumio[757]: verbose: UNSET VOLATILE Dec 07 16:29:12 volumio volumio[757]: info: CoreStateMachine::play index undefined Dec 07 16:29:12 volumio volumio[757]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 07 16:29:12 volumio volumio[757]: info: CorePlayQueue::getTrack 0 Dec 07 16:29:12 volumio volumio[757]: info: CoreStateMachine::startPlaybackTimer Dec 07 16:29:12 volumio volumio[757]: info: CorePlayQueue::getTrack 0 Dec 07 16:29:12 volumio volumio[757]: verbose: ControllerMpd::sendMpdCommand stop Dec 07 16:29:12 volumio volumio[757]: info: Dec 07 16:29:12 volumio volumio[757]: ---------------------------- MPD announces state update: player Dec 07 16:29:12 volumio volumio[757]: info: sendMpdCommand stop took 446 milliseconds Dec 07 16:29:12 volumio volumio[757]: info: ControllerMpd::getState Dec 07 16:29:12 volumio volumio[757]: verbose: ControllerMpd::sendMpdCommand status Dec 07 16:29:12 volumio volumio[757]: info: sendMpdCommand stop took 137 milliseconds Dec 07 16:29:12 volumio volumio[757]: info: CoreCommandRouter::servicePushState Dec 07 16:29:12 volumio volumio[757]: info: CoreStateMachine::pushState Dec 07 16:29:12 volumio volumio[757]: info: CorePlayQueue::getTrack 0 Dec 07 16:29:12 volumio volumio[757]: info: CoreCommandRouter::volumioPushState Dec 07 16:29:12 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 07 16:29:12 volumio volumio[757]: info: CorePlayQueue::getTrack 0 Dec 07 16:29:12 volumio volumio[757]: verbose: STATE SERVICE {"status":"stop","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/B00080CN2K.jpg","uri":"https://stream.radioparadise.com/world-etc-flac","name":"Gamen (Vulture)","title":"Gamen (Vulture)","artist":"Garmarna","album":"CMJ New Music Monthly, Volume 71: July 1999","streaming":true,"disableUiControls":true,"duration":20,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Dec 07 16:29:12 volumio volumio[757]: verbose: CURRENT POSITION 0 Dec 07 16:29:12 volumio volumio[757]: info: CoreStateMachine::syncState stateService stop Dec 07 16:29:12 volumio volumio[757]: info: CoreStateMachine::syncState currentStatus stop Dec 07 16:29:12 volumio volumio[757]: info: CoreStateMachine::pushState Dec 07 16:29:12 volumio volumio[757]: info: CorePlayQueue::getTrack 0 Dec 07 16:29:13 volumio volumio[757]: info: CoreCommandRouter::volumioPushState Dec 07 16:29:13 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 07 16:29:13 volumio volumio[757]: info: No code Dec 07 16:29:13 volumio volumio[757]: info: CoreStateMachine::pushState Dec 07 16:29:13 volumio volumio[757]: info: CorePlayQueue::getTrack 0 Dec 07 16:29:13 volumio volumio[757]: info: CoreCommandRouter::volumioPushState Dec 07 16:29:13 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 07 16:29:13 volumio volumio[757]: verbose: ControllerMpd::sendMpdCommand clear Dec 07 16:29:13 volumio volumio[757]: info: Dec 07 16:29:13 volumio volumio[757]: ---------------------------- MPD announces system playlist update Dec 07 16:29:13 volumio volumio[757]: info: Ignoring MPD Status Update Dec 07 16:29:13 volumio volumio[757]: info: sendMpdCommand status took 298 milliseconds Dec 07 16:29:13 volumio volumio[757]: info: sendMpdCommand clear took 126 milliseconds Dec 07 16:29:13 volumio volumio[757]: verbose: ControllerMpd::parseState Dec 07 16:29:13 volumio volumio[757]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 16:29:13 volumio volumio[757]: verbose: ControllerMpd::sendMpdCommand consume 1 Dec 07 16:29:13 volumio volumio[757]: error: updateQueue error: null Dec 07 16:29:13 volumio volumio[757]: info: ------------------------------ 84ms Dec 07 16:29:13 volumio volumio[757]: info: sendMpdCommand playlistinfo took 62 milliseconds Dec 07 16:29:13 volumio volumio[757]: info: sendMpdCommand consume 1 took 63 milliseconds Dec 07 16:29:13 volumio volumio[757]: verbose: ControllerMpd::parseTrackInfo Dec 07 16:29:13 volumio volumio[757]: info: [1765124953301] [RadioParadise] set to consume mode, adding url: https://stream.radioparadise.com/mellow-flac Dec 07 16:29:13 volumio volumio[757]: verbose: ControllerMpd::sendMpdCommand add "https://stream.radioparadise.com/mellow-flac" Dec 07 16:29:13 volumio volumio[757]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Dec 07 16:29:13 volumio volumio[757]: info: ------------------------------ 460ms Dec 07 16:29:13 volumio volumio[757]: info: Dec 07 16:29:13 volumio volumio[757]: ---------------------------- MPD announces system playlist update Dec 07 16:29:13 volumio volumio[757]: info: Ignoring MPD Status Update Dec 07 16:29:13 volumio volumio[757]: info: sendMpdCommand add "https://stream.radioparadise.com/mellow-flac" took 45 milliseconds Dec 07 16:29:13 volumio volumio[757]: verbose: ControllerMpd::sendMpdCommand play Dec 07 16:29:13 volumio volumio[757]: info: ------------------------------ 79ms Dec 07 16:29:13 volumio volumio[757]: info: sendMpdCommand play took 49 milliseconds Dec 07 16:29:13 volumio volumio[757]: info: [1765124953446] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=1 Dec 07 16:29:14 volumio volumio[757]: info: Dec 07 16:29:14 volumio volumio[757]: ---------------------------- MPD announces state update: player Dec 07 16:29:14 volumio volumio[757]: info: ControllerMpd::getState Dec 07 16:29:14 volumio volumio[757]: verbose: ControllerMpd::sendMpdCommand status Dec 07 16:29:14 volumio volumio[757]: info: Dec 07 16:29:14 volumio volumio[757]: ---------------------------- MPD announces system playlist update Dec 07 16:29:14 volumio volumio[757]: info: Ignoring MPD Status Update Dec 07 16:29:14 volumio volumio[757]: info: Dec 07 16:29:14 volumio volumio[757]: ---------------------------- MPD announces state update: player Dec 07 16:29:14 volumio volumio[757]: info: ControllerMpd::getState Dec 07 16:29:14 volumio volumio[757]: verbose: ControllerMpd::sendMpdCommand status Dec 07 16:29:14 volumio volumio[757]: info: [1765124954378] [RadioParadise] received new metadata: {"time":145,"artist":"Beck","title":"Stormbringer","album":"2006-10-22: Angel Orensanz Center, New York, NY, USA","year":"2011","cover":"https://img.radioparadise.com/covers/l/B0057OORWG.jpg","cover_med":"https://img.radioparadise.com/covers/m/B0057OORWG.jpg","cover_small":"https://img.radioparadise.com/covers/s/B0057OORWG.jpg"} Dec 07 16:29:14 volumio volumio[757]: info: CorePlayQueue::getTrack 0 Dec 07 16:29:14 volumio volumio[757]: info: CoreCommandRouter::servicePushState Dec 07 16:29:14 volumio volumio[757]: info: CorePlayQueue::getTrack 0 Dec 07 16:29:14 volumio volumio[757]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/B0057OORWG.jpg","uri":"https://stream.radioparadise.com/mellow-flac","name":"Stormbringer","title":"Stormbringer","artist":"Beck","album":"2006-10-22: Angel Orensanz Center, New York, NY, USA","streaming":true,"disableUiControls":true,"duration":150,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Dec 07 16:29:14 volumio volumio[757]: verbose: CURRENT POSITION 0 Dec 07 16:29:14 volumio volumio[757]: info: CoreStateMachine::syncState stateService play Dec 07 16:29:14 volumio volumio[757]: info: CoreStateMachine::syncState currentStatus stop Dec 07 16:29:14 volumio volumio[757]: info: CoreStateMachine::pushState Dec 07 16:29:14 volumio volumio[757]: info: CorePlayQueue::getTrack 0 Dec 07 16:29:14 volumio volumio[757]: info: CoreCommandRouter::volumioPushState Dec 07 16:29:14 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 07 16:29:14 volumio volumio[757]: info: [1765124954579] [RadioParadise] setting new timer with duration of 150000 seconds. Dec 07 16:29:14 volumio volumio[757]: info: CorePlayQueue::getTrack 0 Dec 07 16:29:14 volumio volumio[757]: info: CorePlayQueue::getTrack 1 Dec 07 16:29:14 volumio volumio[757]: info: sendMpdCommand status took 411 milliseconds Dec 07 16:29:14 volumio volumio[757]: info: ------------------------------ 407ms Dec 07 16:29:14 volumio volumio[757]: info: sendMpdCommand status took 343 milliseconds Dec 07 16:29:14 volumio volumio[757]: verbose: ControllerMpd::parseState Dec 07 16:29:14 volumio volumio[757]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 16:29:14 volumio volumio[757]: verbose: ControllerMpd::parseState Dec 07 16:29:14 volumio volumio[757]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 16:29:14 volumio volumio[757]: info: CoreCommandRouter::volumioGetQueue Dec 07 16:29:14 volumio volumio[757]: info: CoreStateMachine::getQueue Dec 07 16:29:14 volumio volumio[757]: info: CorePlayQueue::getQueue Dec 07 16:29:14 volumio volumio[757]: info: sendMpdCommand playlistinfo took 237 milliseconds Dec 07 16:29:14 volumio volumio[757]: info: sendMpdCommand playlistinfo took 227 milliseconds Dec 07 16:29:14 volumio volumio[757]: verbose: ControllerMpd::parseTrackInfo Dec 07 16:29:14 volumio volumio[757]: verbose: ControllerMpd::parseTrackInfo Dec 07 16:29:14 volumio volumio[757]: info: ControllerMpd::pushState Dec 07 16:29:14 volumio volumio[757]: info: CoreCommandRouter::servicePushState Dec 07 16:29:15 volumio volumio[757]: info: CorePlayQueue::getTrack 0 Dec 07 16:29:15 volumio volumio[757]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Dec 07 16:29:15 volumio volumio[757]: info: ControllerMpd::pushState Dec 07 16:29:15 volumio volumio[757]: info: CoreCommandRouter::servicePushState Dec 07 16:29:15 volumio volumio[757]: info: CorePlayQueue::getTrack 0 Dec 07 16:29:15 volumio volumio[757]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Dec 07 16:29:15 volumio volumio[757]: info: ------------------------------ 864ms Dec 07 16:29:15 volumio volumio[757]: info: ------------------------------ 762ms Dec 07 16:29:19 volumio volumio[757]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 07 16:29:19 volumio volumio[757]: info: CoreStateMachine::ClearQueue Dec 07 16:29:19 volumio volumio[757]: info: CoreStateMachine::stop Dec 07 16:29:19 volumio volumio[757]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 07 16:29:19 volumio volumio[757]: verbose: UNSET VOLATILE Dec 07 16:29:19 volumio volumio[757]: info: CoreStateMachine::stPlaybackTimer Dec 07 16:29:19 volumio volumio[757]: info: CoreStateMachine::updateTrackBlock Dec 07 16:29:19 volumio volumio[757]: info: CorePlayQueue::getTrackBlock Dec 07 16:29:19 volumio volumio[757]: info: CoreStateMachine::pushState Dec 07 16:29:19 volumio volumio[757]: info: CorePlayQueue::getTrack 0 Dec 07 16:29:19 volumio volumio[757]: info: CoreCommandRouter::volumioPushState Dec 07 16:29:19 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 07 16:29:19 volumio volumio[757]: info: CoreStateMachine::serviceStop Dec 07 16:29:19 volumio volumio[757]: info: CorePlayQueue::getTrack 0 Dec 07 16:29:19 volumio volumio[757]: info: CoreCommandRouter::serviceStop Dec 07 16:29:19 volumio volumio[757]: info: ControllerMpd::stop Dec 07 16:29:19 volumio volumio[757]: verbose: ControllerMpd::sendMpdCommand stop Dec 07 16:29:19 volumio volumio[757]: info: CorePlayQueue::clearPlayQueue Dec 07 16:29:19 volumio volumio[757]: info: CorePlayQueue::saveQueue Dec 07 16:29:19 volumio volumio[757]: info: CoreCommandRouter::volumioPushState Dec 07 16:29:19 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 07 16:29:19 volumio volumio[757]: info: CoreCommandRouter::volumioPushQueue Dec 07 16:29:19 volumio volumio[757]: info: CoreStateMachine::addQueueItems Dec 07 16:29:19 volumio volumio[757]: info: CorePlayQueue::addQueueItems Dec 07 16:29:19 volumio volumio[757]: info: Adding Item to queue: webrp/0 Dec 07 16:29:19 volumio volumio[757]: info: Exploding uri webrp/0 in service radio_paradise Dec 07 16:29:19 volumio volumio[757]: info: CorePlayQueue::saveQueue Dec 07 16:29:19 volumio volumio[757]: info: CoreCommandRouter::volumioPushQueue Dec 07 16:29:19 volumio volumio[757]: info: CoreStateMachine::updateTrackBlock Dec 07 16:29:19 volumio volumio[757]: info: CorePlayQueue::getTrackBlock Dec 07 16:29:19 volumio volumio[757]: info: CoreCommandRouter::volumioPlay Dec 07 16:29:19 volumio volumio[757]: verbose: UNSET VOLATILE Dec 07 16:29:19 volumio volumio[757]: info: CoreStateMachine::play index 0 Dec 07 16:29:19 volumio volumio[757]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 07 16:29:19 volumio volumio[757]: info: CoreStateMachine::stop Dec 07 16:29:19 volumio volumio[757]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 07 16:29:19 volumio volumio[757]: verbose: UNSET VOLATILE Dec 07 16:29:19 volumio volumio[757]: info: CoreStateMachine::play index undefined Dec 07 16:29:19 volumio volumio[757]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 07 16:29:19 volumio volumio[757]: info: CorePlayQueue::getTrack 0 Dec 07 16:29:19 volumio volumio[757]: info: CoreStateMachine::startPlaybackTimer Dec 07 16:29:19 volumio volumio[757]: info: CorePlayQueue::getTrack 0 Dec 07 16:29:19 volumio volumio[757]: verbose: ControllerMpd::sendMpdCommand stop Dec 07 16:29:20 volumio volumio[757]: info: Dec 07 16:29:20 volumio volumio[757]: ---------------------------- MPD announces state update: player Dec 07 16:29:20 volumio volumio[757]: info: sendMpdCommand stop took 431 milliseconds Dec 07 16:29:20 volumio volumio[757]: info: ControllerMpd::getState Dec 07 16:29:20 volumio volumio[757]: verbose: ControllerMpd::sendMpdCommand status Dec 07 16:29:20 volumio volumio[757]: info: sendMpdCommand stop took 140 milliseconds Dec 07 16:29:20 volumio volumio[757]: info: CoreCommandRouter::servicePushState Dec 07 16:29:20 volumio volumio[757]: info: CoreStateMachine::pushState Dec 07 16:29:20 volumio volumio[757]: info: CorePlayQueue::getTrack 0 Dec 07 16:29:20 volumio volumio[757]: info: CoreCommandRouter::volumioPushState Dec 07 16:29:20 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 07 16:29:20 volumio volumio[757]: info: CorePlayQueue::getTrack 0 Dec 07 16:29:20 volumio volumio[757]: verbose: STATE SERVICE {"status":"stop","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/B0057OORWG.jpg","uri":"https://stream.radioparadise.com/mellow-flac","name":"Stormbringer","title":"Stormbringer","artist":"Beck","album":"2006-10-22: Angel Orensanz Center, New York, NY, USA","streaming":true,"disableUiControls":true,"duration":150,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Dec 07 16:29:20 volumio volumio[757]: verbose: CURRENT POSITION 0 Dec 07 16:29:20 volumio volumio[757]: info: CoreStateMachine::syncState stateService stop Dec 07 16:29:20 volumio volumio[757]: info: CoreStateMachine::syncState currentStatus stop Dec 07 16:29:20 volumio volumio[757]: info: CoreStateMachine::pushState Dec 07 16:29:20 volumio volumio[757]: info: CorePlayQueue::getTrack 0 Dec 07 16:29:20 volumio volumio[757]: info: CoreCommandRouter::volumioPushState Dec 07 16:29:20 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 07 16:29:20 volumio volumio[757]: info: No code Dec 07 16:29:20 volumio volumio[757]: info: CoreStateMachine::pushState Dec 07 16:29:20 volumio volumio[757]: info: CorePlayQueue::getTrack 0 Dec 07 16:29:20 volumio volumio[757]: info: CoreCommandRouter::volumioPushState Dec 07 16:29:20 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 07 16:29:20 volumio volumio[757]: verbose: ControllerMpd::sendMpdCommand clear Dec 07 16:29:20 volumio volumio[757]: info: Dec 07 16:29:20 volumio volumio[757]: ---------------------------- MPD announces system playlist update Dec 07 16:29:20 volumio volumio[757]: info: Ignoring MPD Status Update Dec 07 16:29:20 volumio volumio[757]: info: sendMpdCommand status took 535 milliseconds Dec 07 16:29:20 volumio volumio[757]: info: sendMpdCommand clear took 131 milliseconds Dec 07 16:29:20 volumio volumio[757]: verbose: ControllerMpd::parseState Dec 07 16:29:20 volumio volumio[757]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 16:29:20 volumio volumio[757]: verbose: ControllerMpd::sendMpdCommand consume 1 Dec 07 16:29:20 volumio volumio[757]: error: updateQueue error: null Dec 07 16:29:20 volumio volumio[757]: info: ------------------------------ 106ms Dec 07 16:29:20 volumio volumio[757]: info: sendMpdCommand playlistinfo took 84 milliseconds Dec 07 16:29:20 volumio volumio[757]: info: sendMpdCommand consume 1 took 91 milliseconds Dec 07 16:29:20 volumio volumio[757]: verbose: ControllerMpd::parseTrackInfo Dec 07 16:29:20 volumio volumio[757]: info: [1765124960749] [RadioParadise] set to consume mode, adding url: https://stream.radioparadise.com/flac Dec 07 16:29:20 volumio volumio[757]: verbose: ControllerMpd::sendMpdCommand add "https://stream.radioparadise.com/flac" Dec 07 16:29:20 volumio volumio[757]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Dec 07 16:29:20 volumio volumio[757]: info: ------------------------------ 705ms Dec 07 16:29:20 volumio volumio[757]: info: Dec 07 16:29:20 volumio volumio[757]: ---------------------------- MPD announces system playlist update Dec 07 16:29:20 volumio volumio[757]: info: Ignoring MPD Status Update Dec 07 16:29:20 volumio volumio[757]: info: sendMpdCommand add "https://stream.radioparadise.com/flac" took 53 milliseconds Dec 07 16:29:20 volumio volumio[757]: verbose: ControllerMpd::sendMpdCommand play Dec 07 16:29:20 volumio volumio[757]: info: ------------------------------ 123ms Dec 07 16:29:20 volumio volumio[757]: info: sendMpdCommand play took 93 milliseconds Dec 07 16:29:20 volumio volumio[757]: info: [1765124960941] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=0 Dec 07 16:29:21 volumio volumio[757]: info: CoreCommandRouter::volumioGetState Dec 07 16:29:21 volumio volumio[757]: info: CorePlayQueue::getTrack 0 Dec 07 16:29:21 volumio volumio[757]: info: [1765124961749] [RadioParadise] received new metadata: {"time":173,"artist":"Nina Simone","title":"Sinnerman (Sofi Tukker Remix)","album":"Feeling Good: Her Greatest Hits and Remixes","year":"2021","cover":"https://img.radioparadise.com/covers/l/19497_10689996-128d-4e47-bb7e-f78fdd1978fe.jpg","cover_med":"https://img.radioparadise.com/covers/m/19497_10689996-128d-4e47-bb7e-f78fdd1978fe.jpg","cover_small":"https://img.radioparadise.com/covers/s/19497_10689996-128d-4e47-bb7e-f78fdd1978fe.jpg"} Dec 07 16:29:21 volumio volumio[757]: info: CorePlayQueue::getTrack 0 Dec 07 16:29:21 volumio volumio[757]: info: CoreCommandRouter::servicePushState Dec 07 16:29:21 volumio volumio[757]: info: CorePlayQueue::getTrack 0 Dec 07 16:29:21 volumio volumio[757]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/19497_10689996-128d-4e47-bb7e-f78fdd1978fe.jpg","uri":"https://stream.radioparadise.com/flac","name":"Sinnerman (Sofi Tukker Remix)","title":"Sinnerman (Sofi Tukker Remix)","artist":"Nina Simone","album":"Feeling Good: Her Greatest Hits and Remixes","streaming":true,"disableUiControls":true,"duration":178,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Dec 07 16:29:21 volumio volumio[757]: verbose: CURRENT POSITION 0 Dec 07 16:29:21 volumio volumio[757]: info: CoreStateMachine::syncState stateService play Dec 07 16:29:21 volumio volumio[757]: info: CoreStateMachine::syncState currentStatus stop Dec 07 16:29:21 volumio volumio[757]: info: CoreStateMachine::pushState Dec 07 16:29:21 volumio volumio[757]: info: CorePlayQueue::getTrack 0 Dec 07 16:29:21 volumio volumio[757]: info: CoreCommandRouter::volumioPushState Dec 07 16:29:21 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 07 16:29:22 volumio volumio[757]: info: [1765124962015] [RadioParadise] setting new timer with duration of 178000 seconds. Dec 07 16:29:22 volumio volumio[757]: info: CorePlayQueue::getTrack 0 Dec 07 16:29:22 volumio volumio[757]: info: CorePlayQueue::getTrack 1 Dec 07 16:29:22 volumio volumio[757]: info: Dec 07 16:29:22 volumio volumio[757]: ---------------------------- MPD announces state update: player Dec 07 16:29:22 volumio volumio[757]: info: ControllerMpd::getState Dec 07 16:29:22 volumio volumio[757]: verbose: ControllerMpd::sendMpdCommand status Dec 07 16:29:22 volumio volumio[757]: info: CoreCommandRouter::volumioGetQueue Dec 07 16:29:22 volumio volumio[757]: info: CoreStateMachine::getQueue Dec 07 16:29:22 volumio volumio[757]: info: CorePlayQueue::getQueue Dec 07 16:29:22 volumio volumio[757]: info: Dec 07 16:29:22 volumio volumio[757]: ---------------------------- MPD announces system playlist update Dec 07 16:29:22 volumio volumio[757]: info: Ignoring MPD Status Update Dec 07 16:29:22 volumio volumio[757]: info: Dec 07 16:29:22 volumio volumio[757]: ---------------------------- MPD announces state update: player Dec 07 16:29:22 volumio volumio[757]: info: ControllerMpd::getState Dec 07 16:29:22 volumio volumio[757]: verbose: ControllerMpd::sendMpdCommand status Dec 07 16:29:22 volumio volumio[757]: info: sendMpdCommand status took 522 milliseconds Dec 07 16:29:22 volumio volumio[757]: verbose: ControllerMpd::parseState Dec 07 16:29:22 volumio volumio[757]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 16:29:22 volumio volumio[757]: info: ------------------------------ 144ms Dec 07 16:29:22 volumio volumio[757]: info: sendMpdCommand status took 115 milliseconds Dec 07 16:29:22 volumio volumio[757]: info: sendMpdCommand playlistinfo took 87 milliseconds Dec 07 16:29:22 volumio volumio[757]: verbose: ControllerMpd::parseState Dec 07 16:29:22 volumio volumio[757]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 16:29:22 volumio volumio[757]: verbose: ControllerMpd::parseTrackInfo Dec 07 16:29:22 volumio volumio[757]: info: ControllerMpd::pushState Dec 07 16:29:22 volumio volumio[757]: info: CoreCommandRouter::servicePushState Dec 07 16:29:22 volumio volumio[757]: info: CorePlayQueue::getTrack 0 Dec 07 16:29:22 volumio volumio[757]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Dec 07 16:29:22 volumio volumio[757]: info: ------------------------------ 760ms Dec 07 16:29:22 volumio volumio[757]: info: sendMpdCommand playlistinfo took 117 milliseconds Dec 07 16:29:22 volumio volumio[757]: verbose: ControllerMpd::parseTrackInfo Dec 07 16:29:22 volumio volumio[757]: info: ControllerMpd::pushState Dec 07 16:29:22 volumio volumio[757]: info: CoreCommandRouter::servicePushState Dec 07 16:29:22 volumio volumio[757]: info: CorePlayQueue::getTrack 0 Dec 07 16:29:22 volumio volumio[757]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Dec 07 16:29:22 volumio volumio[757]: info: ------------------------------ 352ms Dec 07 16:29:26 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 07 16:29:26 volumio volumio[757]: info: CURURI: music-library Dec 07 16:29:27 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 07 16:29:27 volumio volumio[757]: info: CURURI: music-library/NAS Dec 07 16:29:28 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 07 16:29:28 volumio volumio[757]: info: CURURI: music-library/NAS/ds-CH-music Dec 07 16:29:31 volumio volumio[757]: info: CoreCommandRouter::volumioGetState Dec 07 16:29:31 volumio volumio[757]: info: CorePlayQueue::getTrack 0 Dec 07 16:29:31 volumio volumio[757]: info: Listing playlists Dec 07 16:29:31 volumio volumio[757]: info: Listing playlists Dec 07 16:29:34 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 07 16:29:34 volumio volumio[757]: info: CURURI: music-library/NAS/ds-CH-music/Camel Dec 07 16:29:39 volumio volumio[757]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 07 16:29:39 volumio volumio[757]: info: CoreStateMachine::ClearQueue Dec 07 16:29:39 volumio volumio[757]: info: CoreStateMachine::stop Dec 07 16:29:39 volumio volumio[757]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 07 16:29:39 volumio volumio[757]: verbose: UNSET VOLATILE Dec 07 16:29:39 volumio volumio[757]: info: CoreStateMachine::stPlaybackTimer Dec 07 16:29:39 volumio volumio[757]: info: CoreStateMachine::updateTrackBlock Dec 07 16:29:39 volumio volumio[757]: info: CorePlayQueue::getTrackBlock Dec 07 16:29:39 volumio volumio[757]: info: CoreStateMachine::pushState Dec 07 16:29:39 volumio volumio[757]: info: CorePlayQueue::getTrack 0 Dec 07 16:29:39 volumio volumio[757]: info: CoreCommandRouter::volumioPushState Dec 07 16:29:39 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 07 16:29:39 volumio volumio[757]: info: CoreStateMachine::serviceStop Dec 07 16:29:39 volumio volumio[757]: info: CorePlayQueue::getTrack 0 Dec 07 16:29:39 volumio volumio[757]: info: CoreCommandRouter::serviceStop Dec 07 16:29:39 volumio volumio[757]: info: ControllerMpd::stop Dec 07 16:29:39 volumio volumio[757]: verbose: ControllerMpd::sendMpdCommand stop Dec 07 16:29:39 volumio volumio[757]: info: CorePlayQueue::clearPlayQueue Dec 07 16:29:39 volumio volumio[757]: info: CorePlayQueue::saveQueue Dec 07 16:29:39 volumio volumio[757]: info: CoreCommandRouter::volumioPushState Dec 07 16:29:39 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 07 16:29:39 volumio volumio[757]: info: CoreCommandRouter::volumioPushQueue Dec 07 16:29:39 volumio volumio[757]: info: CoreStateMachine::addQueueItems Dec 07 16:29:39 volumio volumio[757]: info: CorePlayQueue::addQueueItems Dec 07 16:29:39 volumio volumio[757]: info: Adding Item to queue: music-library/NAS/ds-CH-music/Camel/Camel - Mirage Dec 07 16:29:39 volumio volumio[757]: info: Exploding uri music-library/NAS/ds-CH-music/Camel/Camel - Mirage in service mpd Dec 07 16:29:40 volumio volumio[757]: info: Dec 07 16:29:40 volumio volumio[757]: ---------------------------- MPD announces state update: player Dec 07 16:29:40 volumio volumio[757]: info: ALBUMART /albumart?cacheid=4&web=//extralarge&path=%2Fmnt%2FNAS%2Fds-CH-music%2FCamel%2FCamel%20-%20Mirage%2F01.%20Freefall.dts&metadata=false Dec 07 16:29:40 volumio volumio[757]: info: URI /mnt/NAS/ds-CH-music/Camel/Camel - Mirage/01. Freefall.dts Dec 07 16:29:40 volumio volumio[757]: info: ALBUMART /albumart?cacheid=4&web=//extralarge&path=%2Fmnt%2FNAS%2Fds-CH-music%2FCamel%2FCamel%20-%20Mirage%2F02.%20Supertwister.m4a&metadata=false Dec 07 16:29:40 volumio volumio[757]: info: URI /mnt/NAS/ds-CH-music/Camel/Camel - Mirage/02. Supertwister.m4a Dec 07 16:29:40 volumio volumio[757]: info: ALBUMART /albumart?cacheid=4&web=//extralarge&path=%2Fmnt%2FNAS%2Fds-CH-music%2FCamel%2FCamel%20-%20Mirage%2F03.%20Nimrodel-The%20Procession-The%20White%20Rider.m4a&metadata=false Dec 07 16:29:40 volumio volumio[757]: info: URI /mnt/NAS/ds-CH-music/Camel/Camel - Mirage/03. Nimrodel-The Procession-The White Rider.m4a Dec 07 16:29:40 volumio volumio[757]: info: ALBUMART /albumart?cacheid=4&web=//extralarge&path=%2Fmnt%2FNAS%2Fds-CH-music%2FCamel%2FCamel%20-%20Mirage%2F04.%20Earthrise.m4a&metadata=false Dec 07 16:29:40 volumio volumio[757]: info: URI /mnt/NAS/ds-CH-music/Camel/Camel - Mirage/04. Earthrise.m4a Dec 07 16:29:40 volumio volumio[757]: info: ALBUMART /albumart?cacheid=4&web=//extralarge&path=%2Fmnt%2FNAS%2Fds-CH-music%2FCamel%2FCamel%20-%20Mirage%2F05.%20Lady%20Fantasy-%20Encounter-Smiles%20For%20You-Lady%20Fantasy.m4a&metadata=false Dec 07 16:29:40 volumio volumio[757]: info: URI /mnt/NAS/ds-CH-music/Camel/Camel - Mirage/05. Lady Fantasy- Encounter-Smiles For You-Lady Fantasy.m4a Dec 07 16:29:40 volumio volumio[757]: info: ALBUMART /albumart?cacheid=4&web=//extralarge&path=%2Fmnt%2FNAS%2Fds-CH-music%2FCamel%2FCamel%20-%20Mirage%2F06.%20Supertwister%20(live%20at%20The%20Marquee%20Club)%20(previously%20unreleased).m4a&metadata=false Dec 07 16:29:40 volumio volumio[757]: info: URI /mnt/NAS/ds-CH-music/Camel/Camel - Mirage/06. Supertwister (live at The Marquee Club) (previously unreleased).m4a Dec 07 16:29:40 volumio volumio[757]: info: ALBUMART /albumart?cacheid=4&web=//extralarge&path=%2Fmnt%2FNAS%2Fds-CH-music%2FCamel%2FCamel%20-%20Mirage%2F07.%20Mystic%20Queen%20(live%20at%20The%20Marquee%20Club)%20(previously%20unreleased).m4a&metadata=false Dec 07 16:29:40 volumio volumio[757]: info: URI /mnt/NAS/ds-CH-music/Camel/Camel - Mirage/07. Mystic Queen (live at The Marquee Club) (previously unreleased).m4a Dec 07 16:29:40 volumio volumio[757]: info: ALBUMART /albumart?cacheid=4&web=//extralarge&path=%2Fmnt%2FNAS%2Fds-CH-music%2FCamel%2FCamel%20-%20Mirage%2F08.%20Arubaluba%20(live%20at%20The%20Marquee%20Club)%20(previously%20unreleased).m4a&metadata=false Dec 07 16:29:40 volumio volumio[757]: info: URI /mnt/NAS/ds-CH-music/Camel/Camel - Mirage/08. Arubaluba (live at The Marquee Club) (previously unreleased).m4a Dec 07 16:29:40 volumio volumio[757]: info: ALBUMART /albumart?cacheid=4&web=//extralarge&path=%2Fmnt%2FNAS%2Fds-CH-music%2FCamel%2FCamel%20-%20Mirage%2F09.%20Lady%20Fantasy%20Suite-%20Encounter-Smiles%20For%20You-Lady%20Fantasy%20(previously%20unreleased).m4a&metadata=false Dec 07 16:29:40 volumio volumio[757]: info: URI /mnt/NAS/ds-CH-music/Camel/Camel - Mirage/09. Lady Fantasy Suite- Encounter-Smiles For You-Lady Fantasy (previously unreleased).m4a Dec 07 16:29:40 volumio volumio[757]: info: ALBUMART /albumart?cacheid=4&web=//extralarge&path=%2Fmnt%2FNAS%2Fds-CH-music%2FCamel%2FCamel%20-%20Mirage%2FCamel%20-%20Mirage.zip&metadata=false Dec 07 16:29:40 volumio volumio[757]: info: URI /mnt/NAS/ds-CH-music/Camel/Camel - Mirage/Camel - Mirage.zip Dec 07 16:29:40 volumio volumio[757]: info: ALBUMART /albumart?cacheid=4&web=//extralarge&path=%2Fmnt%2FNAS%2Fds-CH-music%2FCamel%2FCamel%20-%20Mirage%2FCamel%20-%20Mirage.zip&metadata=false Dec 07 16:29:40 volumio volumio[757]: info: URI /mnt/NAS/ds-CH-music/Camel/Camel - Mirage/Camel - Mirage.zip Dec 07 16:29:40 volumio volumio[757]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 07 16:29:40 volumio volumio[757]: Error: Unable to resolve or reject the same promise twice Dec 07 16:29:40 volumio volumio[757]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Dec 07 16:29:40 volumio volumio[757]: at /volumio/app/plugins/music_service/mpd/index.js:2520:21 Dec 07 16:29:40 volumio volumio[757]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Dec 07 16:29:40 volumio volumio[757]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Dec 07 16:29:40 volumio volumio[757]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Dec 07 16:29:40 volumio volumio[757]: at emitOne (events.js:116:13) Dec 07 16:29:40 volumio volumio[757]: at Socket.emit (events.js:211:7) Dec 07 16:29:40 volumio volumio[757]: at addChunk (_stream_readable.js:263:12) Dec 07 16:29:40 volumio volumio[757]: at readableAddChunk (_stream_readable.js:246:13) Dec 07 16:29:40 volumio volumio[757]: at Socket.Readable.push (_stream_readable.js:208:10) Dec 07 16:29:40 volumio volumio[757]: at Pipe.onread (net.js:607:20) Dec 07 16:29:40 volumio volumio[757]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 07 16:29:45 volumio sudo[1173]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-07 16:28 Dec 07 16:29:45 volumio sudo[1173]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="958dedc0edff114f244fa9acc70cf53089d9e5ae" VOLUMIO_BE_VERSION="fd3e37ffec12db034ec93f94dbc26e5495d5b91a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Oct 6 12:34:49 CEST 2021" VOLUMIO_VERSION="2.917" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="98c8f08c531a6f0456f17ab17795e35f"