-- Logs begin at Thu 2019-02-14 18:11:59 +08, end at Wed 2025-07-09 20:35:18 +08. -- Jul 09 20:34:21 volumio ntpd[707]: Soliciting pool server 47.254.196.78 Jul 09 20:34:28 volumio go-librespot[2654]: time="2025-07-09T20:34:28+08:00" level=trace msg="sent dealer ping" Jul 09 20:34:28 volumio go-librespot[2654]: time="2025-07-09T20:34:28+08:00" level=trace msg="received dealer pong" Jul 09 20:34:32 volumio ntpd[707]: Soliciting pool server 47.254.196.78 Jul 09 20:34:33 volumio volumio[939]: verbose: New Socket.io Connection to 192.168.8.163 from 192.168.8.185 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 8 Jul 09 20:34:33 volumio volumio[939]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 09 20:34:33 volumio volumio[939]: info: CoreCommandRouter::volumioGetVisibleSources Jul 09 20:34:33 volumio volumio[939]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 09 20:34:33 volumio volumio[939]: info: CoreCommandRouter::volumioGetState Jul 09 20:34:33 volumio volumio[939]: info: CorePlayQueue::getTrack 1 Jul 09 20:34:33 volumio volumio[939]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 09 20:34:33 volumio volumio[939]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 09 20:34:33 volumio volumio[939]: info: Received Get System Info Jul 09 20:34:33 volumio volumio[939]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 09 20:34:33 volumio volumio[939]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 09 20:34:33 volumio volumio[939]: info: Discovery: Getting this device information Jul 09 20:34:33 volumio volumio[939]: info: CoreCommandRouter::volumioGetState Jul 09 20:34:33 volumio volumio[939]: info: CorePlayQueue::getTrack 1 Jul 09 20:34:33 volumio volumio[939]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 09 20:34:33 volumio volumio[939]: info: CoreCommandRouter::volumioGetState Jul 09 20:34:33 volumio volumio[939]: info: CorePlayQueue::getTrack 1 Jul 09 20:34:33 volumio volumio[939]: info: Listing playlists Jul 09 20:34:34 volumio volumio[939]: info: CoreCommandRouter::volumioGetQueue Jul 09 20:34:34 volumio volumio[939]: info: CoreStateMachine::getQueue Jul 09 20:34:34 volumio volumio[939]: info: CorePlayQueue::getQueue Jul 09 20:34:34 volumio volumio[939]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jul 09 20:34:37 volumio volumio[939]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 09 20:34:37 volumio volumio[939]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jul 09 20:34:39 volumio go-librespot[2654]: time="2025-07-09T20:34:39+08:00" level=error msg="websocket connection errored" error="failed to get reader: failed to read frame header: EOF" Jul 09 20:34:39 volumio volumio[939]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSpotify Jul 09 20:34:39 volumio volumio[939]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 09 20:34:39 volumio sudo[2828]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop go-librespot-daemon.service Jul 09 20:34:39 volumio sudo[2828]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 20:34:39 volumio volumio[939]: info: Disabling plugin spop Jul 09 20:34:39 volumio volumio[939]: info: Done. Jul 09 20:34:39 volumio volumio[939]: info: Connection to go-librespot Websocket closed Jul 09 20:34:39 volumio systemd[1]: Stopping go-librespot Daemon... Jul 09 20:34:39 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Jul 09 20:34:39 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Jul 09 20:34:39 volumio systemd[1]: Stopped go-librespot Daemon. Jul 09 20:34:39 volumio volumio[939]: info: Connection to go-librespot Websocket closed Jul 09 20:34:39 volumio sudo[2828]: pam_unix(sudo:session): session closed for user root Jul 09 20:34:40 volumio ntpd[707]: Soliciting pool server 111.90.158.16 Jul 09 20:34:42 volumio volumio[939]: info: Enabling plugin spop Jul 09 20:34:42 volumio volumio[939]: info: Loading plugin "spop"... Jul 09 20:34:42 volumio volumio[939]: info: PLUGIN START: spop Jul 09 20:34:42 volumio volumio[939]: info: Creating Spotify config file Jul 09 20:34:42 volumio volumio[939]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 20:34:42 volumio volumio[939]: info: Done. Jul 09 20:34:42 volumio volumio[939]: info: No need to fix Spotify hosts Jul 09 20:34:42 volumio volumio[939]: info: Spotify config file written Jul 09 20:34:42 volumio sudo[2870]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jul 09 20:34:42 volumio sudo[2870]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 20:34:42 volumio systemd[1]: Started go-librespot Daemon. Jul 09 20:34:42 volumio go-librespot[2872]: go-librespot daemon starting... Jul 09 20:34:42 volumio sudo[2870]: pam_unix(sudo:session): session closed for user root Jul 09 20:34:42 volumio go-librespot[2872]: time="2025-07-09T20:34:42+08:00" level=info msg="running go-librespot 0.2.0" Jul 09 20:34:42 volumio go-librespot[2872]: time="2025-07-09T20:34:42+08:00" level=debug msg="app state loaded" Jul 09 20:34:42 volumio go-librespot[2872]: time="2025-07-09T20:34:42+08:00" level=info msg="api server listening on 127.0.0.1:9879" Jul 09 20:34:43 volumio go-librespot[2872]: time="2025-07-09T20:34:43+08:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Jul 09 20:34:43 volumio go-librespot[2872]: time="2025-07-09T20:34:43+08:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jul 09 20:34:43 volumio go-librespot[2872]: time="2025-07-09T20:34:43+08:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jul 09 20:34:43 volumio go-librespot[2872]: time="2025-07-09T20:34:43+08:00" level=info msg="zeroconf server listening on port 39963" Jul 09 20:34:43 volumio volumio[939]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Jul 09 20:34:43 volumio volumio[939]: SPOTIFY: BQDe6BFRT8DKr8O4h_YgbdPK-5UPAbaNF25Ks08SJ5nStNmySm5CSA2GsV05wbkqKQigfGMv3VApi-qIIfvjVh780gJyO_mr6t_g4Aq5675xw2vZAHCFHXWlDYsRj3LwwZFqfP6kTicljueWsPGJPJ24KNEp7SMefINwNzWvyM16dtJbUckfvhDa243cap-i3ZuP6P27PwUAE5xcFYPJvb08ouAsmk0QtPDo2OE7TO8TxuCHvSUwdA2NrHyxep44I7xTNsJu8dk_uPTyqgBPvKA8EhP0qlH6BTzKgztJEc72tJc Jul 09 20:34:43 volumio volumio[939]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Jul 09 20:34:43 volumio volumio[939]: info: New Spotify access token = BQDe6BFRT8DKr8O4h_YgbdPK-5UPAbaNF25Ks08SJ5nStNmySm5CSA2GsV05wbkqKQigfGMv3VApi-qIIfvjVh780gJyO_mr6t_g4Aq5675xw2vZAHCFHXWlDYsRj3LwwZFqfP6kTicljueWsPGJPJ24KNEp7SMefINwNzWvyM16dtJbUckfvhDa243cap-i3ZuP6P27PwUAE5xcFYPJvb08ouAsmk0QtPDo2OE7TO8TxuCHvSUwdA2NrHyxep44I7xTNsJu8dk_uPTyqgBPvKA8EhP0qlH6BTzKgztJEc72tJc Jul 09 20:34:43 volumio volumio[939]: info: Spotify credentials grant success - running version from March 24, 2019 Jul 09 20:34:43 volumio go-librespot[2872]: time="2025-07-09T20:34:43+08:00" level=debug msg="obtained new client token: AACGstc7+380tyOrZLCYi+aXDYT6InRY0YxTmsG8y99OPwEN00f52es+UmKLjrm3OHNjhNojL8xqnCmpZI+VneUiNnzhsRvmLEZQqAOwSV7hDOjFITwGIdHrx0HWtz2+I/uBw73InWR1QsKK6j3jfJ4Fo2U/Si7/X0qvMs2erm0M5JC3x7VbvyI9H9cYZ4xi1KTOLcMfs9BWVzmxjioJqEbNizS5/F9+/nSfswokzn/hakBbU10wD00g" Jul 09 20:34:43 volumio go-librespot[2872]: time="2025-07-09T20:34:43+08:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Jul 09 20:34:43 volumio go-librespot[2872]: time="2025-07-09T20:34:43+08:00" level=debug msg="completed keyexchange" Jul 09 20:34:43 volumio go-librespot[2872]: time="2025-07-09T20:34:43+08:00" level=debug msg="completed challenge" Jul 09 20:34:43 volumio volumio[939]: SPOTIFY: User informations: {"country":"MY","display_name":"oyien","email":"ayamemas24792@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/pmctii2dlo9vndz6yd54dst2u"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/pmctii2dlo9vndz6yd54dst2u","id":"pmctii2dlo9vndz6yd54dst2u","images":[],"product":"premium","type":"user","uri":"spotify:user:pmctii2dlo9vndz6yd54dst2u"} Jul 09 20:34:43 volumio volumio[939]: info: Spotify Successfully logged in Jul 09 20:34:43 volumio volumio[939]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 09 20:34:43 volumio volumio[939]: info: [1752064483769] CoreMusicLibrary::Adding element Spotify Jul 09 20:34:43 volumio volumio[939]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 09 20:34:43 volumio volumio[939]: Cannot find translation for source Spotify Jul 09 20:34:43 volumio volumio[939]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 09 20:34:43 volumio go-librespot[2872]: time="2025-07-09T20:34:43+08:00" level=info msg="authenticated AP as pmctii2dlo9vndz6yd54dst2u" Jul 09 20:34:44 volumio go-librespot[2872]: time="2025-07-09T20:34:44+08:00" level=info msg="authenticated Login5 as pmctii2dlo9vndz6yd54dst2u" Jul 09 20:34:44 volumio go-librespot[2872]: time="2025-07-09T20:34:44+08:00" level=debug msg="initializing zeroconf session, username: pmctii2dlo9vndz6yd54dst2u" Jul 09 20:34:44 volumio go-librespot[2872]: time="2025-07-09T20:34:44+08:00" level=debug msg="dealer connection opened" Jul 09 20:34:44 volumio go-librespot[2872]: time="2025-07-09T20:34:44+08:00" level=trace msg="starting accesspoint recv loop" Jul 09 20:34:44 volumio go-librespot[2872]: time="2025-07-09T20:34:44+08:00" level=trace msg="starting dealer recv loop" Jul 09 20:34:44 volumio go-librespot[2872]: time="2025-07-09T20:34:44+08:00" level=trace msg="received accesspoint ping" Jul 09 20:34:44 volumio go-librespot[2872]: time="2025-07-09T20:34:44+08:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Jul 09 20:34:44 volumio go-librespot[2872]: time="2025-07-09T20:34:44+08:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Jul 09 20:34:44 volumio go-librespot[2872]: time="2025-07-09T20:34:44+08:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Jul 09 20:34:44 volumio go-librespot[2872]: time="2025-07-09T20:34:44+08:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Jul 09 20:34:44 volumio go-librespot[2872]: time="2025-07-09T20:34:44+08:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Jul 09 20:34:44 volumio go-librespot[2872]: time="2025-07-09T20:34:44+08:00" level=debug msg="received connection id: ZjA0ZjlmOTUtOWYyNy00NTY4LThjMjktZWRhY2I4MjBlMTVmK2RlYWxlcit0Y3A6Ly8wYWIxNTg4YS5pcC5nYWUyLnNwb3RpZnkubmV0OjU3MDArMEI2RkYzOUFDREZBRTYwNkU5NEE1MDYyQ0M1MEE3RUNGNkM3MTI2QzY1NDI5NkYxMTNDNDJGQTFDMjA4MkFDOA==" Jul 09 20:34:44 volumio go-librespot[2872]: time="2025-07-09T20:34:44+08:00" level=trace msg="received accesspoint pong ack" Jul 09 20:34:44 volumio go-librespot[2872]: time="2025-07-09T20:34:44+08:00" level=debug msg="put connect state because NEW_DEVICE" Jul 09 20:34:45 volumio volumio[939]: info: go-librespot daemon successfully initialized Jul 09 20:34:47 volumio volumio[939]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 09 20:34:47 volumio volumio[939]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jul 09 20:34:48 volumio volumio[939]: info: Initializing connection to go-librespot Websocket Jul 09 20:34:48 volumio go-librespot[2872]: time="2025-07-09T20:34:48+08:00" level=debug msg="new websocket client" Jul 09 20:34:48 volumio volumio[939]: info: Connection to go-librespot Websocket established Jul 09 20:34:51 volumio volumio[939]: info: Getting Spotify volume Jul 09 20:34:51 volumio volumio[939]: info: Spotify volume: 100 Jul 09 20:34:51 volumio volumio[939]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Jul 09 20:34:51 volumio volumio[939]: info: CoreCommandRouter::volumioGetState Jul 09 20:34:51 volumio volumio[939]: info: CorePlayQueue::getTrack 1 Jul 09 20:34:51 volumio volumio[939]: SPOTIFY: RECEIVED VOLUMIO VOLUME 14 Jul 09 20:34:51 volumio volumio[939]: SPOTIFY: SPOTIFY VOLUME 100 Jul 09 20:34:51 volumio volumio[939]: SPOTIFY: VOLUMIO VOLUME 14 Jul 09 20:34:51 volumio volumio[939]: SPOTIFY: DELTA VOLUME ENOUGH: true Jul 09 20:34:51 volumio volumio[939]: info: Setting Spotify Volume from Volumio: 14 Jul 09 20:34:53 volumio volumio[939]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 09 20:34:53 volumio volumio[939]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jul 09 20:34:53 volumio volumio[939]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 09 20:34:53 volumio volumio[939]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 09 20:34:53 volumio volumio[939]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 09 20:34:53 volumio volumio[939]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 09 20:34:53 volumio volumio[939]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 09 20:34:53 volumio volumio[939]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 09 20:34:53 volumio volumio[939]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 09 20:34:53 volumio volumio[939]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 09 20:34:53 volumio volumio[939]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 09 20:34:53 volumio volumio[939]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jul 09 20:34:53 volumio volumio[939]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 09 20:34:53 volumio volumio[939]: SPOTIFY: SETTING SPOTIFY VOLUME 14 Jul 09 20:34:53 volumio volumio[939]: info: Sending Spotify command with payload to local API: /player/volume Jul 09 20:34:53 volumio go-librespot[2872]: time="2025-07-09T20:34:53+08:00" level=debug msg="update volume to 9174/65535" Jul 09 20:34:54 volumio go-librespot[2872]: time="2025-07-09T20:34:54+08:00" level=debug msg="put connect state because VOLUME_CHANGED" Jul 09 20:34:54 volumio go-librespot[2872]: time="2025-07-09T20:34:54+08:00" level=trace msg="emitting websocket event: volume" Jul 09 20:34:54 volumio volumio[939]: SPOTIFY: received: {"type":"volume","data":{"value":14,"max":100}} Jul 09 20:34:54 volumio volumio[939]: SPOTIFY: RECEIVED SPOTIFY VOLUME 14 Jul 09 20:35:02 volumio volumio[939]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 09 20:35:02 volumio volumio[939]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jul 09 20:35:05 volumio volumio[939]: info: Starting Uninstall of plugin music_service - spop Jul 09 20:35:05 volumio volumio[939]: info: Uninstalling plugin spop Jul 09 20:35:05 volumio go-librespot[2872]: time="2025-07-09T20:35:05+08:00" level=error msg="websocket connection errored" error="failed to get reader: failed to read frame header: EOF" Jul 09 20:35:05 volumio volumio[939]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSpotify Jul 09 20:35:05 volumio volumio[939]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 09 20:35:05 volumio sudo[2918]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop go-librespot-daemon.service Jul 09 20:35:05 volumio sudo[2918]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 20:35:05 volumio volumio[939]: info: Disabling plugin spop Jul 09 20:35:05 volumio systemd[1]: Stopping go-librespot Daemon... Jul 09 20:35:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Jul 09 20:35:05 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Jul 09 20:35:05 volumio systemd[1]: Stopped go-librespot Daemon. Jul 09 20:35:05 volumio volumio[939]: info: Checking if uninstall.sh is present Jul 09 20:35:05 volumio volumio[939]: info: Connection to go-librespot Websocket closed Jul 09 20:35:05 volumio volumio[939]: info: Executing uninstall.sh Jul 09 20:35:05 volumio sudo[2918]: pam_unix(sudo:session): session closed for user root Jul 09 20:35:05 volumio sudo[2922]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/music_service/spop/uninstall.sh Jul 09 20:35:05 volumio sudo[2922]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 20:35:06 volumio sudo[2922]: pam_unix(sudo:session): session closed for user root Jul 09 20:35:06 volumio volumio[939]: info: Uninstall script completed Jul 09 20:35:06 volumio volumio[939]: info: Removing plugin spop from configuration Jul 09 20:35:06 volumio volumio[939]: info: Successfully removed spop configuration files Jul 09 20:35:06 volumio volumio[939]: info: Plugin folders cleanup Jul 09 20:35:06 volumio volumio[939]: info: Scanning into folder /volumio/app/plugins/ Jul 09 20:35:06 volumio volumio[939]: info: Scanning category audio_interface Jul 09 20:35:06 volumio volumio[939]: info: Scanning category miscellanea Jul 09 20:35:06 volumio volumio[939]: info: Scanning category music_service Jul 09 20:35:06 volumio volumio[939]: info: Scanning category plugins.json Jul 09 20:35:06 volumio volumio[939]: info: Scanning category system_controller Jul 09 20:35:06 volumio volumio[939]: info: Scanning category user_interface Jul 09 20:35:06 volumio volumio[939]: info: Scanning into folder /data/plugins/ Jul 09 20:35:06 volumio volumio[939]: info: Scanning category audio_interface Jul 09 20:35:06 volumio volumio[939]: info: Scanning category music_service Jul 09 20:35:06 volumio volumio[939]: info: Cleaning folder for spop Jul 09 20:35:07 volumio volumio[939]: info: Scanning category system_controller Jul 09 20:35:07 volumio volumio[939]: info: Scanning category system_hardware Jul 09 20:35:07 volumio volumio[939]: info: Plugin folders cleanup completed Jul 09 20:35:07 volumio volumio[939]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 09 20:35:07 volumio volumio[939]: info: [{"prettyName":"FusionDsp","name":"fusiondsp","category":"audio_interface","version":"1.0.61","icon":"fa-sliders fa-rotate-90","isManuallyInstalled":true,"enabled":false,"active":false},{"prettyName":"AutoStart","name":"autostart","category":"system_controller","version":"3.0.4","icon":"fa-lightbulb-o","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"SerialAmpControl","name":"serialampcontroller","category":"system_hardware","version":"2.7.1","icon":"fa-volume-up","isManuallyInstalled":false,"enabled":true,"active":true}] Jul 09 20:35:07 volumio volumio[939]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jul 09 20:35:17 volumio volumio[939]: info: CoreCommandRouter::volumioNext Jul 09 20:35:17 volumio volumio[939]: info: CoreStateMachine::next Jul 09 20:35:17 volumio volumio[939]: info: CoreStateMachine::stop Jul 09 20:35:17 volumio volumio[939]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 09 20:35:17 volumio volumio[939]: info: CoreStateMachine::stPlaybackTimer Jul 09 20:35:17 volumio volumio[939]: info: CoreStateMachine::updateTrackBlock Jul 09 20:35:17 volumio volumio[939]: info: CorePlayQueue::getTrackBlock Jul 09 20:35:17 volumio volumio[939]: info: CoreStateMachine::pushState Jul 09 20:35:17 volumio volumio[939]: info: CorePlayQueue::getTrack 1 Jul 09 20:35:17 volumio volumio[939]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 20:35:17 volumio volumio[939]: info: CoreCommandRouter::volumioPushState Jul 09 20:35:17 volumio volumio[939]: info: CoreStateMachine::serviceStop Jul 09 20:35:17 volumio volumio[939]: info: CorePlayQueue::getTrack 1 Jul 09 20:35:17 volumio volumio[939]: info: CoreCommandRouter::serviceStop Jul 09 20:35:17 volumio volumio[939]: error: WARNING: No stop method for service spop Jul 09 20:35:17 volumio volumio[939]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 09 20:35:17 volumio volumio[939]: TypeError: Cannot read property 'then' of undefined Jul 09 20:35:17 volumio volumio[939]: at CoreStateMachine.next (/volumio/app/statemachine.js:1179:9) Jul 09 20:35:17 volumio volumio[939]: at CoreCommandRouter.volumioNext (/volumio/app/index.js:103:28) Jul 09 20:35:17 volumio volumio[939]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:274:26) Jul 09 20:35:17 volumio volumio[939]: at Socket.emit (events.js:315:20) Jul 09 20:35:17 volumio volumio[939]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Jul 09 20:35:17 volumio volumio[939]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Jul 09 20:35:17 volumio volumio[939]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 09 20:35:18 volumio sudo[2972]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-09 20:34 Jul 09 20:35:18 volumio sudo[2972]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="811ff8312d42f9da0260be1d353081a805678820" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 02 Jul 2025 04:07:25 PM CEST" VOLUMIO_VERSION="3.819" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3b9115b1943141a9d7186aa434f8f3c2"