-- Logs begin at Mon 2025-11-03 16:26:57 CET, end at Mon 2025-11-03 17:03:58 CET. -- Nov 03 17:02:06 lr-volumio volumiologrotate[742]: ls: cannot access '/var/log/samba/log.wb-LR': No such file or directory Nov 03 17:02:06 lr-volumio volumiologrotate[742]: ls: cannot access 'VOLUMIO': No such file or directory Nov 03 17:02:08 lr-volumio volumio[973]: info: CoreCommandRouter::volumioGetState Nov 03 17:02:08 lr-volumio volumio[973]: info: CorePlayQueue::getTrack 0 Nov 03 17:02:08 lr-volumio volumio[973]: info: Listing playlists Nov 03 17:02:18 lr-volumio volumio[973]: info: CoreCommandRouter::volumioGetState Nov 03 17:02:18 lr-volumio volumio[973]: info: CorePlayQueue::getTrack 0 Nov 03 17:02:28 lr-volumio volumio[973]: info: CoreCommandRouter::volumioGetState Nov 03 17:02:28 lr-volumio volumio[973]: info: CorePlayQueue::getTrack 0 Nov 03 17:02:28 lr-volumio volumio[973]: info: Listing playlists Nov 03 17:02:28 lr-volumio volumio[973]: info: Listing playlists Nov 03 17:02:34 lr-volumio nmbd[915]: [2025/11/03 17:02:34.651234, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Nov 03 17:02:34 lr-volumio nmbd[915]: query_name_response: Multiple (2) responses received for a query on subnet 172.16.100.100 for name WORKGROUP<1d>. Nov 03 17:02:34 lr-volumio nmbd[915]: This response was from IP 172.16.0.131, reporting an IP address of 172.16.0.131. Nov 03 17:02:38 lr-volumio volumio[973]: info: CoreCommandRouter::volumioGetState Nov 03 17:02:38 lr-volumio volumio[973]: info: CorePlayQueue::getTrack 0 Nov 03 17:02:41 lr-volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 03 17:02:41 lr-volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Nov 03 17:02:48 lr-volumio volumio[973]: info: CoreCommandRouter::volumioGetState Nov 03 17:02:48 lr-volumio volumio[973]: info: CorePlayQueue::getTrack 0 Nov 03 17:02:48 lr-volumio volumio[973]: info: Listing playlists Nov 03 17:02:58 lr-volumio volumio[973]: info: CoreCommandRouter::volumioGetState Nov 03 17:02:58 lr-volumio volumio[973]: info: CorePlayQueue::getTrack 0 Nov 03 17:03:06 lr-volumio volumiologrotate[742]: ls: cannot access '/var/log/samba/log.wb-LR': No such file or directory Nov 03 17:03:06 lr-volumio volumiologrotate[742]: ls: cannot access 'VOLUMIO': No such file or directory Nov 03 17:03:08 lr-volumio volumio[973]: info: CoreCommandRouter::volumioGetState Nov 03 17:03:08 lr-volumio volumio[973]: info: CorePlayQueue::getTrack 0 Nov 03 17:03:08 lr-volumio volumio[973]: info: Listing playlists Nov 03 17:03:08 lr-volumio volumio[973]: info: Listing playlists Nov 03 17:03:18 lr-volumio volumio[973]: info: CoreCommandRouter::volumioGetState Nov 03 17:03:18 lr-volumio volumio[973]: info: CorePlayQueue::getTrack 0 Nov 03 17:03:25 lr-volumio volumio[973]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 03 17:03:27 lr-volumio volumio[973]: info: CALLMETHOD: music_service spop saveGoLibrespotSettings [object Object] Nov 03 17:03:27 lr-volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: spop , saveGoLibrespotSettings Nov 03 17:03:27 lr-volumio volumio[973]: info: Creating Spotify config file Nov 03 17:03:27 lr-volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 17:03:27 lr-volumio volumio[973]: info: Spotify config file written Nov 03 17:03:27 lr-volumio sudo[8005]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Nov 03 17:03:27 lr-volumio sudo[8005]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 17:03:27 lr-volumio systemd[1]: Started go-librespot Daemon. Nov 03 17:03:27 lr-volumio go-librespot[8007]: go-librespot daemon starting... Nov 03 17:03:27 lr-volumio sudo[8005]: pam_unix(sudo:session): session closed for user root Nov 03 17:03:27 lr-volumio go-librespot[8007]: time="2025-11-03T17:03:27+01:00" level=info msg="running go-librespot 0.4.0" Nov 03 17:03:27 lr-volumio go-librespot[8007]: time="2025-11-03T17:03:27+01:00" level=debug msg="app state loaded" Nov 03 17:03:27 lr-volumio go-librespot[8007]: time="2025-11-03T17:03:27+01:00" level=info msg="api server listening on 127.0.0.1:9879" Nov 03 17:03:27 lr-volumio go-librespot[8007]: time="2025-11-03T17:03:27+01: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-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 03 17:03:27 lr-volumio go-librespot[8007]: time="2025-11-03T17:03:27+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 03 17:03:27 lr-volumio go-librespot[8007]: time="2025-11-03T17:03:27+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 03 17:03:27 lr-volumio go-librespot[8007]: time="2025-11-03T17:03:27+01:00" level=info msg="zeroconf server listening on port 36571" Nov 03 17:03:27 lr-volumio go-librespot[8007]: time="2025-11-03T17:03:27+01:00" level=debug msg="obtained new client token: AAAzZvt5H+Xpky86q6M9xRVRPQcn+uplHKJUN4pk/bg5lfqW/eGNmXXJFL7TavNq8d32Ts+2BMH1fgKhc0XLLklnD0qIT7Qw5JH5FDoZ+2p+C49T1vYl5q1DSVFdSrQT9CwbPlcGuZ+M2XFs495GlV1aMTdU+C9Xt9qLEA3dylvo4SZkbDonWfh91Peex7SyjCUi6LYovseBqQ0weG2CvbUPcvV9YNSrbO4ms7ilmeaV9pLiDkM0D0E2+w==" Nov 03 17:03:27 lr-volumio go-librespot[8007]: time="2025-11-03T17:03:27+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Nov 03 17:03:27 lr-volumio go-librespot[8007]: time="2025-11-03T17:03:27+01:00" level=debug msg="completed keyexchange" Nov 03 17:03:27 lr-volumio go-librespot[8007]: time="2025-11-03T17:03:27+01:00" level=debug msg="completed challenge" Nov 03 17:03:27 lr-volumio go-librespot[8007]: time="2025-11-03T17:03:27+01:00" level=info msg="authenticated AP" username="nx*********************bb" Nov 03 17:03:27 lr-volumio go-librespot[8007]: time="2025-11-03T17:03:27+01:00" level=info msg="authenticated Login5" username="nx*********************bb" Nov 03 17:03:27 lr-volumio go-librespot[8007]: time="2025-11-03T17:03:27+01:00" level=debug msg="initializing zeroconf session" username="nx*********************bb" Nov 03 17:03:27 lr-volumio volumio[973]: info: CALLMETHOD: music_service spop saveGoLibrespotSettings [object Object] Nov 03 17:03:27 lr-volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: spop , saveGoLibrespotSettings Nov 03 17:03:27 lr-volumio volumio[973]: info: Creating Spotify config file Nov 03 17:03:27 lr-volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 17:03:27 lr-volumio go-librespot[8007]: time="2025-11-03T17:03:27+01:00" level=debug msg="dealer connection opened" Nov 03 17:03:27 lr-volumio go-librespot[8007]: time="2025-11-03T17:03:27+01:00" level=trace msg="starting accesspoint recv loop" Nov 03 17:03:27 lr-volumio go-librespot[8007]: time="2025-11-03T17:03:27+01:00" level=trace msg="starting dealer recv loop" Nov 03 17:03:27 lr-volumio go-librespot[8007]: time="2025-11-03T17:03:27+01:00" level=trace msg="received accesspoint ping" Nov 03 17:03:27 lr-volumio go-librespot[8007]: time="2025-11-03T17:03:27+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Nov 03 17:03:27 lr-volumio go-librespot[8007]: time="2025-11-03T17:03:27+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Nov 03 17:03:27 lr-volumio go-librespot[8007]: time="2025-11-03T17:03:27+01:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Nov 03 17:03:27 lr-volumio go-librespot[8007]: time="2025-11-03T17:03:27+01:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Nov 03 17:03:27 lr-volumio go-librespot[8007]: time="2025-11-03T17:03:27+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Nov 03 17:03:27 lr-volumio go-librespot[8007]: time="2025-11-03T17:03:27+01:00" level=debug msg="received connection id: NDg4MDBkOWUtOTZj...RUJDMjkyMzk3Qw==" Nov 03 17:03:27 lr-volumio go-librespot[8007]: time="2025-11-03T17:03:27+01:00" level=trace msg="received accesspoint pong ack" Nov 03 17:03:27 lr-volumio volumio[973]: info: Spotify config file written Nov 03 17:03:27 lr-volumio sudo[8017]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Nov 03 17:03:27 lr-volumio sudo[8017]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 17:03:28 lr-volumio go-librespot[8007]: time="2025-11-03T17:03:28+01:00" level=debug msg="put connect state because NEW_DEVICE" Nov 03 17:03:28 lr-volumio systemd[1]: Stopping go-librespot Daemon... Nov 03 17:03:28 lr-volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Nov 03 17:03:28 lr-volumio systemd[1]: go-librespot-daemon.service: Succeeded. Nov 03 17:03:28 lr-volumio systemd[1]: Stopped go-librespot Daemon. Nov 03 17:03:28 lr-volumio volumio[973]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 03 17:03:28 lr-volumio volumio[973]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 03 17:03:28 lr-volumio volumio[973]: info: CALLMETHOD: music_service spop saveGoLibrespotSettings [object Object] Nov 03 17:03:28 lr-volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: spop , saveGoLibrespotSettings Nov 03 17:03:28 lr-volumio volumio[973]: info: Creating Spotify config file Nov 03 17:03:28 lr-volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 17:03:28 lr-volumio systemd[1]: Started go-librespot Daemon. Nov 03 17:03:28 lr-volumio volumio[973]: info: Spotify config file written Nov 03 17:03:28 lr-volumio go-librespot[8019]: go-librespot daemon starting... Nov 03 17:03:28 lr-volumio sudo[8017]: pam_unix(sudo:session): session closed for user root Nov 03 17:03:28 lr-volumio go-librespot[8019]: time="2025-11-03T17:03:28+01:00" level=info msg="running go-librespot 0.4.0" Nov 03 17:03:28 lr-volumio go-librespot[8019]: time="2025-11-03T17:03:28+01:00" level=debug msg="app state loaded" Nov 03 17:03:28 lr-volumio go-librespot[8019]: time="2025-11-03T17:03:28+01:00" level=info msg="api server listening on 127.0.0.1:9879" Nov 03 17:03:28 lr-volumio sudo[8027]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Nov 03 17:03:28 lr-volumio sudo[8027]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 17:03:28 lr-volumio systemd[1]: Stopping go-librespot Daemon... Nov 03 17:03:28 lr-volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Nov 03 17:03:28 lr-volumio systemd[1]: go-librespot-daemon.service: Succeeded. Nov 03 17:03:28 lr-volumio systemd[1]: Stopped go-librespot Daemon. Nov 03 17:03:28 lr-volumio volumio[973]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 03 17:03:28 lr-volumio volumio[973]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 03 17:03:28 lr-volumio systemd[1]: Started go-librespot Daemon. Nov 03 17:03:28 lr-volumio sudo[8027]: pam_unix(sudo:session): session closed for user root Nov 03 17:03:28 lr-volumio go-librespot[8029]: go-librespot daemon starting... Nov 03 17:03:28 lr-volumio go-librespot[8029]: time="2025-11-03T17:03:28+01:00" level=info msg="running go-librespot 0.4.0" Nov 03 17:03:28 lr-volumio go-librespot[8029]: time="2025-11-03T17:03:28+01:00" level=debug msg="app state loaded" Nov 03 17:03:28 lr-volumio go-librespot[8029]: time="2025-11-03T17:03:28+01:00" level=info msg="api server listening on 127.0.0.1:9879" Nov 03 17:03:28 lr-volumio go-librespot[8029]: time="2025-11-03T17:03:28+01: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-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 03 17:03:28 lr-volumio go-librespot[8029]: time="2025-11-03T17:03:28+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 03 17:03:28 lr-volumio go-librespot[8029]: time="2025-11-03T17:03:28+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 03 17:03:28 lr-volumio go-librespot[8029]: time="2025-11-03T17:03:28+01:00" level=info msg="zeroconf server listening on port 36209" Nov 03 17:03:28 lr-volumio go-librespot[8029]: time="2025-11-03T17:03:28+01:00" level=debug msg="obtained new client token: AACAFsMRm36Zo2BZYvgYJS/klJf0IhnYzqMJGoxdQymarZceIGgWk01a/4EDT5oxMnyA4y2sl54Jc99plBhND7t+Bs+VW0KgSvW0WJxoUKmo5nY+bH+vKqWkZwGNwrq9tujNb6ColXWl9xdYSrFWUblSrhblxHsBYGOdsktt5DTdo7XB1npRBo7C2R8DSiyqmtzuM+5OlGpBYNWJZzBAxr410oIQz1V6jTqe96lAkFC6HJsjIr6HmCvV4Q==" Nov 03 17:03:28 lr-volumio go-librespot[8029]: time="2025-11-03T17:03:28+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Nov 03 17:03:28 lr-volumio go-librespot[8029]: time="2025-11-03T17:03:28+01:00" level=debug msg="completed keyexchange" Nov 03 17:03:28 lr-volumio go-librespot[8029]: time="2025-11-03T17:03:28+01:00" level=debug msg="completed challenge" Nov 03 17:03:28 lr-volumio volumio[973]: info: CoreCommandRouter::volumioGetState Nov 03 17:03:28 lr-volumio volumio[973]: info: CorePlayQueue::getTrack 0 Nov 03 17:03:28 lr-volumio go-librespot[8029]: time="2025-11-03T17:03:28+01:00" level=info msg="authenticated AP" username="nx*********************bb" Nov 03 17:03:28 lr-volumio volumio[973]: info: Listing playlists Nov 03 17:03:28 lr-volumio go-librespot[8029]: time="2025-11-03T17:03:28+01:00" level=info msg="authenticated Login5" username="nx*********************bb" Nov 03 17:03:28 lr-volumio go-librespot[8029]: time="2025-11-03T17:03:28+01:00" level=debug msg="initializing zeroconf session" username="nx*********************bb" Nov 03 17:03:28 lr-volumio go-librespot[8029]: time="2025-11-03T17:03:28+01:00" level=debug msg="dealer connection opened" Nov 03 17:03:28 lr-volumio go-librespot[8029]: time="2025-11-03T17:03:28+01:00" level=trace msg="starting accesspoint recv loop" Nov 03 17:03:28 lr-volumio go-librespot[8029]: time="2025-11-03T17:03:28+01:00" level=trace msg="starting dealer recv loop" Nov 03 17:03:28 lr-volumio go-librespot[8029]: time="2025-11-03T17:03:28+01:00" level=trace msg="received accesspoint ping" Nov 03 17:03:28 lr-volumio go-librespot[8029]: time="2025-11-03T17:03:28+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Nov 03 17:03:28 lr-volumio go-librespot[8029]: time="2025-11-03T17:03:28+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Nov 03 17:03:28 lr-volumio go-librespot[8029]: time="2025-11-03T17:03:28+01:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Nov 03 17:03:28 lr-volumio go-librespot[8029]: time="2025-11-03T17:03:28+01:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Nov 03 17:03:28 lr-volumio go-librespot[8029]: time="2025-11-03T17:03:28+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Nov 03 17:03:28 lr-volumio go-librespot[8029]: time="2025-11-03T17:03:28+01:00" level=debug msg="received connection id: NGFlN2MyNTItMThi...NTJGNzY4OTU3MQ==" Nov 03 17:03:28 lr-volumio go-librespot[8029]: time="2025-11-03T17:03:28+01:00" level=trace msg="received accesspoint pong ack" Nov 03 17:03:28 lr-volumio go-librespot[8029]: time="2025-11-03T17:03:28+01:00" level=debug msg="put connect state because NEW_DEVICE" Nov 03 17:03:30 lr-volumio volumio[973]: info: go-librespot daemon successfully initialized Nov 03 17:03:31 lr-volumio volumio[973]: info: go-librespot daemon successfully initialized Nov 03 17:03:31 lr-volumio volumio[973]: info: go-librespot daemon successfully initialized Nov 03 17:03:31 lr-volumio volumio[973]: info: CALLMETHOD: music_service spop saveGoLibrespotSettings [object Object] Nov 03 17:03:31 lr-volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: spop , saveGoLibrespotSettings Nov 03 17:03:31 lr-volumio volumio[973]: info: Creating Spotify config file Nov 03 17:03:31 lr-volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 17:03:31 lr-volumio volumio[973]: info: Spotify config file written Nov 03 17:03:31 lr-volumio sudo[8041]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Nov 03 17:03:31 lr-volumio sudo[8041]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 17:03:31 lr-volumio systemd[1]: Stopping go-librespot Daemon... Nov 03 17:03:31 lr-volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Nov 03 17:03:31 lr-volumio systemd[1]: go-librespot-daemon.service: Succeeded. Nov 03 17:03:31 lr-volumio systemd[1]: Stopped go-librespot Daemon. Nov 03 17:03:31 lr-volumio volumio[973]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 03 17:03:31 lr-volumio volumio[973]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 03 17:03:31 lr-volumio systemd[1]: Started go-librespot Daemon. Nov 03 17:03:31 lr-volumio go-librespot[8043]: go-librespot daemon starting... Nov 03 17:03:31 lr-volumio sudo[8041]: pam_unix(sudo:session): session closed for user root Nov 03 17:03:31 lr-volumio go-librespot[8043]: time="2025-11-03T17:03:31+01:00" level=info msg="running go-librespot 0.4.0" Nov 03 17:03:31 lr-volumio go-librespot[8043]: time="2025-11-03T17:03:31+01:00" level=debug msg="app state loaded" Nov 03 17:03:31 lr-volumio go-librespot[8043]: time="2025-11-03T17:03:31+01:00" level=info msg="api server listening on 127.0.0.1:9879" Nov 03 17:03:31 lr-volumio go-librespot[8043]: time="2025-11-03T17:03:31+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 03 17:03:31 lr-volumio go-librespot[8043]: time="2025-11-03T17:03:31+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 03 17:03:31 lr-volumio go-librespot[8043]: time="2025-11-03T17:03:31+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 03 17:03:31 lr-volumio go-librespot[8043]: time="2025-11-03T17:03:31+01:00" level=info msg="zeroconf server listening on port 36153" Nov 03 17:03:31 lr-volumio go-librespot[8043]: time="2025-11-03T17:03:31+01:00" level=debug msg="obtained new client token: AAAT9hufVFn3e9Oiu5/ZtJ4lWqhclCqLCE30Kqsn2NpxWRs4QRtSdSgyHn0lcjNx7+rNdZXYoqnIBhkHlhkD1+neIzkfNc+WtSPlvLkpm9oLKAN/A7bjsfLuFf398lOg4npXxKo4wcPqvWI6ISmxwkoiEW1YG9o+SXvOvn3IWJk4Q+O9wrZoagZ3f9UF7FzFmt/hYN6e/S9f+Ob3E7wf06xLd993uKOTxGIr0vH+eCkLEmku/6AEhK86tA==" Nov 03 17:03:31 lr-volumio go-librespot[8043]: time="2025-11-03T17:03:31+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Nov 03 17:03:31 lr-volumio go-librespot[8043]: time="2025-11-03T17:03:31+01:00" level=debug msg="completed keyexchange" Nov 03 17:03:31 lr-volumio go-librespot[8043]: time="2025-11-03T17:03:31+01:00" level=debug msg="completed challenge" Nov 03 17:03:31 lr-volumio go-librespot[8043]: time="2025-11-03T17:03:31+01:00" level=info msg="authenticated AP" username="nx*********************bb" Nov 03 17:03:31 lr-volumio go-librespot[8043]: time="2025-11-03T17:03:31+01:00" level=info msg="authenticated Login5" username="nx*********************bb" Nov 03 17:03:31 lr-volumio go-librespot[8043]: time="2025-11-03T17:03:31+01:00" level=debug msg="initializing zeroconf session" username="nx*********************bb" Nov 03 17:03:31 lr-volumio go-librespot[8043]: time="2025-11-03T17:03:31+01:00" level=debug msg="dealer connection opened" Nov 03 17:03:31 lr-volumio go-librespot[8043]: time="2025-11-03T17:03:31+01:00" level=trace msg="starting accesspoint recv loop" Nov 03 17:03:31 lr-volumio go-librespot[8043]: time="2025-11-03T17:03:31+01:00" level=trace msg="starting dealer recv loop" Nov 03 17:03:31 lr-volumio go-librespot[8043]: time="2025-11-03T17:03:31+01:00" level=trace msg="received accesspoint ping" Nov 03 17:03:31 lr-volumio go-librespot[8043]: time="2025-11-03T17:03:31+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Nov 03 17:03:31 lr-volumio go-librespot[8043]: time="2025-11-03T17:03:31+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Nov 03 17:03:31 lr-volumio go-librespot[8043]: time="2025-11-03T17:03:31+01:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Nov 03 17:03:31 lr-volumio go-librespot[8043]: time="2025-11-03T17:03:31+01:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Nov 03 17:03:31 lr-volumio go-librespot[8043]: time="2025-11-03T17:03:31+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Nov 03 17:03:31 lr-volumio go-librespot[8043]: time="2025-11-03T17:03:31+01:00" level=debug msg="received connection id: YjU3NWE2YWItMDMy...MzEyRDVGQjdDMA==" Nov 03 17:03:31 lr-volumio go-librespot[8043]: time="2025-11-03T17:03:31+01:00" level=trace msg="received accesspoint pong ack" Nov 03 17:03:31 lr-volumio go-librespot[8043]: time="2025-11-03T17:03:31+01:00" level=debug msg="put connect state because NEW_DEVICE" Nov 03 17:03:33 lr-volumio volumio[973]: info: Initializing connection to go-librespot Websocket Nov 03 17:03:33 lr-volumio go-librespot[8043]: time="2025-11-03T17:03:33+01:00" level=debug msg="new websocket client" Nov 03 17:03:33 lr-volumio volumio[973]: info: Connection to go-librespot Websocket established Nov 03 17:03:34 lr-volumio volumio[973]: info: CALLMETHOD: music_service spop logout Nov 03 17:03:34 lr-volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: spop , logout Nov 03 17:03:34 lr-volumio volumio[973]: info: Deleting Spotify credentials File Nov 03 17:03:34 lr-volumio volumio[973]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSpotify Nov 03 17:03:34 lr-volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 03 17:03:34 lr-volumio volumio[973]: Cannot find translation for source cht-bsw-rt5672 Nov 03 17:03:34 lr-volumio volumio[973]: info: Initializing connection to go-librespot Websocket Nov 03 17:03:34 lr-volumio go-librespot[8043]: time="2025-11-03T17:03:34+01:00" level=debug msg="new websocket client" Nov 03 17:03:34 lr-volumio volumio[973]: info: Connection to go-librespot Websocket established Nov 03 17:03:34 lr-volumio volumio[973]: info: Initializing connection to go-librespot Websocket Nov 03 17:03:34 lr-volumio go-librespot[8043]: time="2025-11-03T17:03:34+01:00" level=debug msg="new websocket client" Nov 03 17:03:34 lr-volumio volumio[973]: info: Connection to go-librespot Websocket established Nov 03 17:03:34 lr-volumio volumio[973]: info: go-librespot daemon successfully initialized Nov 03 17:03:35 lr-volumio volumio[973]: info: Creating Spotify config file Nov 03 17:03:35 lr-volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 17:03:35 lr-volumio volumio[973]: info: Spotify config file written Nov 03 17:03:35 lr-volumio sudo[8052]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Nov 03 17:03:35 lr-volumio sudo[8052]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 17:03:35 lr-volumio systemd[1]: Stopping go-librespot Daemon... Nov 03 17:03:35 lr-volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Nov 03 17:03:35 lr-volumio systemd[1]: go-librespot-daemon.service: Succeeded. Nov 03 17:03:35 lr-volumio systemd[1]: Stopped go-librespot Daemon. Nov 03 17:03:35 lr-volumio volumio[973]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 03 17:03:35 lr-volumio volumio[973]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 03 17:03:35 lr-volumio volumio[973]: info: Connection to go-librespot Websocket closed Nov 03 17:03:35 lr-volumio volumio[973]: info: Connection to go-librespot Websocket closed Nov 03 17:03:35 lr-volumio volumio[973]: info: Connection to go-librespot Websocket closed Nov 03 17:03:35 lr-volumio systemd[1]: Started go-librespot Daemon. Nov 03 17:03:35 lr-volumio go-librespot[8054]: go-librespot daemon starting... Nov 03 17:03:35 lr-volumio sudo[8052]: pam_unix(sudo:session): session closed for user root Nov 03 17:03:35 lr-volumio go-librespot[8054]: time="2025-11-03T17:03:35+01:00" level=info msg="running go-librespot 0.4.0" Nov 03 17:03:35 lr-volumio go-librespot[8054]: time="2025-11-03T17:03:35+01:00" level=debug msg="no app state found" Nov 03 17:03:35 lr-volumio go-librespot[8054]: time="2025-11-03T17:03:35+01:00" level=debug msg="stored credentials not found" Nov 03 17:03:35 lr-volumio go-librespot[8054]: time="2025-11-03T17:03:35+01:00" level=info msg="generated new device id: 8f62205e7a1bcb0f008899a95bf2b1eb3e8e9e31" Nov 03 17:03:35 lr-volumio go-librespot[8054]: time="2025-11-03T17:03:35+01:00" level=info msg="api server listening on 127.0.0.1:9879" Nov 03 17:03:35 lr-volumio go-librespot[8054]: time="2025-11-03T17:03:35+01: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-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 03 17:03:35 lr-volumio go-librespot[8054]: time="2025-11-03T17:03:35+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 03 17:03:35 lr-volumio go-librespot[8054]: time="2025-11-03T17:03:35+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 03 17:03:35 lr-volumio go-librespot[8054]: time="2025-11-03T17:03:35+01:00" level=info msg="zeroconf server listening on port 37737" Nov 03 17:03:36 lr-volumio volumio[973]: info: Getting Spotify volume Nov 03 17:03:36 lr-volumio volumio[973]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Nov 03 17:03:36 lr-volumio volumio[973]: info: CoreCommandRouter::volumioGetState Nov 03 17:03:36 lr-volumio volumio[973]: info: CorePlayQueue::getTrack 0 Nov 03 17:03:36 lr-volumio volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 49 Nov 03 17:03:37 lr-volumio volumio[973]: info: Getting Spotify volume Nov 03 17:03:37 lr-volumio volumio[973]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Nov 03 17:03:37 lr-volumio volumio[973]: info: CoreCommandRouter::volumioGetState Nov 03 17:03:37 lr-volumio volumio[973]: info: CorePlayQueue::getTrack 0 Nov 03 17:03:37 lr-volumio volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 49 Nov 03 17:03:37 lr-volumio volumio[973]: info: Getting Spotify volume Nov 03 17:03:37 lr-volumio volumio[973]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Nov 03 17:03:37 lr-volumio volumio[973]: info: CoreCommandRouter::volumioGetState Nov 03 17:03:37 lr-volumio volumio[973]: info: CorePlayQueue::getTrack 0 Nov 03 17:03:37 lr-volumio volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 49 Nov 03 17:03:37 lr-volumio volumio[973]: info: Initializing connection to go-librespot Websocket Nov 03 17:03:37 lr-volumio go-librespot[8054]: time="2025-11-03T17:03:37+01:00" level=debug msg="new websocket client" Nov 03 17:03:37 lr-volumio volumio[973]: info: Connection to go-librespot Websocket established Nov 03 17:03:38 lr-volumio volumio[973]: info: Initializing connection to go-librespot Websocket Nov 03 17:03:38 lr-volumio go-librespot[8054]: time="2025-11-03T17:03:38+01:00" level=debug msg="new websocket client" Nov 03 17:03:38 lr-volumio volumio[973]: info: Connection to go-librespot Websocket established Nov 03 17:03:38 lr-volumio volumio[973]: info: go-librespot daemon successfully initialized Nov 03 17:03:38 lr-volumio volumio[973]: info: CoreCommandRouter::volumioGetState Nov 03 17:03:38 lr-volumio volumio[973]: info: CorePlayQueue::getTrack 0 Nov 03 17:03:38 lr-volumio volumio[973]: info: CALLMETHOD: music_service spop saveGoLibrespotSettings [object Object] Nov 03 17:03:38 lr-volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: spop , saveGoLibrespotSettings Nov 03 17:03:38 lr-volumio volumio[973]: info: Creating Spotify config file Nov 03 17:03:38 lr-volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 17:03:38 lr-volumio volumio[973]: info: Spotify config file written Nov 03 17:03:38 lr-volumio sudo[8077]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Nov 03 17:03:38 lr-volumio sudo[8077]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 17:03:38 lr-volumio systemd[1]: Stopping go-librespot Daemon... Nov 03 17:03:38 lr-volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Nov 03 17:03:38 lr-volumio systemd[1]: go-librespot-daemon.service: Succeeded. Nov 03 17:03:38 lr-volumio systemd[1]: Stopped go-librespot Daemon. Nov 03 17:03:38 lr-volumio volumio[973]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 03 17:03:38 lr-volumio volumio[973]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 03 17:03:38 lr-volumio volumio[973]: info: Connection to go-librespot Websocket closed Nov 03 17:03:38 lr-volumio volumio[973]: info: Connection to go-librespot Websocket closed Nov 03 17:03:38 lr-volumio systemd[1]: Started go-librespot Daemon. Nov 03 17:03:38 lr-volumio sudo[8077]: pam_unix(sudo:session): session closed for user root Nov 03 17:03:38 lr-volumio go-librespot[8079]: go-librespot daemon starting... Nov 03 17:03:38 lr-volumio go-librespot[8079]: time="2025-11-03T17:03:38+01:00" level=info msg="running go-librespot 0.4.0" Nov 03 17:03:38 lr-volumio go-librespot[8079]: time="2025-11-03T17:03:38+01:00" level=debug msg="app state loaded" Nov 03 17:03:38 lr-volumio go-librespot[8079]: time="2025-11-03T17:03:38+01:00" level=debug msg="stored credentials not found" Nov 03 17:03:38 lr-volumio go-librespot[8079]: time="2025-11-03T17:03:38+01:00" level=info msg="api server listening on 127.0.0.1:9879" Nov 03 17:03:38 lr-volumio go-librespot[8079]: time="2025-11-03T17:03:38+01: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-gew1.spotify.com:80]" Nov 03 17:03:38 lr-volumio go-librespot[8079]: time="2025-11-03T17:03:38+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Nov 03 17:03:38 lr-volumio go-librespot[8079]: time="2025-11-03T17:03:38+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Nov 03 17:03:38 lr-volumio go-librespot[8079]: time="2025-11-03T17:03:38+01:00" level=info msg="zeroconf server listening on port 38377" Nov 03 17:03:40 lr-volumio volumio[973]: info: Getting Spotify volume Nov 03 17:03:40 lr-volumio volumio[973]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Nov 03 17:03:40 lr-volumio volumio[973]: info: CoreCommandRouter::volumioGetState Nov 03 17:03:40 lr-volumio volumio[973]: info: CorePlayQueue::getTrack 0 Nov 03 17:03:40 lr-volumio volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 49 Nov 03 17:03:41 lr-volumio volumio[973]: info: Getting Spotify volume Nov 03 17:03:41 lr-volumio volumio[973]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Nov 03 17:03:41 lr-volumio volumio[973]: info: Initializing connection to go-librespot Websocket Nov 03 17:03:41 lr-volumio go-librespot[8079]: time="2025-11-03T17:03:41+01:00" level=debug msg="new websocket client" Nov 03 17:03:41 lr-volumio volumio[973]: info: CoreCommandRouter::volumioGetState Nov 03 17:03:41 lr-volumio volumio[973]: info: CorePlayQueue::getTrack 0 Nov 03 17:03:41 lr-volumio volumio[973]: info: Connection to go-librespot Websocket established Nov 03 17:03:41 lr-volumio volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 49 Nov 03 17:03:41 lr-volumio volumio[973]: info: CoreCommandRouter::volumioGetState Nov 03 17:03:41 lr-volumio volumio[973]: info: CorePlayQueue::getTrack 0 Nov 03 17:03:41 lr-volumio volumio[973]: info: Initializing connection to go-librespot Websocket Nov 03 17:03:41 lr-volumio go-librespot[8079]: time="2025-11-03T17:03:41+01:00" level=debug msg="new websocket client" Nov 03 17:03:41 lr-volumio volumio[973]: info: Connection to go-librespot Websocket established Nov 03 17:03:41 lr-volumio volumio[973]: info: go-librespot daemon successfully initialized Nov 03 17:03:42 lr-volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 03 17:03:42 lr-volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Nov 03 17:03:44 lr-volumio volumio[973]: info: Getting Spotify volume Nov 03 17:03:44 lr-volumio volumio[973]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Nov 03 17:03:44 lr-volumio volumio[973]: info: CoreCommandRouter::volumioGetState Nov 03 17:03:44 lr-volumio volumio[973]: info: CorePlayQueue::getTrack 0 Nov 03 17:03:44 lr-volumio volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 49 Nov 03 17:03:44 lr-volumio volumio[973]: info: Getting Spotify volume Nov 03 17:03:44 lr-volumio volumio[973]: info: Initializing connection to go-librespot Websocket Nov 03 17:03:44 lr-volumio volumio[973]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Nov 03 17:03:44 lr-volumio go-librespot[8079]: time="2025-11-03T17:03:44+01:00" level=debug msg="new websocket client" Nov 03 17:03:44 lr-volumio volumio[973]: info: Connection to go-librespot Websocket established Nov 03 17:03:44 lr-volumio volumio[973]: info: CoreCommandRouter::volumioGetState Nov 03 17:03:44 lr-volumio volumio[973]: info: CorePlayQueue::getTrack 0 Nov 03 17:03:44 lr-volumio volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 49 Nov 03 17:03:47 lr-volumio volumio[973]: info: Starting Uninstall of plugin music_service - spop Nov 03 17:03:47 lr-volumio volumio[973]: info: Uninstalling plugin spop Nov 03 17:03:47 lr-volumio go-librespot[8079]: time="2025-11-03T17:03:47+01:00" level=error msg="websocket connection errored" error="failed to get reader: failed to read frame header: EOF" Nov 03 17:03:47 lr-volumio volumio[973]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSpotify Nov 03 17:03:47 lr-volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 03 17:03:47 lr-volumio volumio[973]: Cannot find translation for source cht-bsw-rt5672 Nov 03 17:03:47 lr-volumio sudo[8108]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop go-librespot-daemon.service Nov 03 17:03:47 lr-volumio sudo[8108]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 17:03:47 lr-volumio volumio[973]: info: Disabling plugin spop Nov 03 17:03:47 lr-volumio systemd[1]: Stopping go-librespot Daemon... Nov 03 17:03:47 lr-volumio volumio[973]: info: Checking if uninstall.sh is present Nov 03 17:03:47 lr-volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Nov 03 17:03:47 lr-volumio systemd[1]: go-librespot-daemon.service: Succeeded. Nov 03 17:03:47 lr-volumio volumio[973]: info: Connection to go-librespot Websocket closed Nov 03 17:03:47 lr-volumio systemd[1]: Stopped go-librespot Daemon. Nov 03 17:03:47 lr-volumio volumio[973]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 03 17:03:47 lr-volumio volumio[973]: info: Executing uninstall.sh Nov 03 17:03:47 lr-volumio sudo[8108]: pam_unix(sudo:session): session closed for user root Nov 03 17:03:47 lr-volumio volumio[973]: info: Connection to go-librespot Websocket closed Nov 03 17:03:47 lr-volumio volumio[973]: info: Connection to go-librespot Websocket closed Nov 03 17:03:47 lr-volumio sudo[8112]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/music_service/spop/uninstall.sh Nov 03 17:03:47 lr-volumio sudo[8112]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 17:03:47 lr-volumio sudo[8112]: pam_unix(sudo:session): session closed for user root Nov 03 17:03:47 lr-volumio volumio[973]: info: Uninstall script completed Nov 03 17:03:47 lr-volumio volumio[973]: info: Removing plugin spop from configuration Nov 03 17:03:47 lr-volumio volumio[973]: info: Successfully removed spop configuration files Nov 03 17:03:47 lr-volumio volumio[973]: info: Plugin folders cleanup Nov 03 17:03:47 lr-volumio volumio[973]: info: Scanning into folder /volumio/app/plugins/ Nov 03 17:03:47 lr-volumio volumio[973]: info: Scanning category audio_interface Nov 03 17:03:47 lr-volumio volumio[973]: info: Scanning category miscellanea Nov 03 17:03:47 lr-volumio volumio[973]: info: Scanning category music_service Nov 03 17:03:47 lr-volumio volumio[973]: info: Scanning category plugins.json Nov 03 17:03:47 lr-volumio volumio[973]: info: Scanning category system_controller Nov 03 17:03:47 lr-volumio volumio[973]: info: Scanning category user_interface Nov 03 17:03:47 lr-volumio volumio[973]: info: Scanning into folder /data/plugins/ Nov 03 17:03:47 lr-volumio volumio[973]: info: Scanning category audio_interface Nov 03 17:03:47 lr-volumio volumio[973]: info: Scanning category music_service Nov 03 17:03:47 lr-volumio volumio[973]: info: Cleaning folder for spop Nov 03 17:03:48 lr-volumio volumio[973]: info: Plugin folders cleanup completed Nov 03 17:03:48 lr-volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 03 17:03:48 lr-volumio volumio[973]: info: [{"prettyName":"FusionDsp","name":"fusiondsp","category":"audio_interface","version":"1.0.62","icon":"fa-sliders fa-rotate-90","isManuallyInstalled":false,"enabled":false,"active":false},{"prettyName":"Squeezelite MC","name":"squeezelite_mc","category":"music_service","version":"1.0.4","icon":"fa-play-circle-o","isManuallyInstalled":false,"enabled":true,"active":true}] Nov 03 17:03:48 lr-volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Nov 03 17:03:48 lr-volumio volumio[973]: info: Getting Spotify volume Nov 03 17:03:48 lr-volumio volumio[973]: (node:973) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 03 17:03:48 lr-volumio volumio[973]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) Nov 03 17:03:48 lr-volumio volumio[973]: (node:973) 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: 3) Nov 03 17:03:48 lr-volumio volumio[973]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Nov 03 17:03:48 lr-volumio volumio[973]: info: CoreCommandRouter::volumioGetState Nov 03 17:03:48 lr-volumio volumio[973]: info: CorePlayQueue::getTrack 0 Nov 03 17:03:48 lr-volumio volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 49 Nov 03 17:03:48 lr-volumio volumio[973]: info: CoreCommandRouter::volumioGetState Nov 03 17:03:48 lr-volumio volumio[973]: info: CorePlayQueue::getTrack 0 Nov 03 17:03:48 lr-volumio volumio[973]: info: Listing playlists Nov 03 17:03:56 lr-volumio volumio[973]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/spop/volumio/buster/amd64 Nov 03 17:03:57 lr-volumio volumio[973]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/spop/volumio/buster/amd64 Nov 03 17:03:58 lr-volumio volumio[973]: info: Folder /tmp/plugins removed Nov 03 17:03:58 lr-volumio volumio[973]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 03 17:03:58 lr-volumio volumio[973]: Error: Unable to resolve or reject the same promise twice Nov 03 17:03:58 lr-volumio volumio[973]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Nov 03 17:03:58 lr-volumio volumio[973]: at /volumio/app/pluginmanager.js:1087:11 Nov 03 17:03:58 lr-volumio volumio[973]: at CB (/volumio/node_modules/fs-extra/lib/remove/rimraf.js:57:5) Nov 03 17:03:58 lr-volumio volumio[973]: at FSReqCallback.oncomplete (fs.js:180:23) Nov 03 17:03:58 lr-volumio volumio[973]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 03 17:03:58 lr-volumio sudo[8150]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-11-03 17:02 Nov 03 17:03:58 lr-volumio sudo[8150]: 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="669bb6cc406bb77b11c5184fedb8a4dfd19ba4ec" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 11:08:46 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="9ef0bfa9b61509be20c9051562554f31"