-- Logs begin at Sat 2026-08-29 17:52:58 CEST, end at Sat 2026-08-29 20:07:18 CEST. -- Aug 29 20:06:34 volumio volumio[1444]: info: CorePlayQueue::getTrack 7 Aug 29 20:06:34 volumio volumio[1444]: info: CorePlayQueue::getTrack 8 Aug 29 20:06:34 volumio volumio[1444]: info: Prefetching next song Aug 29 20:06:34 volumio volumio[1444]: info: [1788026794590] ControllerSpotify::prefetch Aug 29 20:06:34 volumio volumio[1444]: info: Sending Spotify command with payload to local API: /player/add_to_queue Aug 29 20:06:39 volumio volumio[1444]: info: CoreStateMachine::startPlaybackTimer Aug 29 20:06:39 volumio volumio[1444]: info: CorePlayQueue::getTrack 8 Aug 29 20:06:39 volumio volumio[1444]: info: CoreStateMachine::pushState Aug 29 20:06:39 volumio volumio[1444]: info: CorePlayQueue::getTrack 8 Aug 29 20:06:39 volumio volumio[1444]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 20:06:39 volumio volumio[1444]: info: CoreCommandRouter::volumioPushState Aug 29 20:06:39 volumio volumio[1444]: info: MRS: Pushing multiroomSync output update for this device Aug 29 20:06:39 volumio volumio[1444]: info: MRS: Pushing multiroomSync output Aug 29 20:06:39 volumio volumio[1444]: info: CoreCommandRouter::volumioGetState Aug 29 20:06:39 volumio volumio[1444]: info: CorePlayQueue::getTrack 8 Aug 29 20:06:40 volumio go-librespot[9508]: time="2026-08-29T20:06:40+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Aug 29 20:06:41 volumio go-librespot[9508]: time="2026-08-29T20:06:41+02:00" level=debug msg="connected to ap-gew4.spotify.com:80" Aug 29 20:06:41 volumio go-librespot[9508]: time="2026-08-29T20:06:41+02:00" level=debug msg="completed keyexchange" Aug 29 20:06:41 volumio go-librespot[9508]: time="2026-08-29T20:06:41+02:00" level=debug msg="completed challenge" Aug 29 20:06:41 volumio go-librespot[9508]: time="2026-08-29T20:06:41+02:00" level=info msg="authenticated AP" username="31************************4a" Aug 29 20:06:41 volumio go-librespot[9508]: time="2026-08-29T20:06:41+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 29 20:06:41 volumio volumio[1444]: (node:1444) UnhandledPromiseRejectionWarning: Error: socket hang up Aug 29 20:06:41 volumio volumio[1444]: at connResetException (internal/errors.js:639:14) Aug 29 20:06:41 volumio volumio[1444]: at Socket.socketOnEnd (_http_client.js:499:23) Aug 29 20:06:41 volumio volumio[1444]: at Socket.emit (events.js:412:35) Aug 29 20:06:41 volumio volumio[1444]: at endReadableNT (internal/streams/readable.js:1333:12) Aug 29 20:06:41 volumio volumio[1444]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Aug 29 20:06:41 volumio volumio[1444]: (node:1444) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 5069) Aug 29 20:06:41 volumio volumio[1444]: info: Connection to go-librespot Websocket closed Aug 29 20:06:41 volumio volumio[1444]: error: Failed to send command to Spotify local API: /player/add_to_queue: Error: socket hang up Aug 29 20:06:41 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 20:06:41 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 20:06:43 volumio volumio[1444]: info: CoreCommandRouter::volumioPlay Aug 29 20:06:43 volumio volumio[1444]: info: CoreStateMachine::play index undefined Aug 29 20:06:43 volumio volumio[1444]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 20:06:43 volumio volumio[1444]: info: CorePlayQueue::getTrack 8 Aug 29 20:06:43 volumio volumio[1444]: info: CoreStateMachine::startPlaybackTimer Aug 29 20:06:43 volumio volumio[1444]: info: CorePlayQueue::getTrack 8 Aug 29 20:06:43 volumio volumio[1444]: info: [1788026803128] ControllerSpotify::clearAddPlayTrack Aug 29 20:06:43 volumio volumio[1444]: info: Sending Spotify command with payload to local API: /player/play Aug 29 20:06:43 volumio volumio[1444]: error: Failed to send command to Spotify local API: /player/play: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 29 20:06:44 volumio volumio[1444]: info: Initializing connection to go-librespot Websocket Aug 29 20:06:44 volumio volumio[1444]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 29 20:06:44 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 29 20:06:44 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 38619. Aug 29 20:06:44 volumio systemd[1]: Stopped go-librespot Daemon. Aug 29 20:06:44 volumio systemd[1]: Started go-librespot Daemon. Aug 29 20:06:44 volumio go-librespot[10940]: go-librespot daemon starting... Aug 29 20:06:44 volumio go-librespot[10940]: time="2026-08-29T20:06:44+02:00" level=info msg="running go-librespot 0.7.1" Aug 29 20:06:44 volumio go-librespot[10940]: time="2026-08-29T20:06:44+02:00" level=debug msg="app state loaded" Aug 29 20:06:44 volumio go-librespot[10940]: time="2026-08-29T20:06:44+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 20:06:44 volumio go-librespot[10940]: time="2026-08-29T20:06:44+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 29 20:06:44 volumio go-librespot[10940]: time="2026-08-29T20:06:44+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 29 20:06:44 volumio go-librespot[10940]: time="2026-08-29T20:06:44+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 29 20:06:44 volumio go-librespot[10940]: time="2026-08-29T20:06:44+02:00" level=info msg="zeroconf server listening on port 37363" Aug 29 20:06:44 volumio go-librespot[10940]: time="2026-08-29T20:06:44+02:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Aug 29 20:06:45 volumio go-librespot[10940]: time="2026-08-29T20:06:45+02:00" level=debug msg="obtained new client token: AAHADv+Iwippk6DDLGgoYGXE8S1tV5EVdRxSaN2/FXgN0EO1Fo+BXZ8eqpSYpin2rxHwVKQK6LofQHQGX5q/bj/YEoEA+62H/YKkEdjg8V9QoCPBfD2p+tegGu0AoWvX4LC89XitkA+YzplXHgf5g969U0GP9YwjVL812GXf0rD2afMwpXkutXbu3KBpg76UtEkpCEXGnHY1rJCT0gdrtevS7dSo6/mIpNDKzA5lBsyo9x38CG9IS0A=" Aug 29 20:06:45 volumio go-librespot[10940]: time="2026-08-29T20:06:45+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 29 20:06:45 volumio go-librespot[10940]: time="2026-08-29T20:06:45+02:00" level=debug msg="completed keyexchange" Aug 29 20:06:45 volumio go-librespot[10940]: time="2026-08-29T20:06:45+02:00" level=debug msg="completed challenge" Aug 29 20:06:45 volumio go-librespot[10940]: time="2026-08-29T20:06:45+02:00" level=info msg="authenticated AP" username="31************************4a" Aug 29 20:06:45 volumio go-librespot[10940]: time="2026-08-29T20:06:45+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 29 20:06:45 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 20:06:45 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 20:06:47 volumio volumio[1444]: info: Initializing connection to go-librespot Websocket Aug 29 20:06:47 volumio volumio[1444]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 29 20:06:48 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 29 20:06:48 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 38620. Aug 29 20:06:48 volumio systemd[1]: Stopped go-librespot Daemon. Aug 29 20:06:48 volumio systemd[1]: Started go-librespot Daemon. Aug 29 20:06:48 volumio go-librespot[10948]: go-librespot daemon starting... Aug 29 20:06:48 volumio go-librespot[10948]: time="2026-08-29T20:06:48+02:00" level=info msg="running go-librespot 0.7.1" Aug 29 20:06:48 volumio go-librespot[10948]: time="2026-08-29T20:06:48+02:00" level=debug msg="app state loaded" Aug 29 20:06:48 volumio go-librespot[10948]: time="2026-08-29T20:06:48+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 20:06:48 volumio go-librespot[10948]: time="2026-08-29T20:06:48+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 29 20:06:48 volumio go-librespot[10948]: time="2026-08-29T20:06:48+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 29 20:06:48 volumio go-librespot[10948]: time="2026-08-29T20:06:48+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 29 20:06:48 volumio go-librespot[10948]: time="2026-08-29T20:06:48+02:00" level=info msg="zeroconf server listening on port 46537" Aug 29 20:06:48 volumio go-librespot[10948]: time="2026-08-29T20:06:48+02:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Aug 29 20:06:48 volumio go-librespot[10948]: time="2026-08-29T20:06:48+02:00" level=debug msg="obtained new client token: AAEN2VgSlv61X9uR6aBvKowCXakiEq0OP1JYrOvORIf3/WKoyfrkrouj0urJpHhJSNNIxd26kJFaeaK7ZS7mXBYZNPKV8QDEvg1GB8yMZXec269wRkpNZYndUl49OKKGDbs5XinHEW5/Kky+6KbaLlLozMlN0rSxHeO/qkFOKZvSln4mlWk0bl/g9sH5tBd+ESYZhJJ5loAV78KyyxrjLPTxqjlG8WKKsv/cfaCy+Liv4koMNSibX5rCtg==" Aug 29 20:06:48 volumio go-librespot[10948]: time="2026-08-29T20:06:48+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 29 20:06:50 volumio volumio[1444]: info: Initializing connection to go-librespot Websocket Aug 29 20:06:50 volumio volumio[1444]: info: Connection to go-librespot Websocket established Aug 29 20:06:50 volumio go-librespot[10948]: time="2026-08-29T20:06:50+02:00" level=debug msg="new websocket client" Aug 29 20:06:53 volumio volumio[1444]: info: Getting Spotify volume Aug 29 20:06:53 volumio volumio[1444]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Aug 29 20:06:53 volumio volumio[1444]: info: CoreCommandRouter::volumioGetState Aug 29 20:06:53 volumio volumio[1444]: info: CorePlayQueue::getTrack 8 Aug 29 20:07:01 volumio volumio[1444]: verbose: New Socket.io Connection to 192.168.0.223 from 192.168.0.132 UA: Mozilla/5.0 (Android 13; Mobile; rv:154.0) Gecko/154.0 Firefox/154.0 Engine version: 3 Transport: polling Total Clients: 7 Aug 29 20:07:01 volumio volumio[1444]: info: CoreCommandRouter::volumioGetState Aug 29 20:07:01 volumio volumio[1444]: info: CorePlayQueue::getTrack 8 Aug 29 20:07:01 volumio volumio[1444]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 29 20:07:01 volumio volumio[1444]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Aug 29 20:07:01 volumio volumio[1444]: info: CoreCommandRouter::volumioGetVisibleSources Aug 29 20:07:01 volumio volumio[1444]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 20:07:01 volumio volumio[1444]: info: Listing playlists Aug 29 20:07:01 volumio volumio[1444]: info: CoreCommandRouter::volumioGetQueue Aug 29 20:07:01 volumio volumio[1444]: info: CoreStateMachine::getQueue Aug 29 20:07:01 volumio volumio[1444]: info: CorePlayQueue::getQueue Aug 29 20:07:02 volumio volumio[1444]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Aug 29 20:07:02 volumio volumio[1444]: info: Playing Remote Device: http://192.168.0.223 Aug 29 20:07:02 volumio volumio[1444]: info: Aug 29 20:07:02 volumio volumio[1444]: ---------------------------- Client requests Volumio play Aug 29 20:07:02 volumio volumio[1444]: info: CoreCommandRouter::volumioPlay Aug 29 20:07:02 volumio volumio[1444]: info: CoreStateMachine::play index undefined Aug 29 20:07:02 volumio volumio[1444]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 20:07:02 volumio volumio[1444]: info: CorePlayQueue::getTrack 8 Aug 29 20:07:02 volumio volumio[1444]: info: CoreStateMachine::startPlaybackTimer Aug 29 20:07:02 volumio volumio[1444]: info: CorePlayQueue::getTrack 8 Aug 29 20:07:02 volumio volumio[1444]: info: [1788026822593] ControllerSpotify::clearAddPlayTrack Aug 29 20:07:02 volumio volumio[1444]: info: Sending Spotify command with payload to local API: /player/play Aug 29 20:07:02 volumio volumio[1444]: info: Done playing: Aug 29 20:07:07 volumio volumio[1444]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Aug 29 20:07:07 volumio volumio[1444]: info: Playing Remote Device: http://192.168.0.223 Aug 29 20:07:07 volumio volumio[1444]: info: Aug 29 20:07:07 volumio volumio[1444]: ---------------------------- Client requests Volumio play Aug 29 20:07:07 volumio volumio[1444]: info: CoreCommandRouter::volumioPlay Aug 29 20:07:07 volumio volumio[1444]: info: CoreStateMachine::play index undefined Aug 29 20:07:07 volumio volumio[1444]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 20:07:07 volumio volumio[1444]: info: CorePlayQueue::getTrack 8 Aug 29 20:07:07 volumio volumio[1444]: info: CoreStateMachine::startPlaybackTimer Aug 29 20:07:07 volumio volumio[1444]: info: CorePlayQueue::getTrack 8 Aug 29 20:07:07 volumio volumio[1444]: info: [1788026827796] ControllerSpotify::clearAddPlayTrack Aug 29 20:07:07 volumio volumio[1444]: info: Sending Spotify command with payload to local API: /player/play Aug 29 20:07:07 volumio volumio[1444]: info: Done playing: Aug 29 20:07:08 volumio volumio[1444]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Aug 29 20:07:08 volumio volumio[1444]: info: Playing Remote Device: http://192.168.0.223 Aug 29 20:07:08 volumio volumio[1444]: info: Aug 29 20:07:08 volumio volumio[1444]: ---------------------------- Client requests Volumio play Aug 29 20:07:08 volumio volumio[1444]: info: CoreCommandRouter::volumioPlay Aug 29 20:07:08 volumio volumio[1444]: info: CoreStateMachine::play index undefined Aug 29 20:07:08 volumio volumio[1444]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 20:07:08 volumio volumio[1444]: info: CorePlayQueue::getTrack 8 Aug 29 20:07:08 volumio volumio[1444]: info: CoreStateMachine::startPlaybackTimer Aug 29 20:07:08 volumio volumio[1444]: info: CorePlayQueue::getTrack 8 Aug 29 20:07:08 volumio volumio[1444]: info: [1788026828938] ControllerSpotify::clearAddPlayTrack Aug 29 20:07:08 volumio volumio[1444]: info: Sending Spotify command with payload to local API: /player/play Aug 29 20:07:08 volumio volumio[1444]: info: Done playing: Aug 29 20:07:09 volumio volumio[1444]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Aug 29 20:07:09 volumio volumio[1444]: info: Playing Remote Device: http://192.168.0.223 Aug 29 20:07:09 volumio volumio[1444]: info: Aug 29 20:07:09 volumio volumio[1444]: ---------------------------- Client requests Volumio play Aug 29 20:07:09 volumio volumio[1444]: info: CoreCommandRouter::volumioPlay Aug 29 20:07:09 volumio volumio[1444]: info: CoreStateMachine::play index undefined Aug 29 20:07:09 volumio volumio[1444]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 20:07:09 volumio volumio[1444]: info: CorePlayQueue::getTrack 8 Aug 29 20:07:09 volumio volumio[1444]: info: CoreStateMachine::startPlaybackTimer Aug 29 20:07:09 volumio volumio[1444]: info: CorePlayQueue::getTrack 8 Aug 29 20:07:09 volumio volumio[1444]: info: [1788026829631] ControllerSpotify::clearAddPlayTrack Aug 29 20:07:09 volumio volumio[1444]: info: Sending Spotify command with payload to local API: /player/play Aug 29 20:07:09 volumio volumio[1444]: info: Done playing: Aug 29 20:07:10 volumio volumio[1444]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Aug 29 20:07:10 volumio volumio[1444]: info: Playing Remote Device: http://192.168.0.223 Aug 29 20:07:10 volumio volumio[1444]: info: Aug 29 20:07:10 volumio volumio[1444]: ---------------------------- Client requests Volumio play Aug 29 20:07:10 volumio volumio[1444]: info: CoreCommandRouter::volumioPlay Aug 29 20:07:10 volumio volumio[1444]: info: CoreStateMachine::play index undefined Aug 29 20:07:10 volumio volumio[1444]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 20:07:10 volumio volumio[1444]: info: CorePlayQueue::getTrack 8 Aug 29 20:07:10 volumio volumio[1444]: info: CoreStateMachine::startPlaybackTimer Aug 29 20:07:10 volumio volumio[1444]: info: CorePlayQueue::getTrack 8 Aug 29 20:07:10 volumio volumio[1444]: info: [1788026830049] ControllerSpotify::clearAddPlayTrack Aug 29 20:07:10 volumio volumio[1444]: info: Sending Spotify command with payload to local API: /player/play Aug 29 20:07:10 volumio volumio[1444]: info: Done playing: Aug 29 20:07:10 volumio volumio[1444]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Aug 29 20:07:10 volumio volumio[1444]: info: Playing Remote Device: http://192.168.0.223 Aug 29 20:07:10 volumio volumio[1444]: info: Aug 29 20:07:10 volumio volumio[1444]: ---------------------------- Client requests Volumio play Aug 29 20:07:10 volumio volumio[1444]: info: CoreCommandRouter::volumioPlay Aug 29 20:07:10 volumio volumio[1444]: info: CoreStateMachine::play index undefined Aug 29 20:07:10 volumio volumio[1444]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 20:07:10 volumio volumio[1444]: info: CorePlayQueue::getTrack 8 Aug 29 20:07:10 volumio volumio[1444]: info: CoreStateMachine::startPlaybackTimer Aug 29 20:07:10 volumio volumio[1444]: info: CorePlayQueue::getTrack 8 Aug 29 20:07:10 volumio volumio[1444]: info: [1788026830372] ControllerSpotify::clearAddPlayTrack Aug 29 20:07:10 volumio volumio[1444]: info: Sending Spotify command with payload to local API: /player/play Aug 29 20:07:10 volumio volumio[1444]: info: Done playing: Aug 29 20:07:10 volumio volumio[1444]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Aug 29 20:07:10 volumio volumio[1444]: info: Playing Remote Device: http://192.168.0.223 Aug 29 20:07:10 volumio volumio[1444]: info: Aug 29 20:07:10 volumio volumio[1444]: ---------------------------- Client requests Volumio play Aug 29 20:07:10 volumio volumio[1444]: info: CoreCommandRouter::volumioPlay Aug 29 20:07:10 volumio volumio[1444]: info: CoreStateMachine::play index undefined Aug 29 20:07:10 volumio volumio[1444]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 20:07:10 volumio volumio[1444]: info: CorePlayQueue::getTrack 8 Aug 29 20:07:10 volumio volumio[1444]: info: CoreStateMachine::startPlaybackTimer Aug 29 20:07:10 volumio volumio[1444]: info: CorePlayQueue::getTrack 8 Aug 29 20:07:10 volumio volumio[1444]: info: [1788026830695] ControllerSpotify::clearAddPlayTrack Aug 29 20:07:10 volumio volumio[1444]: info: Sending Spotify command with payload to local API: /player/play Aug 29 20:07:10 volumio volumio[1444]: info: Done playing: Aug 29 20:07:12 volumio volumio[1444]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Aug 29 20:07:12 volumio volumio[1444]: info: Playing Remote Device: http://192.168.0.223 Aug 29 20:07:12 volumio volumio[1444]: info: Aug 29 20:07:12 volumio volumio[1444]: ---------------------------- Client requests Volumio play Aug 29 20:07:12 volumio volumio[1444]: info: CoreCommandRouter::volumioPlay Aug 29 20:07:12 volumio volumio[1444]: info: CoreStateMachine::play index undefined Aug 29 20:07:12 volumio volumio[1444]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 20:07:12 volumio volumio[1444]: info: CorePlayQueue::getTrack 8 Aug 29 20:07:12 volumio volumio[1444]: info: CoreStateMachine::startPlaybackTimer Aug 29 20:07:12 volumio volumio[1444]: info: CorePlayQueue::getTrack 8 Aug 29 20:07:12 volumio volumio[1444]: info: [1788026832883] ControllerSpotify::clearAddPlayTrack Aug 29 20:07:12 volumio volumio[1444]: info: Sending Spotify command with payload to local API: /player/play Aug 29 20:07:12 volumio volumio[1444]: info: Done playing: Aug 29 20:07:13 volumio volumio[1444]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Aug 29 20:07:13 volumio volumio[1444]: info: Playing Remote Device: http://192.168.0.223 Aug 29 20:07:13 volumio volumio[1444]: info: Aug 29 20:07:13 volumio volumio[1444]: ---------------------------- Client requests Volumio play Aug 29 20:07:13 volumio volumio[1444]: info: CoreCommandRouter::volumioPlay Aug 29 20:07:13 volumio volumio[1444]: info: CoreStateMachine::play index undefined Aug 29 20:07:13 volumio volumio[1444]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 20:07:13 volumio volumio[1444]: info: CorePlayQueue::getTrack 8 Aug 29 20:07:13 volumio volumio[1444]: info: CoreStateMachine::startPlaybackTimer Aug 29 20:07:13 volumio volumio[1444]: info: CorePlayQueue::getTrack 8 Aug 29 20:07:13 volumio volumio[1444]: info: [1788026833584] ControllerSpotify::clearAddPlayTrack Aug 29 20:07:13 volumio volumio[1444]: info: Sending Spotify command with payload to local API: /player/play Aug 29 20:07:13 volumio volumio[1444]: info: Done playing: Aug 29 20:07:17 volumio volumio[1444]: info: CoreCommandRouter::executeOnPlugin: multiroom , audioOutputPlay Aug 29 20:07:17 volumio volumio[1444]: info: Error : CoreCommandRouter::executeOnPlugin: No method [audioOutputPlay] in plugin multiroom Aug 29 20:07:17 volumio volumio[1444]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 20:07:17 volumio volumio[1444]: TypeError: Cannot read property 'then' of undefined Aug 29 20:07:17 volumio volumio[1444]: at outputs.audioOutputPlay (/volumio/app/plugins/audio_interface/outputs/index.js:367:9) Aug 29 20:07:17 volumio volumio[1444]: at CoreCommandRouter.audioOutputPlay (/volumio/app/index.js:2279:30) Aug 29 20:07:17 volumio volumio[1444]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1467:26) Aug 29 20:07:17 volumio volumio[1444]: at Socket.emit (events.js:400:28) Aug 29 20:07:17 volumio volumio[1444]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Aug 29 20:07:17 volumio volumio[1444]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Aug 29 20:07:17 volumio volumio[1444]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 20:07:18 volumio sudo[11012]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-08-29 20:06 Aug 29 20:07:18 volumio sudo[11012]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 06:03:54 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_DEVICENAME="Asus Tinkerboard" VOLUMIO_HASH="35e446ff98e90abcfcab1bb2733dfd3a"