-- Logs begin at Wed 2021-10-06 10:20:30 UTC, end at Wed 2024-08-28 12:42:48 UTC. -- Aug 28 12:41:00 volumio volumio[995]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Aug 28 12:41:01 volumio volumio[995]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Aug 28 12:41:01 volumio volumio[995]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Aug 28 12:41:01 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 12:41:01 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 12:41:01 volumio volumio[995]: info: Starting MyVolumio Remote Streaming Endpoints Aug 28 12:41:01 volumio volumio[995]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Aug 28 12:41:01 volumio volumio[995]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Aug 28 12:41:01 volumio volumio[995]: info: Streaming services startup Aug 28 12:41:01 volumio volumio[995]: info: Starting Streaming Daemon Aug 28 12:41:01 volumio sudo[1359]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 28 12:41:01 volumio sudo[1359]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 12:41:01 volumio volumio[995]: info: MyVolumio login type: Token Aug 28 12:41:01 volumio volumio[995]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Aug 28 12:41:01 volumio systemd[1]: Stopping Volumio Streaming Daemon... Aug 28 12:41:01 volumio systemd[1]: Starting Volumio Streaming Daemon... Aug 28 12:41:01 volumio systemd[1]: Started Volumio Streaming Daemon. Aug 28 12:41:01 volumio sudo[1359]: pam_unix(sudo:session): session closed for user root Aug 28 12:41:01 volumio dbus[783]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' Aug 28 12:41:01 volumio systemd[1]: Starting RealtimeKit Scheduling Policy Service... Aug 28 12:41:01 volumio volumio-streaming-daemon[1371]: ############################ Aug 28 12:41:01 volumio volumio-streaming-daemon[1371]: # Volumio Streaming Daemon # Aug 28 12:41:01 volumio volumio-streaming-daemon[1371]: # Running on port 7777 # Aug 28 12:41:01 volumio volumio-streaming-daemon[1371]: ############################ Aug 28 12:41:01 volumio volumio-streaming-daemon[1371]: Environment: production Aug 28 12:41:01 volumio volumio[995]: info: Connecting to daemon Aug 28 12:41:01 volumio dbus[783]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Aug 28 12:41:01 volumio systemd[1]: Started RealtimeKit Scheduling Policy Service. Aug 28 12:41:01 volumio rtkit-daemon[1382]: Successfully called chroot. Aug 28 12:41:01 volumio rtkit-daemon[1382]: Successfully dropped privileges. Aug 28 12:41:01 volumio rtkit-daemon[1382]: Successfully limited resources. Aug 28 12:41:01 volumio rtkit-daemon[1382]: Running. Aug 28 12:41:01 volumio rtkit-daemon[1382]: Canary thread running. Aug 28 12:41:01 volumio rtkit-daemon[1382]: Watchdog thread running. Aug 28 12:41:01 volumio volumio[995]: error: SPOP command error: Error: connect ECONNREFUSED 127.0.0.1:6602 Aug 28 12:41:01 volumio volumio[995]: error: SPOP status error: Error: connect ECONNREFUSED 127.0.0.1:6602 Aug 28 12:41:01 volumio volumio[995]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSpotify Aug 28 12:41:01 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 28 12:41:01 volumio udisks-glue[698]: error: Timeout Aug 28 12:41:01 volumio pulseaudio[1374]: Unable to contact D-Bus: org.freedesktop.DBus.Error.Spawn.ExecFailed: /usr/bin/dbus-launch terminated abnormally without any error message Aug 28 12:41:02 volumio pulseaudio[1374]: Unable to contact D-Bus: org.freedesktop.DBus.Error.Spawn.ExecFailed: /usr/bin/dbus-launch terminated abnormally without any error message Aug 28 12:41:02 volumio bluetoothd[857]: Endpoint registered: sender=:1.20 path=/MediaEndpoint/A2DPSource Aug 28 12:41:02 volumio bluetoothd[857]: Endpoint registered: sender=:1.20 path=/MediaEndpoint/A2DPSink Aug 28 12:41:02 volumio pulseaudio[1374]: org.bluez.Manager.GetProperties() failed: org.freedesktop.DBus.Error.UnknownMethod: Method "GetProperties" with signature "" on interface "org.bluez.Manager" doesn't exist Aug 28 12:41:02 volumio volumio[995]: info: An error occurred while refreshing Spotify Token Error: Bad Request Aug 28 12:41:02 volumio volumio[995]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Aug 28 12:41:02 volumio volumio[995]: info: MYVOLUMIO: Adding device Aug 28 12:41:02 volumio volumio[995]: info: MYVOLUMIO: Evaluating Server Aug 28 12:41:03 volumio volumio[995]: info: MyVolumio status changed Aug 28 12:41:03 volumio volumio[995]: info: Streaming services startup Aug 28 12:41:03 volumio volumio[995]: info: Re-Starting Streaming Daemon Aug 28 12:41:03 volumio volumio[995]: info: Removing browser output: myVolumio user plan is not superstar Aug 28 12:41:03 volumio volumio[995]: info: Removing audio output: Aug 28 12:41:03 volumio volumio[995]: info: Stoppping Tunnel 1 Aug 28 12:41:03 volumio sudo[1433]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Aug 28 12:41:03 volumio sudo[1433]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 12:41:03 volumio systemd[1]: Stopped MyVolumio SSH Tunnel. Aug 28 12:41:03 volumio sudo[1433]: pam_unix(sudo:session): session closed for user root Aug 28 12:41:03 volumio volumio-streaming-daemon[1371]: Restarting daemon Aug 28 12:41:03 volumio volumio-streaming-daemon[1371]: Environment: production Aug 28 12:41:03 volumio volumio[995]: info: Remote SSH Stopped Aug 28 12:41:03 volumio volumio[995]: info: Setting Geolocation for MyVolumio to as1 Aug 28 12:41:03 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 12:41:03 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 12:41:03 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 12:41:04 volumio volumio-streaming-daemon[1371]: Environment: production Aug 28 12:41:04 volumio volumio-streaming-daemon[1371]: Environment: production Aug 28 12:41:04 volumio volumio[995]: info: Fetching Streaming Services browse cache Aug 28 12:41:04 volumio volumio[995]: info: Fetching Streaming Services browse cache Aug 28 12:41:05 volumio volumio[995]: info: Updating MyVolumio device info Aug 28 12:41:05 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 12:41:05 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 12:41:05 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 12:41:14 volumio volumio[995]: info: CoreCommandRouter::volumioGetVisibleSources Aug 28 12:41:14 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 28 12:41:14 volumio volumio[995]: info: CoreCommandRouter::volumioGetState Aug 28 12:41:14 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Aug 28 12:41:14 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 28 12:41:14 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 28 12:41:14 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 28 12:41:14 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 28 12:41:19 volumio volumio[995]: info: CoreCommandRouter::volumioGetState Aug 28 12:41:19 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Aug 28 12:41:19 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 28 12:41:19 volumio volumio[995]: info: Listing playlists Aug 28 12:41:19 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 28 12:41:19 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 28 12:41:19 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 28 12:41:19 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 28 12:41:20 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 28 12:41:20 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 12:41:24 volumio systemd[1]: Started Music Player Daemon. Aug 28 12:41:24 volumio systemd[1]: Starting Multi-User System. Aug 28 12:41:24 volumio systemd[1]: Reached target Multi-User System. Aug 28 12:41:24 volumio systemd[1]: Starting Graphical Interface. Aug 28 12:41:24 volumio systemd[1]: Reached target Graphical Interface. Aug 28 12:41:24 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Aug 28 12:41:24 volumio volumio[995]: error: updateQueue error: null Aug 28 12:41:24 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Aug 28 12:41:24 volumio systemd[1]: Startup finished in 9.517s (kernel) + 1min 2.113s (userspace) = 1min 11.631s. Aug 28 12:41:29 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 28 12:41:40 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 28 12:41:43 volumio volumio[995]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Aug 28 12:41:43 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Aug 28 12:41:43 volumio volumio[995]: info: Error : CoreCommandRouter::executeOnPlugin: No method [retreiveBackendEventStates] in plugin my_volumio Aug 28 12:41:44 volumio volumio[995]: info: Received Get System Version Aug 28 12:41:44 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 28 12:42:05 volumio volumio[995]: Cannot compose Albumart path Aug 28 12:42:05 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 28 12:42:05 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 28 12:42:05 volumio volumio[995]: info: Discovery: Getting this device information Aug 28 12:42:05 volumio volumio[995]: info: CoreCommandRouter::volumioGetState Aug 28 12:42:05 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Aug 28 12:42:05 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Aug 28 12:42:05 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getConf Aug 28 12:42:05 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getConf Aug 28 12:42:05 volumio volumio[995]: info: CoreCommandRouter::volumioGetState Aug 28 12:42:05 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Aug 28 12:42:05 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 28 12:42:05 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 28 12:42:12 volumio sudo[1595]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 28 12:42:12 volumio sudo[1597]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 28 12:42:12 volumio sudo[1595]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 12:42:12 volumio sudo[1597]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 12:42:12 volumio sudo[1595]: pam_unix(sudo:session): session closed for user root Aug 28 12:42:12 volumio sudo[1597]: pam_unix(sudo:session): session closed for user root Aug 28 12:42:12 volumio volumio[995]: info: CoreCommandRouter::volumioGetState Aug 28 12:42:12 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Aug 28 12:42:12 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 28 12:42:12 volumio volumio[995]: info: Listing playlists Aug 28 12:42:12 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 28 12:42:12 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 28 12:42:12 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 28 12:42:12 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 28 12:42:14 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 28 12:42:14 volumio volumio[995]: info: CoreCommandRouter::volumioPlay Aug 28 12:42:14 volumio volumio[995]: verbose: UNSET VOLATILE Aug 28 12:42:14 volumio volumio[995]: info: CoreStateMachine::play index undefined Aug 28 12:42:14 volumio volumio[995]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 28 12:42:14 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Aug 28 12:42:14 volumio volumio[995]: info: CoreStateMachine::startPlaybackTimer Aug 28 12:42:14 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Aug 28 12:42:14 volumio volumio[995]: info: [1724848934620] ControllerWebradio::clearAddPlayTrack Aug 28 12:42:14 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand stop Aug 28 12:42:14 volumio volumio[995]: info: sendMpdCommand stop took 10 milliseconds Aug 28 12:42:14 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand clear Aug 28 12:42:14 volumio volumio[995]: info: Aug 28 12:42:14 volumio volumio[995]: ---------------------------- MPD announces system playlist update Aug 28 12:42:14 volumio volumio[995]: info: Ignoring MPD Status Update Aug 28 12:42:14 volumio volumio[995]: info: sendMpdCommand clear took 3 milliseconds Aug 28 12:42:14 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand load "http://serpent0.duckdns.org:8088/kbsfm.pls" Aug 28 12:42:14 volumio volumio[995]: error: updateQueue error: null Aug 28 12:42:14 volumio volumio[995]: info: ------------------------------ 9ms Aug 28 12:42:15 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 28 12:42:24 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand add "http://serpent0.duckdns.org:8088/kbsfm.pls" Aug 28 12:42:24 volumio volumio[995]: info: Aug 28 12:42:24 volumio volumio[995]: ---------------------------- MPD announces system playlist update Aug 28 12:42:24 volumio volumio[995]: info: Ignoring MPD Status Update Aug 28 12:42:24 volumio volumio[995]: info: sendMpdCommand add "http://serpent0.duckdns.org:8088/kbsfm.pls" took 3 milliseconds Aug 28 12:42:24 volumio volumio[995]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 28 12:42:24 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand play Aug 28 12:42:24 volumio volumio[995]: info: ------------------------------ 8ms Aug 28 12:42:24 volumio volumio[995]: info: sendMpdCommand play took 3 milliseconds Aug 28 12:42:25 volumio volumio[995]: info: Aug 28 12:42:25 volumio volumio[995]: ---------------------------- MPD announces state update: player Aug 28 12:42:25 volumio volumio[995]: info: ControllerMpd::getState Aug 28 12:42:25 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand status Aug 28 12:42:25 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand clearerror Aug 28 12:42:25 volumio volumio[995]: info: sendMpdCommand status took 7 milliseconds Aug 28 12:42:25 volumio volumio[995]: verbose: ControllerMpd::parseState Aug 28 12:42:25 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 28 12:42:25 volumio volumio[995]: info: sendMpdCommand clearerror took 6 milliseconds Aug 28 12:42:25 volumio volumio[995]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 28 12:42:25 volumio volumio[995]: verbose: ControllerMpd::parseTrackInfo Aug 28 12:42:25 volumio volumio[995]: info: ControllerMpd::pushState Aug 28 12:42:25 volumio volumio[995]: info: CoreCommandRouter::servicePushState Aug 28 12:42:25 volumio volumio[995]: info: CoreStateMachine::pushState Aug 28 12:42:25 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Aug 28 12:42:25 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 12:42:25 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Aug 28 12:42:25 volumio volumio[995]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"kbsfm.pls","artist":null,"album":null,"uri":"http://serpent0.duckdns.org:8088/kbsfm.pls","trackType":"pls"} Aug 28 12:42:25 volumio volumio[995]: verbose: CURRENT POSITION 0 Aug 28 12:42:25 volumio volumio[995]: info: CoreStateMachine::syncState stateService stop Aug 28 12:42:25 volumio volumio[995]: info: CoreStateMachine::syncState currentStatus stop Aug 28 12:42:25 volumio volumio[995]: info: CoreStateMachine::pushState Aug 28 12:42:25 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Aug 28 12:42:25 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 12:42:25 volumio volumio[995]: info: No code Aug 28 12:42:25 volumio volumio[995]: info: CoreStateMachine::pushState Aug 28 12:42:25 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Aug 28 12:42:25 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 12:42:25 volumio volumio[995]: info: ------------------------------ 62ms Aug 28 12:42:27 volumio volumio[995]: info: CoreCommandRouter::volumioGetVisibleSources Aug 28 12:42:27 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 28 12:42:27 volumio volumio[995]: info: CoreCommandRouter::volumioGetQueue Aug 28 12:42:27 volumio volumio[995]: info: CoreStateMachine::getQueue Aug 28 12:42:27 volumio volumio[995]: info: CorePlayQueue::getQueue Aug 28 12:42:30 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Aug 28 12:42:31 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Aug 28 12:42:31 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 28 12:42:31 volumio volumio[995]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Aug 28 12:42:31 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Aug 28 12:42:31 volumio volumio[995]: info: Error : CoreCommandRouter::executeOnPlugin: No method [retreiveBackendEventStates] in plugin my_volumio Aug 28 12:42:31 volumio volumio[995]: info: Received Get System Version Aug 28 12:42:31 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 28 12:42:31 volumio volumio[995]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 28 12:42:31 volumio volumio[995]: info: CoreStateMachine::ClearQueue Aug 28 12:42:31 volumio volumio[995]: info: CoreStateMachine::stop Aug 28 12:42:31 volumio volumio[995]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 28 12:42:31 volumio volumio[995]: verbose: UNSET VOLATILE Aug 28 12:42:31 volumio volumio[995]: info: CorePlayQueue::clearPlayQueue Aug 28 12:42:31 volumio volumio[995]: info: CorePlayQueue::saveQueue Aug 28 12:42:31 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Aug 28 12:42:31 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 12:42:31 volumio volumio[995]: info: CoreCommandRouter::volumioPushQueue Aug 28 12:42:31 volumio volumio[995]: info: CoreStateMachine::addQueueItems Aug 28 12:42:31 volumio volumio[995]: info: CorePlayQueue::addQueueItems Aug 28 12:42:31 volumio volumio[995]: info: Adding Item to queue: http://serpent0.duckdns.org:8088/kbsfm.pls Aug 28 12:42:31 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Aug 28 12:42:31 volumio volumio[995]: info: CorePlayQueue::saveQueue Aug 28 12:42:31 volumio volumio[995]: info: CoreCommandRouter::volumioPushQueue Aug 28 12:42:31 volumio volumio[995]: info: CoreStateMachine::updateTrackBlock Aug 28 12:42:31 volumio volumio[995]: info: CorePlayQueue::getTrackBlock Aug 28 12:42:31 volumio volumio[995]: info: CoreCommandRouter::volumioPlay Aug 28 12:42:31 volumio volumio[995]: verbose: UNSET VOLATILE Aug 28 12:42:31 volumio volumio[995]: info: CoreStateMachine::play index 0 Aug 28 12:42:31 volumio volumio[995]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 28 12:42:31 volumio volumio[995]: info: CoreStateMachine::stop Aug 28 12:42:31 volumio volumio[995]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 28 12:42:31 volumio volumio[995]: verbose: UNSET VOLATILE Aug 28 12:42:31 volumio volumio[995]: info: CoreStateMachine::play index undefined Aug 28 12:42:31 volumio volumio[995]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 28 12:42:31 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Aug 28 12:42:31 volumio volumio[995]: info: CoreStateMachine::startPlaybackTimer Aug 28 12:42:31 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Aug 28 12:42:31 volumio volumio[995]: info: [1724848951991] ControllerWebradio::clearAddPlayTrack Aug 28 12:42:31 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand stop Aug 28 12:42:32 volumio volumio[995]: info: sendMpdCommand stop took 18 milliseconds Aug 28 12:42:32 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand clear Aug 28 12:42:32 volumio volumio[995]: info: Aug 28 12:42:32 volumio volumio[995]: ---------------------------- MPD announces system playlist update Aug 28 12:42:32 volumio volumio[995]: info: Ignoring MPD Status Update Aug 28 12:42:32 volumio volumio[995]: info: sendMpdCommand clear took 4 milliseconds Aug 28 12:42:32 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand load "http://serpent0.duckdns.org:8088/kbsfm.pls" Aug 28 12:42:32 volumio volumio[995]: error: updateQueue error: null Aug 28 12:42:32 volumio volumio[995]: info: ------------------------------ 6ms Aug 28 12:42:42 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand add "http://serpent0.duckdns.org:8088/kbsfm.pls" Aug 28 12:42:42 volumio volumio[995]: info: Aug 28 12:42:42 volumio volumio[995]: ---------------------------- MPD announces system playlist update Aug 28 12:42:42 volumio volumio[995]: info: Ignoring MPD Status Update Aug 28 12:42:42 volumio volumio[995]: info: sendMpdCommand add "http://serpent0.duckdns.org:8088/kbsfm.pls" took 2 milliseconds Aug 28 12:42:42 volumio volumio[995]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 28 12:42:42 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand play Aug 28 12:42:42 volumio volumio[995]: info: ------------------------------ 4ms Aug 28 12:42:42 volumio volumio[995]: info: sendMpdCommand play took 3 milliseconds Aug 28 12:42:43 volumio volumio[995]: info: Aug 28 12:42:43 volumio volumio[995]: ---------------------------- MPD announces state update: player Aug 28 12:42:43 volumio volumio[995]: info: ControllerMpd::getState Aug 28 12:42:43 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand status Aug 28 12:42:43 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand clearerror Aug 28 12:42:43 volumio volumio[995]: info: sendMpdCommand status took 5 milliseconds Aug 28 12:42:43 volumio volumio[995]: verbose: ControllerMpd::parseState Aug 28 12:42:43 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 28 12:42:43 volumio volumio[995]: info: sendMpdCommand clearerror took 8 milliseconds Aug 28 12:42:43 volumio volumio[995]: info: sendMpdCommand playlistinfo took 6 milliseconds Aug 28 12:42:43 volumio volumio[995]: verbose: ControllerMpd::parseTrackInfo Aug 28 12:42:43 volumio volumio[995]: info: ControllerMpd::pushState Aug 28 12:42:43 volumio volumio[995]: info: CoreCommandRouter::servicePushState Aug 28 12:42:43 volumio volumio[995]: info: CoreStateMachine::pushState Aug 28 12:42:43 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Aug 28 12:42:43 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 12:42:43 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Aug 28 12:42:43 volumio volumio[995]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"kbsfm.pls","artist":null,"album":null,"uri":"http://serpent0.duckdns.org:8088/kbsfm.pls","trackType":"pls"} Aug 28 12:42:43 volumio volumio[995]: verbose: CURRENT POSITION 0 Aug 28 12:42:43 volumio volumio[995]: info: CoreStateMachine::syncState stateService stop Aug 28 12:42:43 volumio volumio[995]: info: CoreStateMachine::syncState currentStatus stop Aug 28 12:42:43 volumio volumio[995]: info: CoreStateMachine::pushState Aug 28 12:42:43 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Aug 28 12:42:43 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 12:42:43 volumio volumio[995]: info: No code Aug 28 12:42:43 volumio volumio[995]: info: CoreStateMachine::pushState Aug 28 12:42:43 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Aug 28 12:42:43 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 12:42:43 volumio volumio[995]: info: ------------------------------ 52ms Aug 28 12:42:45 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: personal_radio , handleBrowseUri Aug 28 12:42:46 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: personal_radio , handleBrowseUri Aug 28 12:42:46 volumio volumio[995]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 28 12:42:46 volumio volumio[995]: info: CoreStateMachine::ClearQueue Aug 28 12:42:46 volumio volumio[995]: info: CoreStateMachine::stop Aug 28 12:42:46 volumio volumio[995]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 28 12:42:46 volumio volumio[995]: verbose: UNSET VOLATILE Aug 28 12:42:46 volumio volumio[995]: info: CorePlayQueue::clearPlayQueue Aug 28 12:42:46 volumio volumio[995]: info: CorePlayQueue::saveQueue Aug 28 12:42:46 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Aug 28 12:42:46 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 12:42:46 volumio volumio[995]: info: CoreCommandRouter::volumioPushQueue Aug 28 12:42:46 volumio volumio[995]: info: CoreStateMachine::addQueueItems Aug 28 12:42:46 volumio volumio[995]: info: CorePlayQueue::addQueueItems Aug 28 12:42:46 volumio volumio[995]: info: Adding Item to queue: webkbs/0 Aug 28 12:42:46 volumio volumio[995]: info: Exploding uri webkbs/0 in service personal_radio Aug 28 12:42:47 volumio volumio[995]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 28 12:42:47 volumio volumio[995]: ReferenceError: self is not defined Aug 28 12:42:47 volumio volumio[995]: at ControllerPersonalRadio.errorToast (/data/plugins/music_service/personal_radio/index.js:575:22) Aug 28 12:42:47 volumio volumio[995]: at /data/plugins/music_service/personal_radio/index.js:487:12 Aug 28 12:42:47 volumio volumio[995]: at Request.handleRequestResponse [as _callback] (/data/plugins/music_service/personal_radio/node_modules/unirest/index.js:463:26) Aug 28 12:42:47 volumio volumio[995]: at Request.self.callback (/data/plugins/music_service/personal_radio/node_modules/request/request.js:187:22) Aug 28 12:42:47 volumio volumio[995]: at emitTwo (events.js:126:13) Aug 28 12:42:47 volumio volumio[995]: at Request.emit (events.js:214:7) Aug 28 12:42:47 volumio volumio[995]: at Request. (/data/plugins/music_service/personal_radio/node_modules/request/request.js:1044:10) Aug 28 12:42:47 volumio volumio[995]: at emitOne (events.js:116:13) Aug 28 12:42:47 volumio volumio[995]: at Request.emit (events.js:211:7) Aug 28 12:42:47 volumio volumio[995]: at IncomingMessage. (/data/plugins/music_service/personal_radio/node_modules/request/request.js:965:12) Aug 28 12:42:47 volumio volumio[995]: at emitNone (events.js:111:20) Aug 28 12:42:47 volumio volumio[995]: at IncomingMessage.emit (events.js:208:7) Aug 28 12:42:47 volumio volumio[995]: at endReadableNT (_stream_readable.js:1064:12) Aug 28 12:42:47 volumio volumio[995]: at _combinedTickCallback (internal/process/next_tick.js:138:11) Aug 28 12:42:47 volumio volumio[995]: at process._tickCallback (internal/process/next_tick.js:180:9) Aug 28 12:42:47 volumio volumio[995]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 28 12:42:48 volumio sudo[1682]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-28 12:41 Aug 28 12:42:48 volumio sudo[1682]: 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="307f91893fca011201acba9973e1c41bd3a0ad5d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Sep 30 22:42:09 CEST 2021" VOLUMIO_VERSION="2.915" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="02ee61bb422c79b6e5c0b6d1847a78d9"