-- Logs begin at Wed 2025-04-30 22:09:14 CEST, end at Sat 2025-05-03 19:19:50 CEST. --
May 03 19:18:26 volumio systemd-timedated[1411]: Changed local time to Sat May 3 19:18:26 2025
May 03 19:18:26 volumio volumio[868]: info: Discovery: A device disappeared from network
May 03 19:18:26 volumio volumio[868]: info: Discovery: Browse raised the following error TypeError: Cannot read property 'toLowerCase' of undefined
May 03 19:18:26 volumio systemd[1]: Starting Daily apt download activities...
May 03 19:18:26 volumio volumio-time-update[614]: volumio-time-update-util: System time updated successfully.
May 03 19:18:26 volumio sudo[1409]: pam_unix(sudo:session): session closed for user root
May 03 19:18:26 volumio systemd[1]: Starting Daily man-db regeneration...
May 03 19:18:26 volumio systemd[1]: Started Volumio Time Update Utility.
May 03 19:18:26 volumio systemd[1]: Reached target Multi-User System.
May 03 19:18:26 volumio systemd[1]: Reached target Graphical Interface.
May 03 19:18:26 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes...
May 03 19:18:26 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded.
May 03 19:18:26 volumio systemd[1]: Started Update UTMP about System Runlevel Changes.
May 03 19:18:26 volumio volumio[868]: info: Initializing connection to go-librespot Websocket
May 03 19:18:26 volumio ntpd[806]: Listen normally on 4 eth0 192.168.1.144:123
May 03 19:18:26 volumio ntpd[806]: Deleting interface #3 eth0, 169.254.175.239#123, interface stats: received=0, sent=0, dropped=0, active_time=10 secs
May 03 19:18:26 volumio ntpd[806]: new interface(s) found: waking up resolver
May 03 19:18:28 volumio volumio[868]: info: Discovery: adding ff68d2f4-cc46-4f0e-92fe-fdbbcb77de2c
May 03 19:18:28 volumio volumio[868]: info: Discovery: Found device Volumio
May 03 19:18:28 volumio volumio[868]: info: CoreCommandRouter::volumioGetState
May 03 19:18:28 volumio volumio[868]: info: CorePlayQueue::getTrack 0
May 03 19:18:28 volumio systemd[1]: systemd-fsckd.service: Succeeded.
May 03 19:18:28 volumio go-librespot[1212]: time="2025-05-03T19:18:28+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]"
May 03 19:18:28 volumio go-librespot[1212]: time="2025-05-03T19:18:28+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]"
May 03 19:18:28 volumio go-librespot[1212]: time="2025-05-03T19:18:28+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]"
May 03 19:18:28 volumio go-librespot[1212]: time="2025-05-03T19:18:28+02:00" level=debug msg="zeroconf server listening on port 42031"
May 03 19:18:28 volumio go-librespot[1212]: time="2025-05-03T19:18:28+02:00" level=debug msg="obtained new client token: AACvz5+yWtczgNyNFZuRjEbbJ8Q0G+xl8nYIL3wxrFYFisIAXQjo2DAXFretNcreLDW5Lc0sOMZ6D9zwgFi//daheSfBWnz1s5np7cAPOpjqszhRER7H2ERSUs/zCUkl5lZb4YVNgVd0i/iMQL3wjnaWCru4c8OXdm1K4xU5dOs2oD7FLtGy1euzAK+kLRS9yaTvpkTE8md24GJYuhXKNdNeg8MzomEd04Mzn3ySxpZRRzsz/1EzKj2sOwkF"
May 03 19:18:28 volumio go-librespot[1212]: time="2025-05-03T19:18:28+02:00" level=info msg="connected to ap-gew1.spotify.com:4070"
May 03 19:18:28 volumio volumio[868]: info: Discovery: this is already registered, ff68d2f4-cc46-4f0e-92fe-fdbbcb77de2c
May 03 19:18:28 volumio volumio[868]: info: Discovery: Found device Volumio
May 03 19:18:28 volumio volumio[868]: info: CoreCommandRouter::volumioGetState
May 03 19:18:28 volumio volumio[868]: info: CorePlayQueue::getTrack 0
May 03 19:18:28 volumio go-librespot[1212]: time="2025-05-03T19:18:28+02:00" level=debug msg="completed keyexchange"
May 03 19:18:28 volumio go-librespot[1212]: time="2025-05-03T19:18:28+02:00" level=debug msg="new websocket client"
May 03 19:18:28 volumio volumio[868]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3
May 03 19:18:28 volumio volumio[868]: info: Connection to go-librespot Websocket established
May 03 19:18:28 volumio volumio[868]: info: Volumio called home
May 03 19:18:28 volumio go-librespot[1212]: time="2025-05-03T19:18:28+02:00" level=debug msg="completed challenge"
May 03 19:18:29 volumio go-librespot[1212]: time="2025-05-03T19:18:29+02:00" level=debug msg="authenticated as iesus_80"
May 03 19:18:29 volumio go-librespot[1212]: time="2025-05-03T19:18:29+02:00" level=debug msg="authenticated as iesus_80"
May 03 19:18:29 volumio go-librespot[1212]: time="2025-05-03T19:18:29+02:00" level=debug msg="dealer connection opened"
May 03 19:18:29 volumio go-librespot[1212]: time="2025-05-03T19:18:29+02:00" level=debug msg="initializing zeroconf session, username: iesus_80"
May 03 19:18:29 volumio go-librespot[1212]: time="2025-05-03T19:18:29+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336"
May 03 19:18:29 volumio go-librespot[1212]: time="2025-05-03T19:18:29+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2"
May 03 19:18:29 volumio go-librespot[1212]: time="2025-05-03T19:18:29+02:00" level=debug msg="autoplay enabled: false"
May 03 19:18:29 volumio go-librespot[1212]: time="2025-05-03T19:18:29+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17"
May 03 19:18:29 volumio go-librespot[1212]: time="2025-05-03T19:18:29+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0"
May 03 19:18:29 volumio go-librespot[1212]: time="2025-05-03T19:18:29+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481"
May 03 19:18:29 volumio go-librespot[1212]: time="2025-05-03T19:18:29+02:00" level=debug msg="received connection id: YjhiMTY5NGQtOTIzNi00YzZlLThkNTAtOGFjNmYzZmY1MmU1K2RlYWxlcit0Y3A6Ly8wYWNhNDAwYi5pcC5nZXcxLnNwb3RpZnkubmV0OjU3MDArM0MyNjU3QTNDNDIwRTc5RDBDMkY5MEY3QzYyQ0U4N0YwQzUyQ0FGMDA0RjkxQUI3NDFDRDg1NjhGNjY5NEEzRg=="
May 03 19:18:29 volumio volumio[868]: info: CoreCommandRouter::volumioGetState
May 03 19:18:29 volumio volumio[868]: info: CorePlayQueue::getTrack 0
May 03 19:18:29 volumio go-librespot[1212]: time="2025-05-03T19:18:29+02:00" level=debug msg="put connect state because NEW_DEVICE"
May 03 19:18:29 volumio volumio[868]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------
May 03 19:18:29 volumio volumio[868]: SPOTIFY: BQCYI0ddDhz0fzCJD5nzKXT3KeXTVs8vmHfq8zCL9ZyrJX7gsB2AYg-PhDJoxfR1PoC1A_CoTt0rMxHaXZuheUB1QNYl7JsiOpXs-LwVTLPSpLAyFvNdTN_87Mz272bsBwVIL2Jsj24qkK22LzUPFlPUDMggaXH15Sf217rJItPhdcMUngn2WX_gQ4BDcaJPUsu0f4JHhPeQQdeIlsWvhS5uuCIDASEDhu3t5-bqois1xwavhOitngzVE3rA-RTwGjMG2vldATxBMdVZh6Iv
May 03 19:18:29 volumio volumio[868]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------
May 03 19:18:29 volumio volumio[868]: info: New Spotify access token = BQCYI0ddDhz0fzCJD5nzKXT3KeXTVs8vmHfq8zCL9ZyrJX7gsB2AYg-PhDJoxfR1PoC1A_CoTt0rMxHaXZuheUB1QNYl7JsiOpXs-LwVTLPSpLAyFvNdTN_87Mz272bsBwVIL2Jsj24qkK22LzUPFlPUDMggaXH15Sf217rJItPhdcMUngn2WX_gQ4BDcaJPUsu0f4JHhPeQQdeIlsWvhS5uuCIDASEDhu3t5-bqois1xwavhOitngzVE3rA-RTwGjMG2vldATxBMdVZh6Iv
May 03 19:18:29 volumio volumio[868]: info: Spotify credentials grant success - running version from March 24, 2019
May 03 19:18:30 volumio volumio[868]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium
May 03 19:18:30 volumio volumio[868]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium
May 03 19:18:30 volumio volumio[868]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium
May 03 19:18:30 volumio volumio[868]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium
May 03 19:18:30 volumio volumio[868]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium
May 03 19:18:30 volumio volumio[868]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium
May 03 19:18:30 volumio volumio[868]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium
May 03 19:18:30 volumio volumio[868]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium
May 03 19:18:30 volumio volumio[868]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium
May 03 19:18:30 volumio volumio[868]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium
May 03 19:18:30 volumio volumio[868]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium
May 03 19:18:30 volumio volumio[868]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar
May 03 19:18:30 volumio volumio[868]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar
May 03 19:18:30 volumio volumio[868]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar
May 03 19:18:30 volumio volumio[868]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar
May 03 19:18:30 volumio volumio[868]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar
May 03 19:18:30 volumio volumio[868]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar
May 03 19:18:30 volumio volumio[868]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar
May 03 19:18:30 volumio volumio[868]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar
May 03 19:18:30 volumio volumio[868]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar
May 03 19:18:30 volumio volumio[868]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar
May 03 19:18:30 volumio volumio[868]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso
May 03 19:18:30 volumio volumio[868]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso
May 03 19:18:30 volumio volumio[868]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso
May 03 19:18:30 volumio volumio[868]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso
May 03 19:18:30 volumio volumio[868]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso
May 03 19:18:30 volumio volumio[868]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso
May 03 19:18:30 volumio volumio[868]: info: Adding plugin bluetooth to MyMusic Plugins
May 03 19:18:30 volumio volumio[868]: info: Adding plugin multiroom to MyMusic Plugins
May 03 19:18:30 volumio volumio[868]: info: Adding plugin metavolumio to MyMusic Plugins
May 03 19:18:30 volumio volumio[868]: info: Adding plugin cd_controller to MyMusic Plugins
May 03 19:18:31 volumio volumio[868]: info: Adding plugin smart_inputs to MyMusic Plugins
May 03 19:18:31 volumio volumio[868]: info: Adding plugin tidalconnect to MyMusic Plugins
May 03 19:18:31 volumio volumio[868]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"...
May 03 19:18:32 volumio systemd[1]: systemd-hostnamed.service: Succeeded.
May 03 19:18:34 volumio nmbd[770]: [2025/05/03 19:18:34.800665, 0] ../source3/libsmb/nmblib.c:917(send_udp)
May 03 19:18:34 volumio nmbd[770]: Packet send failed to 169.254.255.255(138) ERRNO=Network is unreachable
May 03 19:18:34 volumio nmbd[770]: [2025/05/03 19:18:34.801831, 0] ../source3/libsmb/nmblib.c:917(send_udp)
May 03 19:18:34 volumio nmbd[770]: Packet send failed to 127.0.0.1(137) ERRNO=Network is unreachable
May 03 19:18:34 volumio nmbd[770]: [2025/05/03 19:18:34.801909, 0] ../source3/nmbd/nmbd_packets.c:180(send_netbios_packet)
May 03 19:18:34 volumio nmbd[770]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed
May 03 19:18:34 volumio nmbd[770]: [2025/05/03 19:18:34.801971, 0] ../source3/libsmb/nmblib.c:917(send_udp)
May 03 19:18:34 volumio nmbd[770]: Packet send failed to 127.0.0.1(137) ERRNO=Network is unreachable
May 03 19:18:34 volumio nmbd[770]: [2025/05/03 19:18:34.802027, 0] ../source3/nmbd/nmbd_packets.c:180(send_netbios_packet)
May 03 19:18:34 volumio nmbd[770]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed
May 03 19:18:34 volumio nmbd[770]: [2025/05/03 19:18:34.802083, 0] ../source3/libsmb/nmblib.c:917(send_udp)
May 03 19:18:34 volumio nmbd[770]: Packet send failed to 127.0.0.1(137) ERRNO=Network is unreachable
May 03 19:18:34 volumio nmbd[770]: [2025/05/03 19:18:34.802137, 0] ../source3/nmbd/nmbd_packets.c:180(send_netbios_packet)
May 03 19:18:34 volumio nmbd[770]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed
May 03 19:18:34 volumio nmbd[770]: [2025/05/03 19:18:34.802192, 0] ../source3/libsmb/nmblib.c:917(send_udp)
May 03 19:18:34 volumio nmbd[770]: Packet send failed to 127.0.0.1(137) ERRNO=Network is unreachable
May 03 19:18:34 volumio nmbd[770]: [2025/05/03 19:18:34.802245, 0] ../source3/nmbd/nmbd_packets.c:180(send_netbios_packet)
May 03 19:18:34 volumio nmbd[770]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed
May 03 19:18:34 volumio nmbd[770]: [2025/05/03 19:18:34.802299, 0] ../source3/libsmb/nmblib.c:917(send_udp)
May 03 19:18:34 volumio nmbd[770]: Packet send failed to 127.0.0.1(137) ERRNO=Network is unreachable
May 03 19:18:34 volumio nmbd[770]: [2025/05/03 19:18:34.802351, 0] ../source3/nmbd/nmbd_packets.c:180(send_netbios_packet)
May 03 19:18:34 volumio nmbd[770]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed
May 03 19:18:35 volumio systemd[1]: apt-daily.service: Succeeded.
May 03 19:18:35 volumio systemd[1]: Started Daily apt download activities.
May 03 19:18:35 volumio systemd[1]: Starting Daily apt upgrade and clean activities...
May 03 19:18:35 volumio volumio[868]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded
May 03 19:18:35 volumio volumio[868]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio
May 03 19:18:35 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 03 19:18:35 volumio volumio[868]: info: Starting MyVolumio Remote Streaming Endpoints
May 03 19:18:35 volumio volumio[868]: info: MyVolumio login type: Token
May 03 19:18:35 volumio volumio[868]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started
May 03 19:18:35 volumio volumio[868]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"...
May 03 19:18:37 volumio volumio[868]: info: Starting Streaming Service Transparent Proxy
May 03 19:18:37 volumio volumio[868]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded
May 03 19:18:37 volumio volumio[868]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services
May 03 19:18:37 volumio volumio[868]: info: Streaming services startup
May 03 19:18:37 volumio volumio[868]: info: Starting Streaming Daemon
May 03 19:18:37 volumio sudo[1580]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
May 03 19:18:37 volumio sudo[1580]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 03 19:18:37 volumio sudo[1580]: pam_unix(sudo:session): session closed for user root
May 03 19:18:37 volumio volumio[868]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started
May 03 19:18:37 volumio volumio[868]: info: Getting Spotify volume
May 03 19:18:37 volumio systemd[1]: apt-daily-upgrade.service: Succeeded.
May 03 19:18:37 volumio systemd[1]: Started Daily apt upgrade and clean activities.
May 03 19:18:38 volumio volumio[868]: error: Cannot start Volumio Streaming Daemon
May 03 19:18:38 volumio volumio[868]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
May 03 19:18:38 volumio volumio[868]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
May 03 19:18:38 volumio systemd[1]: man-db.service: Succeeded.
May 03 19:18:38 volumio systemd[1]: Started Daily man-db regeneration.
May 03 19:18:38 volumio systemd[1]: Startup finished in 13.907s (kernel) + 46.353s (userspace) = 1min 261ms.
May 03 19:18:38 volumio volumio[868]: info: Spotify volume: 100
May 03 19:18:38 volumio volumio[868]: STREAMING PROXY: Starting server on port 3245
May 03 19:18:38 volumio volumio[868]: Node JS runtime: 14
May 03 19:18:39 volumio volumio[868]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4
May 03 19:18:39 volumio volumio[868]: SPOTIFY: User informations: {"country":"ES","display_name":"XuXo","email":"iesus_80@hotmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/iesus_80"},"followers":{"href":null,"total":2},"href":"https://api.spotify.com/v1/users/iesus_80","id":"iesus_80","images":[],"product":"premium","type":"user","uri":"spotify:user:iesus_80"}
May 03 19:18:39 volumio volumio[868]: info: Spotify Successfully logged in
May 03 19:18:39 volumio volumio[868]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
May 03 19:18:39 volumio volumio[868]: info: [1746292719875] CoreMusicLibrary::Adding element Spotify
May 03 19:18:39 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
May 03 19:18:39 volumio volumio[868]: Cannot find translation for source Spotify
May 03 19:18:40 volumio volumio[868]: error: MyVolumio Custom Token format not valid, refreshing it
May 03 19:18:40 volumio volumio[868]: info: [now-playing] OpenWeatherMap API key obtained.
May 03 19:18:40 volumio volumio[868]: info: CoreCommandRouter::volumioGetState
May 03 19:18:40 volumio volumio[868]: info: CorePlayQueue::getTrack 0
May 03 19:18:41 volumio volumio[868]: info: New access token = BQAHzyRQVVxrDBgWiaI93xzLR671iWDNd7HMJ8yCkIKjFwl0GQbRnckwYz1s6eieI7k3Tp-gp-GAPW2On9ix778pC8vtWNLvefzj7yLFqjgEquTSNWvYVxVzgDkj4ELuLIIRYDzl6NCkgni7_K403GMaQYTdSr9lt6DHqCM1O39g7Ld1EX7rcMf4Zr5mkQzPuUATb6m1JQvin1gRpHW9pPtDfDqS7KDhOm5C37Dkx92vX-namhFHbSD-55lAVLtEb919_RoAYkj63g-UKAUq
May 03 19:18:41 volumio volumio[868]: info: New access token = BQCnktQyzcf3NkQcEAqF3qVIg6rx4aApgi8-0IG6fIzSppphs_K3XaxsUm0IOISA58thyjj6qG4wsPZQmZW6rosOQckuMecEGlwAzY9ENwQOkW__T8jqbcrZPHghVR6-60uNS33Q0Q--Jt0rrNzVQ-1JlJqWc_ehmjUqRROT0s9aJf0cbwVUzVkBL94x9Gh5vFPe72dbaC43qEiA-XbxASgS4qT4Oh4HbpoTzQelS9cATzv44qeGsr1LfS2DLtxOCvvHUYBWDbQl99RP1JC9
May 03 19:18:41 volumio volumio[868]: info: New access token = BQAlxU3nVFuzjQ7PVKQV_SLOi5nL5ToC-tbRc-0R6JomaPDphItQEVUmX_9cutX_pdUvNyPAl6-F70bWfNLMOpGXCpjckbl9_waAEhKco2ey3MaBYOtX5G4qgQeCfyM67Xrvuli5TJ_gFZzB42-p-aShClkglNbiXOvDXr6WLmQ_ZuYiyGEQhT9T37HRIr_ssHRkk8rI3jrDao9dVsgbTHKT-lkiTWaYXOOq3YXMKwF0qDybY1FYxn4fy9QfZvI80swtmiwM0niWEr_WO6Ay
May 03 19:18:41 volumio volumio[868]: error: [now-playing] Error fetching https://openweathermap.org/data/2.5/onecall?appid=5796abbde9106b7da4febfae8c44c232&lat=37.2203&lon=-3.5931: Error: Response error: 401 - Unauthorized
May 03 19:18:41 volumio volumio[868]: at fetchPage (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:29:15)
May 03 19:18:41 volumio volumio[868]: at runMicrotasks ()
May 03 19:18:41 volumio volumio[868]: at processTicksAndRejections (internal/process/task_queues.js:93:5)
May 03 19:18:41 volumio volumio[868]: at async Promise.all (index 0)
May 03 19:18:41 volumio volumio[868]: at async fetchData (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:84:24)
May 03 19:18:41 volumio volumio[868]: at async OpenWeatherMapAPI.getWeather (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:97:45)
May 03 19:18:41 volumio volumio[868]: at async /data/plugins/user_interface/now_playing/dist/lib/api/WeatherAPI.js:297:25
May 03 19:18:41 volumio volumio[868]: info: [now-playing] Fetching API key...
May 03 19:18:41 volumio volumio[868]: error: [now-playing] Error fetching https://openweathermap.org/data/2.5/weather?appid=5796abbde9106b7da4febfae8c44c232&lat=37.2203&lon=-3.5931: Error: Response error: 401 - Unauthorized
May 03 19:18:41 volumio volumio[868]: at fetchPage (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:29:15)
May 03 19:18:41 volumio volumio[868]: at runMicrotasks ()
May 03 19:18:41 volumio volumio[868]: at processTicksAndRejections (internal/process/task_queues.js:93:5)
May 03 19:18:41 volumio volumio[868]: at async Promise.all (index 1)
May 03 19:18:42 volumio volumio[1312]: ....................................................................................................................................................................................++++
May 03 19:18:43 volumio volumio[868]: info: MyVolumio login type: Token
May 03 19:18:47 volumio volumio[868]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN
May 03 19:18:47 volumio volumio[1312]: ....................................................++++
May 03 19:18:47 volumio volumio[1312]: e is 65537 (0x010001)
May 03 19:18:47 volumio volumio[1312]: writing RSA key
May 03 19:18:48 volumio volumio[868]: info: [now-playing] OpenWeatherMap API key obtained.
May 03 19:18:48 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
May 03 19:18:48 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 03 19:18:48 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
May 03 19:18:49 volumio volumio-remote-updater[625]: No test mode
May 03 19:18:49 volumio volumio-remote-updater[625]: No alpha test mode
May 03 19:18:49 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
May 03 19:18:49 volumio volumio[868]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
- Slow down CD read speed
- Fix NAS Mounting
NEW ADDITIONS
- Revert TIDAL Connect to working version
- Fix for USB DAC not recognized after OTA update
","title":"Update v3.804","updateavailable":true}
May 03 19:18:49 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
May 03 19:18:50 volumio volumio[868]: info: MyVolumio token set successfully
May 03 19:18:50 volumio volumio[868]: info: MYVOLUMIO: Adding device
May 03 19:18:50 volumio volumio[868]: info: MYVOLUMIO: Evaluating Server
May 03 19:18:51 volumio volumio[868]: error: [now-playing] Error fetching https://openweathermap.org/data/2.5/weather?appid=5796abbde9106b7da4febfae8c44c232&lat=37.2203&lon=-3.5931: Error: Response error: 401 - Unauthorized
May 03 19:18:51 volumio volumio[868]: at fetchPage (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:29:15)
May 03 19:18:51 volumio volumio[868]: at runMicrotasks ()
May 03 19:18:51 volumio volumio[868]: at processTicksAndRejections (internal/process/task_queues.js:93:5)
May 03 19:18:51 volumio volumio[868]: at async Promise.all (index 1)
May 03 19:18:51 volumio volumio[868]: at async fetchData (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:84:24)
May 03 19:18:51 volumio volumio[868]: at async OpenWeatherMapAPI.getWeather (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:97:45)
May 03 19:18:51 volumio volumio[868]: at async /data/plugins/user_interface/now_playing/dist/lib/api/WeatherAPI.js:297:25
May 03 19:18:51 volumio volumio[868]: (node:868) UnhandledPromiseRejectionWarning: Error: Response error: 401 - Unauthorized
May 03 19:18:51 volumio volumio[868]: at fetchPage (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:29:15)
May 03 19:18:51 volumio volumio[868]: at runMicrotasks ()
May 03 19:18:51 volumio volumio[868]: at processTicksAndRejections (internal/process/task_queues.js:93:5)
May 03 19:18:51 volumio volumio[868]: at async Promise.all (index 1)
May 03 19:18:51 volumio volumio[868]: at async fetchData (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:84:24)
May 03 19:18:51 volumio volumio[868]: at async OpenWeatherMapAPI.getWeather (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:97:45)
May 03 19:18:51 volumio volumio[868]: at async /data/plugins/user_interface/now_playing/dist/lib/api/WeatherAPI.js:297:25
May 03 19:18:51 volumio volumio[868]: (Use `node --trace-warnings ...` to show where the warning was created)
May 03 19:18:51 volumio volumio[868]: (node:868) 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: 2)
May 03 19:18:51 volumio volumio[868]: (node:868) [DEP0018] DeprecationWarning: Unhandled promise rejections are deprecated. In the future, promise rejections that are not handled will terminate the Node.js process with a non-zero exit code.
May 03 19:18:51 volumio volumio[868]: error: [now-playing] Error fetching https://openweathermap.org/data/2.5/onecall?appid=5796abbde9106b7da4febfae8c44c232&lat=37.2203&lon=-3.5931: Error: Response error: 401 - Unauthorized
May 03 19:18:51 volumio volumio[868]: at fetchPage (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:29:15)
May 03 19:18:51 volumio volumio[868]: at runMicrotasks ()
May 03 19:18:51 volumio volumio[868]: at processTicksAndRejections (internal/process/task_queues.js:93:5)
May 03 19:18:51 volumio volumio[868]: at async Promise.all (index 0)
May 03 19:18:51 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
May 03 19:18:52 volumio volumio[868]: info: MyVolumio status changed
May 03 19:18:52 volumio volumio[868]: info: Streaming services startup
May 03 19:18:52 volumio volumio[868]: info: Starting Streaming Daemon
May 03 19:18:52 volumio volumio[868]: info: Removing browser output: myVolumio user plan is not superstar
May 03 19:18:52 volumio volumio[868]: info: Removing audio output:
May 03 19:18:52 volumio volumio[868]: info: Stoppping Tunnel 1
May 03 19:18:52 volumio sudo[1776]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
May 03 19:18:52 volumio sudo[1776]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 03 19:18:52 volumio sudo[1776]: pam_unix(sudo:session): session closed for user root
May 03 19:18:52 volumio sudo[1779]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service
May 03 19:18:52 volumio sudo[1779]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 03 19:18:52 volumio sudo[1779]: pam_unix(sudo:session): session closed for user root
May 03 19:18:52 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
May 03 19:18:52 volumio volumio[868]: info: Discovery: Getting this device information
May 03 19:18:52 volumio volumio[868]: info: CoreCommandRouter::volumioGetState
May 03 19:18:52 volumio volumio[868]: info: CorePlayQueue::getTrack 0
May 03 19:18:52 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
May 03 19:18:52 volumio volumio[868]: error: Cannot start Volumio Streaming Daemon
May 03 19:18:52 volumio volumio[868]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
May 03 19:18:52 volumio volumio[868]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
May 03 19:18:52 volumio volumio[868]: info: Setting Geolocation for MyVolumio to eu5
May 03 19:18:52 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 03 19:18:52 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 03 19:18:52 volumio volumio[868]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5
May 03 19:18:52 volumio volumio[868]: info: Remote SSH Stopped
May 03 19:18:54 volumio volumio[868]: info: Updating MyVolumio device info
May 03 19:18:54 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 03 19:18:54 volumio volumio[868]: info: CoreCommandRouter::volumioGetState
May 03 19:18:54 volumio volumio[868]: info: CorePlayQueue::getTrack 0
May 03 19:18:56 volumio systemd[1]: systemd-timedated.service: Succeeded.
May 03 19:18:56 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 03 19:18:56 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 03 19:18:56 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
May 03 19:18:56 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
May 03 19:18:56 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
May 03 19:18:56 volumio volumio[868]: info: CoreCommandRouter::volumioGetBrowseSources
May 03 19:18:56 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
May 03 19:18:56 volumio volumio[868]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"}
May 03 19:18:57 volumio volumio[868]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6
May 03 19:18:57 volumio volumio[868]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"}
May 03 19:19:02 volumio volumio[868]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined
May 03 19:19:02 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo
May 03 19:19:02 volumio volumio[868]: error: MyVolumio Plugin failed to authenticate in a timely fashion
May 03 19:19:02 volumio volumio[868]: info: Completed starting MyVolumio Plugin
May 03 19:19:02 volumio volumio[868]: [Metrics] CommandRouter: 54s 233.13ms
May 03 19:19:02 volumio volumio[868]: info: CoreCommandRouter::volumiosetStartupVolume
May 03 19:19:02 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 03 19:19:02 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 03 19:19:02 volumio volumio[868]: info: CoreCommandRouter::Close All Modals sent
May 03 19:19:02 volumio volumio[868]: info: CoreCommandRouter::Close All Modals sent
May 03 19:19:02 volumio volumio[868]: info: MYVOLUMIO: Adding device
May 03 19:19:02 volumio volumio[868]: info: MYVOLUMIO: Evaluating Server
May 03 19:19:02 volumio volumio[868]: info: Listing playlists
May 03 19:19:02 volumio volumio[868]: info: CoreCommandRouter::volumioGetQueue
May 03 19:19:02 volumio volumio[868]: info: CoreStateMachine::getQueue
May 03 19:19:02 volumio volumio[868]: info: CorePlayQueue::getQueue
May 03 19:19:02 volumio volumio[868]: info: CoreCommandRouter::volumioGetVisibleSources
May 03 19:19:02 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
May 03 19:19:02 volumio volumio[868]: info: CoreCommandRouter::volumioGetState
May 03 19:19:02 volumio volumio[868]: info: CorePlayQueue::getTrack 0
May 03 19:19:03 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
May 03 19:19:03 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
May 03 19:19:03 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
May 03 19:19:03 volumio volumio[868]: error: [now-playing] Error fetching https://openweathermap.org/data/2.5/onecall?appid=5796abbde9106b7da4febfae8c44c232&lat=37.2203&lon=-3.5931: Error: Response error: 401 - Unauthorized
May 03 19:19:03 volumio volumio[868]: at fetchPage (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:29:15)
May 03 19:19:03 volumio volumio[868]: at runMicrotasks ()
May 03 19:19:03 volumio volumio[868]: at processTicksAndRejections (internal/process/task_queues.js:93:5)
May 03 19:19:03 volumio volumio[868]: at async Promise.all (index 0)
May 03 19:19:03 volumio volumio[868]: at async fetchData (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:84:24)
May 03 19:19:03 volumio volumio[868]: at async OpenWeatherMapAPI.getWeather (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:97:45)
May 03 19:19:03 volumio volumio[868]: at async /data/plugins/user_interface/now_playing/dist/lib/api/WeatherAPI.js:297:25
May 03 19:19:03 volumio volumio[868]: info: [now-playing] Fetching API key...
May 03 19:19:03 volumio volumio[868]: error: [now-playing] Error fetching https://openweathermap.org/data/2.5/weather?appid=5796abbde9106b7da4febfae8c44c232&lat=37.2203&lon=-3.5931: Error: Response error: 401 - Unauthorized
May 03 19:19:03 volumio volumio[868]: at fetchPage (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:29:15)
May 03 19:19:03 volumio volumio[868]: at runMicrotasks ()
May 03 19:19:03 volumio volumio[868]: at processTicksAndRejections (internal/process/task_queues.js:93:5)
May 03 19:19:03 volumio volumio[868]: at async Promise.all (index 1)
May 03 19:19:03 volumio volumio[868]: info: Setting Geolocation for MyVolumio to eu4
May 03 19:19:03 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 03 19:19:03 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 03 19:19:03 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 03 19:19:04 volumio volumio[868]: info: [now-playing] OpenWeatherMap API key obtained.
May 03 19:19:04 volumio volumio[868]: error: [now-playing] Error fetching https://openweathermap.org/data/2.5/onecall?appid=5796abbde9106b7da4febfae8c44c232&lat=37.2203&lon=-3.5931: Error: Response error: 401 - Unauthorized
May 03 19:19:04 volumio volumio[868]: at fetchPage (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:29:15)
May 03 19:19:04 volumio volumio[868]: at runMicrotasks ()
May 03 19:19:04 volumio volumio[868]: at processTicksAndRejections (internal/process/task_queues.js:93:5)
May 03 19:19:04 volumio volumio[868]: at async Promise.all (index 0)
May 03 19:19:04 volumio volumio[868]: at async fetchData (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:84:24)
May 03 19:19:04 volumio volumio[868]: at async OpenWeatherMapAPI.getWeather (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:97:45)
May 03 19:19:04 volumio volumio[868]: at async /data/plugins/user_interface/now_playing/dist/lib/api/WeatherAPI.js:297:25
May 03 19:19:04 volumio volumio[868]: error: [now-playing] API endpoint weather/fetchInfo returned error: Error: Could not fetch weather: Response error: 401 - Unauthorized
May 03 19:19:04 volumio volumio[868]: at WeatherAPI.fetchInfo (/data/plugins/user_interface/now_playing/dist/lib/api/WeatherAPI.js:114:19)
May 03 19:19:04 volumio volumio[868]: at runMicrotasks ()
May 03 19:19:04 volumio volumio[868]: at processTicksAndRejections (internal/process/task_queues.js:93:5)
May 03 19:19:04 volumio volumio[868]: at async Object.api (/data/plugins/user_interface/now_playing/dist/app/Handler.js:115:28)
May 03 19:19:04 volumio volumio[868]: (node:868) UnhandledPromiseRejectionWarning: Error: Response error: 401 - Unauthorized
May 03 19:19:04 volumio volumio[868]: at fetchPage (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:29:15)
May 03 19:19:04 volumio volumio[868]: at runMicrotasks ()
May 03 19:19:04 volumio volumio[868]: at processTicksAndRejections (internal/process/task_queues.js:93:5)
May 03 19:19:04 volumio volumio[868]: at async Promise.all (index 0)
May 03 19:19:04 volumio volumio[868]: at async fetchData (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:84:24)
May 03 19:19:04 volumio volumio[868]: at async OpenWeatherMapAPI.getWeather (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:97:45)
May 03 19:19:04 volumio volumio[868]: at async /data/plugins/user_interface/now_playing/dist/lib/api/WeatherAPI.js:297:25
May 03 19:19:04 volumio volumio[868]: (node:868) 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: 4)
May 03 19:19:04 volumio volumio[868]: error: [now-playing] Error fetching https://openweathermap.org/data/2.5/weather?appid=5796abbde9106b7da4febfae8c44c232&lat=37.2203&lon=-3.5931: Error: Response error: 401 - Unauthorized
May 03 19:19:04 volumio volumio[868]: at fetchPage (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:29:15)
May 03 19:19:04 volumio volumio[868]: at runMicrotasks ()
May 03 19:19:04 volumio volumio[868]: at processTicksAndRejections (internal/process/task_queues.js:93:5)
May 03 19:19:04 volumio volumio[868]: at async Promise.all (index 1)
May 03 19:19:04 volumio volumio[868]: info: Updating MyVolumio device info
May 03 19:19:04 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 03 19:19:04 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 03 19:19:04 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 03 19:19:05 volumio volumio[868]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"}
May 03 19:19:05 volumio volumio[868]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"}
May 03 19:19:07 volumio kernel: xhci_hcd 0000:01:00.0: ERROR Transfer event for disabled endpoint slot 3 ep 3
May 03 19:19:07 volumio kernel: xhci_hcd 0000:01:00.0: @0000000429514150 00000000 00000000 0e000000 03048000
May 03 19:19:08 volumio ntpd[806]: Soliciting pool server 195.95.153.43
May 03 19:19:08 volumio ntpd[806]: Soliciting pool server 213.165.71.32
May 03 19:19:09 volumio volumio[868]: info: BOOT COMPLETED
May 03 19:19:09 volumio ntpd[806]: Soliciting pool server 162.159.200.1
May 03 19:19:09 volumio ntpd[806]: Soliciting pool server 185.198.109.227
May 03 19:19:10 volumio ntpd[806]: Soliciting pool server 94.143.139.219
May 03 19:19:10 volumio ntpd[806]: Soliciting pool server 5.250.184.159
May 03 19:19:10 volumio ntpd[806]: Soliciting pool server 162.159.200.123
May 03 19:19:11 volumio ntpd[806]: Soliciting pool server 2606:4700:f1::1
May 03 19:19:11 volumio ntpd[806]: Soliciting pool server 178.215.228.24
May 03 19:19:11 volumio ntpd[806]: Soliciting pool server 82.223.203.159
May 03 19:19:11 volumio ntpd[806]: Soliciting pool server 208.85.20.220
May 03 19:19:12 volumio ntpd[806]: Soliciting pool server 217.160.115.110
May 03 19:19:13 volumio ntpd[806]: Soliciting pool server 84.77.195.114
May 03 19:19:14 volumio ntpd[806]: Soliciting pool server 5.250.191.170
May 03 19:19:14 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
May 03 19:19:14 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
May 03 19:19:14 volumio volumio[868]: info: Discovery: Getting this device information
May 03 19:19:14 volumio volumio[868]: info: CoreCommandRouter::volumioGetState
May 03 19:19:14 volumio volumio[868]: info: CorePlayQueue::getTrack 0
May 03 19:19:14 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
May 03 19:19:14 volumio volumio[868]: verbose: New Socket.io Connection to 192.168.1.144:3000 from 192.168.1.142 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7
May 03 19:19:14 volumio volumio[868]: info: CoreCommandRouter::volumioGetState
May 03 19:19:14 volumio volumio[868]: info: CorePlayQueue::getTrack 0
May 03 19:19:14 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard
May 03 19:19:14 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard
May 03 19:19:16 volumio ntpd[806]: receive: Unexpected origin timestamp 0xebc0ce93.bfd5879d does not match aorg 0000000000.00000000 from server@162.159.200.123 xmt 0xebc0ce94.e1db96aa
May 03 19:19:16 volumio ntpd[806]: receive: Unexpected origin timestamp 0xebc0ce93.bfd8618e does not match aorg 0000000000.00000000 from server@5.250.184.159 xmt 0xebc0ce94.e25faff6
May 03 19:19:16 volumio ntpd[806]: receive: Unexpected origin timestamp 0xebc0ce93.bfd6e916 does not match aorg 0000000000.00000000 from server@94.143.139.219 xmt 0xebc0ce94.e4a1c670
May 03 19:19:16 volumio ntpd[806]: receive: Unexpected origin timestamp 0xebc0ce93.bfd1894f does not match aorg 0000000000.00000000 from server@217.160.115.110 xmt 0xebc0ce94.e4ed60c5
May 03 19:19:16 volumio ntpd[806]: Soliciting pool server 195.95.153.59
May 03 19:19:17 volumio ntpd[806]: Soliciting pool server 217.71.200.210
May 03 19:19:18 volumio volumio[868]: info: CoreCommandRouter::volumioGetState
May 03 19:19:18 volumio volumio[868]: info: CorePlayQueue::getTrack 0
May 03 19:19:19 volumio sudo[2064]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
May 03 19:19:19 volumio sudo[2064]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 03 19:19:19 volumio sudo[2064]: pam_unix(sudo:session): session closed for user root
May 03 19:19:19 volumio sudo[2067]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
May 03 19:19:19 volumio sudo[2067]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 03 19:19:19 volumio sudo[2067]: pam_unix(sudo:session): session closed for user root
May 03 19:19:19 volumio volumio[868]: verbose: New Socket.io Connection to 192.168.1.144 from 192.168.1.142 UA: Mozilla/5.0 (Linux; Android 14; SM-G991B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.111 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8
May 03 19:19:19 volumio sudo[2070]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
May 03 19:19:19 volumio sudo[2070]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 03 19:19:19 volumio sudo[2070]: pam_unix(sudo:session): session closed for user root
May 03 19:19:19 volumio sudo[2073]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
May 03 19:19:19 volumio sudo[2073]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 03 19:19:19 volumio sudo[2073]: pam_unix(sudo:session): session closed for user root
May 03 19:19:19 volumio volumio[868]: verbose: New Socket.io Connection to 192.168.1.144 from 192.168.1.142 UA: Mozilla/5.0 (Linux; Android 14; SM-G991B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.111 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9
May 03 19:19:19 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
May 03 19:19:19 volumio volumio[868]: info: CoreCommandRouter::volumioGetVisibleSources
May 03 19:19:19 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
May 03 19:19:19 volumio volumio[868]: info: CoreCommandRouter::volumioGetState
May 03 19:19:19 volumio volumio[868]: info: CorePlayQueue::getTrack 0
May 03 19:19:19 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
May 03 19:19:19 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
May 03 19:19:19 volumio volumio[868]: info: Received Get System Info
May 03 19:19:19 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
May 03 19:19:19 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
May 03 19:19:19 volumio volumio[868]: info: Discovery: Getting this device information
May 03 19:19:19 volumio volumio[868]: info: CoreCommandRouter::volumioGetState
May 03 19:19:19 volumio volumio[868]: info: CorePlayQueue::getTrack 0
May 03 19:19:19 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
May 03 19:19:19 volumio volumio[868]: info: CoreCommandRouter::volumioGetState
May 03 19:19:19 volumio volumio[868]: info: CorePlayQueue::getTrack 0
May 03 19:19:19 volumio volumio[868]: info: Listing playlists
May 03 19:19:19 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
May 03 19:19:19 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
May 03 19:19:20 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache
May 03 19:19:21 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard
May 03 19:19:21 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
May 03 19:19:21 volumio volumio[868]: info: Received Get System Info
May 03 19:19:21 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
May 03 19:19:21 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
May 03 19:19:21 volumio volumio[868]: info: Discovery: Getting this device information
May 03 19:19:21 volumio volumio[868]: info: CoreCommandRouter::volumioGetState
May 03 19:19:21 volumio volumio[868]: info: CorePlayQueue::getTrack 0
May 03 19:19:21 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
May 03 19:19:22 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
May 03 19:19:22 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
May 03 19:19:22 volumio volumio[868]: info: Discovery: Getting this device information
May 03 19:19:22 volumio volumio[868]: info: CoreCommandRouter::volumioGetState
May 03 19:19:22 volumio volumio[868]: info: CorePlayQueue::getTrack 0
May 03 19:19:22 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
May 03 19:19:22 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
May 03 19:19:22 volumio volumio[868]: info: Received Get System Info
May 03 19:19:22 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
May 03 19:19:22 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
May 03 19:19:22 volumio volumio[868]: info: Discovery: Getting this device information
May 03 19:19:22 volumio volumio[868]: info: CoreCommandRouter::volumioGetState
May 03 19:19:22 volumio volumio[868]: info: CorePlayQueue::getTrack 0
May 03 19:19:22 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
May 03 19:19:24 volumio volumio-remote-updater[625]: No test mode
May 03 19:19:24 volumio volumio-remote-updater[625]: No alpha test mode
May 03 19:19:24 volumio volumio-remote-updater[625]: No test mode
May 03 19:19:24 volumio volumio-remote-updater[625]: No alpha test mode
May 03 19:19:24 volumio volumio-remote-updater[625]: No test mode
May 03 19:19:24 volumio volumio-remote-updater[625]: No alpha test mode
May 03 19:19:24 volumio volumio[868]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
- Slow down CD read speed
- Fix NAS Mounting
NEW ADDITIONS
- Revert TIDAL Connect to working version
- Fix for USB DAC not recognized after OTA update
","title":"Update v3.804","updateavailable":true}
May 03 19:19:24 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
May 03 19:19:24 volumio volumio[868]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
- Slow down CD read speed
- Fix NAS Mounting
NEW ADDITIONS
- Revert TIDAL Connect to working version
- Fix for USB DAC not recognized after OTA update
","title":"Update v3.804","updateavailable":true}
May 03 19:19:24 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
May 03 19:19:24 volumio volumio-remote-updater[625]: No test mode
May 03 19:19:24 volumio volumio-remote-updater[625]: No alpha test mode
May 03 19:19:24 volumio volumio[868]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
- Slow down CD read speed
- Fix NAS Mounting
NEW ADDITIONS
- Revert TIDAL Connect to working version
- Fix for USB DAC not recognized after OTA update
","title":"Update v3.804","updateavailable":true}
May 03 19:19:24 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
May 03 19:19:24 volumio volumio[868]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
- Slow down CD read speed
- Fix NAS Mounting
NEW ADDITIONS
- Revert TIDAL Connect to working version
- Fix for USB DAC not recognized after OTA update
","title":"Update v3.804","updateavailable":true}
May 03 19:19:24 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
May 03 19:19:29 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
May 03 19:19:38 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
May 03 19:19:38 volumio volumio[868]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined
May 03 19:19:38 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates
May 03 19:19:38 volumio volumio[868]: info: Received Get System Version
May 03 19:19:38 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
May 03 19:19:38 volumio volumio[868]: info: Received Get System Info
May 03 19:19:38 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
May 03 19:19:38 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
May 03 19:19:38 volumio volumio[868]: info: Discovery: Getting this device information
May 03 19:19:38 volumio volumio[868]: info: CoreCommandRouter::volumioGetState
May 03 19:19:38 volumio volumio[868]: info: CorePlayQueue::getTrack 0
May 03 19:19:38 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
May 03 19:19:38 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
May 03 19:19:38 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
May 03 19:19:38 volumio volumio[868]: info: Discovery: Getting this device information
May 03 19:19:38 volumio volumio[868]: info: CoreCommandRouter::volumioGetState
May 03 19:19:38 volumio volumio[868]: info: CorePlayQueue::getTrack 0
May 03 19:19:38 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
May 03 19:19:42 volumio volumio[868]: info: CoreCommandRouter::Close All Modals sent
May 03 19:19:46 volumio volumio[868]: info: CoreCommandRouter::getUIConfigOnPlugin
May 03 19:19:46 volumio volumio[868]: info: Retrieving Cloud Streaming UI
May 03 19:19:46 volumio volumio[868]: info: Getting Tidal Cloud Configuration
May 03 19:19:46 volumio volumio[868]: info: CoreCommandRouter::getUIConfigOnPlugin
May 03 19:19:46 volumio volumio[868]: info: Getting Qobuz Cloud Configuration
May 03 19:19:46 volumio volumio[868]: info: Asking plugin for UI Config
May 03 19:19:46 volumio volumio[868]: info: CoreCommandRouter::getUIConfigOnPlugin
May 03 19:19:46 volumio volumio[868]: info: Getting Spotify Cloud Configuration
May 03 19:19:46 volumio volumio[868]: info: Asking plugin for UI Config
May 03 19:19:46 volumio volumio[868]: info: CoreCommandRouter::getUIConfigOnPlugin
May 03 19:19:46 volumio volumio[868]: info: Saving Spotify Acccount
May 03 19:19:46 volumio volumio[868]: info: Got Tidal Cloud Configuration
May 03 19:19:46 volumio volumio[868]: info: Got it
May 03 19:19:46 volumio volumio[868]: info: Got it
May 03 19:19:46 volumio volumio[868]: error: Could not retrieve Spotify Config from plugin Spotify: no section found
May 03 19:19:46 volumio volumio[868]: info: CoreCommandRouter::getUIConfigOnPlugin
May 03 19:19:46 volumio volumio[868]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined
May 03 19:19:46 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus
May 03 19:19:46 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam
May 03 19:19:46 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam
May 03 19:19:46 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam
May 03 19:19:46 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
May 03 19:19:46 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
May 03 19:19:46 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
May 03 19:19:46 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
May 03 19:19:46 volumio volumio[868]: info: CoreCommandRouter::volumioGetBrowseSources
May 03 19:19:46 volumio volumio[868]: info: CoreCommandRouter::volumioGetBrowseSources
May 03 19:19:46 volumio volumio[868]: info: CoreCommandRouter::volumioGetBrowseSources
May 03 19:19:46 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
May 03 19:19:46 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares
May 03 19:19:47 volumio volumio[868]: info: Disabling MyMusic plugin upnp
May 03 19:19:47 volumio sudo[2129]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service
May 03 19:19:47 volumio sudo[2129]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 03 19:19:47 volumio systemd[1]: Stopping UPnP Renderer front-end to MPD...
May 03 19:19:49 volumio volumio[868]: info: Enabling MyMusic plugin upnp
May 03 19:19:49 volumio volumio[868]: info: Enabling plugin upnp
May 03 19:19:49 volumio volumio[868]: info: Loading plugin "upnp"...
May 03 19:19:49 volumio volumio[868]: info: [1746292789753] Starting Upmpd Daemon
May 03 19:19:49 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
May 03 19:19:49 volumio volumio[868]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
May 03 19:19:49 volumio volumio[868]: Error: listen EADDRINUSE: address already in use :::6599
May 03 19:19:49 volumio volumio[868]: at Server.setupListenHandle [as _listen2] (net.js:1318:16)
May 03 19:19:49 volumio volumio[868]: at listenInCluster (net.js:1366:12)
May 03 19:19:49 volumio volumio[868]: at Server.listen (net.js:1452:7)
May 03 19:19:49 volumio volumio[868]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17)
May 03 19:19:49 volumio volumio[868]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38)
May 03 19:19:49 volumio volumio[868]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19)
May 03 19:19:49 volumio volumio[868]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28)
May 03 19:19:49 volumio volumio[868]: at processTicksAndRejections (internal/process/task_queues.js:75:11) {
May 03 19:19:49 volumio volumio[868]: code: 'EADDRINUSE',
May 03 19:19:49 volumio volumio[868]: errno: -98,
May 03 19:19:49 volumio volumio[868]: syscall: 'listen',
May 03 19:19:49 volumio volumio[868]: address: '::',
May 03 19:19:49 volumio volumio[868]: port: 6599
May 03 19:19:49 volumio volumio[868]: }
May 03 19:19:49 volumio volumio[868]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
May 03 19:19:50 volumio sudo[2143]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-03 19:18
May 03 19:19:50 volumio sudo[2143]: 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="f6610a1100d2380eef33bd3004af43153c786f96"
VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6"
VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a"
VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET"
VOLUMIO_VERSION="3.785"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"