-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Tue 2025-04-15 18:37:29 CEST. --
Apr 15 18:36:28 volumio systemd-timedated[1450]: Changed local time to Tue Apr 15 18:36:28 2025
Apr 15 18:36:28 volumio sudo[1448]: pam_unix(sudo:session): session closed for user root
Apr 15 18:36:28 volumio volumio-time-update[671]: volumio-time-update-util: System time updated successfully.
Apr 15 18:36:28 volumio systemd[1]: Started Volumio Time Update Utility.
Apr 15 18:36:28 volumio systemd[1]: Reached target Multi-User System.
Apr 15 18:36:28 volumio systemd[1]: Reached target Graphical Interface.
Apr 15 18:36:28 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes...
Apr 15 18:36:28 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded.
Apr 15 18:36:28 volumio systemd[1]: Started Update UTMP about System Runlevel Changes.
Apr 15 18:36:28 volumio systemd[1]: Startup finished in 14.629s (kernel) + 33.282s (userspace) = 47.912s.
Apr 15 18:36:28 volumio ntpd[839]: Listen normally on 4 eth0 192.168.1.142:123
Apr 15 18:36:28 volumio ntpd[839]: Deleting interface #3 eth0, 169.254.175.239#123, interface stats: received=0, sent=0, dropped=0, active_time=11 secs
Apr 15 18:36:28 volumio ntpd[839]: new interface(s) found: waking up resolver
Apr 15 18:36:28 volumio go-librespot[1229]: time="2025-04-15T18:36: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]"
Apr 15 18:36:28 volumio go-librespot[1229]: time="2025-04-15T18:36: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]"
Apr 15 18:36:28 volumio go-librespot[1229]: time="2025-04-15T18:36: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]"
Apr 15 18:36:28 volumio go-librespot[1229]: time="2025-04-15T18:36:28+02:00" level=debug msg="zeroconf server listening on port 33439"
Apr 15 18:36:28 volumio go-librespot[1229]: time="2025-04-15T18:36:28+02:00" level=debug msg="obtained new client token: AACYwlh0Bch9FDInfD6O7qLq5TnLMAsntGTu2jyfQCilb00c/tPM+lA6cLfUnI8sgB9d5RxE/ePspfOVovZjbv2DJ2/84Yr2jpbkrrUyCkK/GIMOpZAWsXWfmyKqrnmm42D73vOR5r+lcH003wZocRTVF8nUad4BnJT10GtScCr52kLwsvB+IFtTpfzyjxm2kB9SDQqFtnZL14crbOtEtReNSN7YBxAv+4QVSezUfJObAdQrpQIxIYmvYabbUQ=="
Apr 15 18:36:28 volumio go-librespot[1229]: time="2025-04-15T18:36:28+02:00" level=info msg="connected to ap-gew1.spotify.com:4070"
Apr 15 18:36:28 volumio go-librespot[1229]: time="2025-04-15T18:36:28+02:00" level=debug msg="completed keyexchange"
Apr 15 18:36:28 volumio volumio[880]: info: Discovery: adding ff68d2f4-cc46-4f0e-92fe-fdbbcb77de2c
Apr 15 18:36:28 volumio volumio[880]: info: Discovery: Found device Volumio
Apr 15 18:36:28 volumio volumio[880]: info: CoreCommandRouter::volumioGetState
Apr 15 18:36:28 volumio volumio[880]: info: CorePlayQueue::getTrack 0
Apr 15 18:36:29 volumio go-librespot[1229]: time="2025-04-15T18:36:29+02:00" level=debug msg="completed challenge"
Apr 15 18:36:29 volumio go-librespot[1229]: time="2025-04-15T18:36:29+02:00" level=debug msg="authenticated as iesus_80"
Apr 15 18:36:29 volumio go-librespot[1229]: time="2025-04-15T18:36:29+02:00" level=debug msg="authenticated as iesus_80"
Apr 15 18:36:29 volumio go-librespot[1229]: time="2025-04-15T18:36:29+02:00" level=debug msg="new websocket client"
Apr 15 18:36:29 volumio volumio[880]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3
Apr 15 18:36:29 volumio go-librespot[1229]: time="2025-04-15T18:36:29+02:00" level=debug msg="dealer connection opened"
Apr 15 18:36:29 volumio go-librespot[1229]: time="2025-04-15T18:36:29+02:00" level=debug msg="initializing zeroconf session, username: iesus_80"
Apr 15 18:36:29 volumio go-librespot[1229]: time="2025-04-15T18:36:29+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336"
Apr 15 18:36:29 volumio go-librespot[1229]: time="2025-04-15T18:36:29+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2"
Apr 15 18:36:29 volumio go-librespot[1229]: time="2025-04-15T18:36:29+02:00" level=debug msg="autoplay enabled: false"
Apr 15 18:36:29 volumio go-librespot[1229]: time="2025-04-15T18:36:29+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17"
Apr 15 18:36:29 volumio go-librespot[1229]: time="2025-04-15T18:36:29+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0"
Apr 15 18:36:29 volumio go-librespot[1229]: time="2025-04-15T18:36:29+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481"
Apr 15 18:36:29 volumio go-librespot[1229]: time="2025-04-15T18:36:29+02:00" level=debug msg="received connection id: ZWU0NzIzNWQtODk2MC00YWIzLTkzNDEtZWYzZmVjZmM2ZmU0K2RlYWxlcit0Y3A6Ly8wYWNhNDBkNS5pcC5nZXcxLnNwb3RpZnkubmV0OjU3MDArRUUxNkNFNDUxMDVGQzQzOUIwQjNDRkYzNTNDQzc1NTA4MkZGOUQ2NDlFQzNBMEZBQTlFOEZGMkNFQ0MzMjhDRg=="
Apr 15 18:36:29 volumio volumio[880]: info: Connection to go-librespot Websocket established
Apr 15 18:36:29 volumio go-librespot[1229]: time="2025-04-15T18:36:29+02:00" level=debug msg="put connect state because NEW_DEVICE"
Apr 15 18:36:29 volumio volumio[880]: info: Volumio called home
Apr 15 18:36:29 volumio volumio[880]: info: CoreCommandRouter::volumioGetState
Apr 15 18:36:29 volumio volumio[880]: info: CorePlayQueue::getTrack 0
Apr 15 18:36:30 volumio volumio[880]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------
Apr 15 18:36:30 volumio volumio[880]: SPOTIFY: BQCeLoT5n29IGOIBSOw7FZzK--E1SUNXpMoRnIzVH9C6ydymOHcfAEdPz8FNX9cEZXHPVZo0srdxCB122pbPJxVt9MInPDu4Z9ERIehuPHLspm69gKMZlkeWMGP7AATD9gOb2FXbr_PGNwPacSTLfsCPWC4fbB5xXWlXWI9E-GBI20fLhF8Si1AqPUcnMG5hkRUAzMkCyuwXwYjB98hZXLNF6Q8EdYezU7YFp4g7GStEyKxyD2b44ZBvm9YsoByQkKebwRTljlergwVmtf2b
Apr 15 18:36:30 volumio volumio[880]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------
Apr 15 18:36:30 volumio volumio[880]: info: New Spotify access token = BQCeLoT5n29IGOIBSOw7FZzK--E1SUNXpMoRnIzVH9C6ydymOHcfAEdPz8FNX9cEZXHPVZo0srdxCB122pbPJxVt9MInPDu4Z9ERIehuPHLspm69gKMZlkeWMGP7AATD9gOb2FXbr_PGNwPacSTLfsCPWC4fbB5xXWlXWI9E-GBI20fLhF8Si1AqPUcnMG5hkRUAzMkCyuwXwYjB98hZXLNF6Q8EdYezU7YFp4g7GStEyKxyD2b44ZBvm9YsoByQkKebwRTljlergwVmtf2b
Apr 15 18:36:30 volumio volumio[880]: info: Spotify credentials grant success - running version from March 24, 2019
Apr 15 18:36:30 volumio systemd[1]: systemd-fsckd.service: Succeeded.
Apr 15 18:36:31 volumio volumio[880]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium
Apr 15 18:36:31 volumio volumio[880]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium
Apr 15 18:36:31 volumio volumio[880]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium
Apr 15 18:36:31 volumio volumio[880]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium
Apr 15 18:36:31 volumio volumio[880]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium
Apr 15 18:36:31 volumio volumio[880]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium
Apr 15 18:36:31 volumio volumio[880]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium
Apr 15 18:36:31 volumio volumio[880]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium
Apr 15 18:36:31 volumio volumio[880]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium
Apr 15 18:36:31 volumio volumio[880]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium
Apr 15 18:36:31 volumio volumio[880]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar
Apr 15 18:36:31 volumio volumio[880]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar
Apr 15 18:36:31 volumio volumio[880]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar
Apr 15 18:36:31 volumio volumio[880]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar
Apr 15 18:36:31 volumio volumio[880]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar
Apr 15 18:36:31 volumio volumio[880]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar
Apr 15 18:36:31 volumio volumio[880]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar
Apr 15 18:36:31 volumio volumio[880]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar
Apr 15 18:36:31 volumio volumio[880]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar
Apr 15 18:36:31 volumio volumio[880]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar
Apr 15 18:36:31 volumio volumio[880]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso
Apr 15 18:36:31 volumio volumio[880]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso
Apr 15 18:36:31 volumio volumio[880]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso
Apr 15 18:36:31 volumio volumio[880]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso
Apr 15 18:36:31 volumio volumio[880]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso
Apr 15 18:36:31 volumio volumio[880]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso
Apr 15 18:36:31 volumio volumio[880]: info: Adding plugin bluetooth to MyMusic Plugins
Apr 15 18:36:31 volumio volumio[880]: info: Adding plugin multiroom to MyMusic Plugins
Apr 15 18:36:31 volumio volumio[880]: info: Adding plugin metavolumio to MyMusic Plugins
Apr 15 18:36:31 volumio volumio[880]: info: Adding plugin cd_controller to MyMusic Plugins
Apr 15 18:36:31 volumio volumio[880]: info: Adding plugin smart_inputs to MyMusic Plugins
Apr 15 18:36:31 volumio volumio[880]: info: Adding plugin tidalconnect to MyMusic Plugins
Apr 15 18:36:31 volumio volumio[880]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"...
Apr 15 18:36:32 volumio volumio[880]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded
Apr 15 18:36:32 volumio volumio[880]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio
Apr 15 18:36:32 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 15 18:36:32 volumio volumio[880]: info: Starting MyVolumio Remote Streaming Endpoints
Apr 15 18:36:32 volumio volumio[880]: info: MyVolumio login type: Token
Apr 15 18:36:32 volumio volumio[880]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started
Apr 15 18:36:32 volumio volumio[880]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"...
Apr 15 18:36:33 volumio volumio[880]: info: Starting Streaming Service Transparent Proxy
Apr 15 18:36:33 volumio volumio[880]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded
Apr 15 18:36:33 volumio volumio[880]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services
Apr 15 18:36:33 volumio volumio[880]: info: Streaming services startup
Apr 15 18:36:33 volumio volumio[880]: info: Starting Streaming Daemon
Apr 15 18:36:34 volumio sudo[1506]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Apr 15 18:36:34 volumio sudo[1506]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 15 18:36:34 volumio volumio[880]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started
Apr 15 18:36:34 volumio sudo[1506]: pam_unix(sudo:session): session closed for user root
Apr 15 18:36:34 volumio systemd[1]: systemd-hostnamed.service: Succeeded.
Apr 15 18:36:34 volumio volumio[880]: info: Getting Spotify volume
Apr 15 18:36:34 volumio volumio[1323]: .....................................................................................................................++++
Apr 15 18:36:34 volumio volumio[880]: error: Cannot start Volumio Streaming Daemon
Apr 15 18:36:34 volumio volumio[880]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Apr 15 18:36:34 volumio volumio[880]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Apr 15 18:36:35 volumio volumio[880]: info: Spotify volume: 100
Apr 15 18:36:35 volumio volumio[880]: verbose: New Socket.io Connection to 192.168.1.142 from 192.168.1.141 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.38 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4
Apr 15 18:36:35 volumio volumio[880]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5
Apr 15 18:36:35 volumio volumio[880]: STREAMING PROXY: Starting server on port 3245
Apr 15 18:36:35 volumio volumio[880]: Node JS runtime: 14
Apr 15 18:36:35 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Apr 15 18:36:36 volumio volumio[880]: info: CoreCommandRouter::volumioGetVisibleSources
Apr 15 18:36:36 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 15 18:36:36 volumio volumio[880]: info: CoreCommandRouter::volumioGetState
Apr 15 18:36:36 volumio volumio[880]: info: CorePlayQueue::getTrack 0
Apr 15 18:36:36 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Apr 15 18:36:36 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Apr 15 18:36:36 volumio volumio[880]: info: Received Get System Info
Apr 15 18:36:36 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Apr 15 18:36:36 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Apr 15 18:36:36 volumio volumio[880]: info: Discovery: Getting this device information
Apr 15 18:36:36 volumio volumio[880]: info: CoreCommandRouter::volumioGetState
Apr 15 18:36:36 volumio volumio[880]: info: CorePlayQueue::getTrack 0
Apr 15 18:36:36 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Apr 15 18:36:36 volumio volumio[880]: info: CoreCommandRouter::volumioGetState
Apr 15 18:36:36 volumio volumio[880]: info: CorePlayQueue::getTrack 0
Apr 15 18:36:36 volumio volumio[880]: info: Listing playlists
Apr 15 18:36:36 volumio volumio[880]: info: CoreCommandRouter::volumioGetState
Apr 15 18:36:36 volumio volumio[880]: info: CorePlayQueue::getTrack 0
Apr 15 18:36:36 volumio volumio[880]: info: [now-playing] OpenWeatherMap API key obtained.
Apr 15 18:36:37 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache
Apr 15 18:36:38 volumio volumio[880]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN
Apr 15 18:36:38 volumio volumio[880]: 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
Apr 15 18:36:38 volumio volumio[880]: at fetchPage (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:29:15)
Apr 15 18:36:38 volumio volumio[880]: at runMicrotasks ()
Apr 15 18:36:38 volumio volumio[880]: at processTicksAndRejections (internal/process/task_queues.js:93:5)
Apr 15 18:36:38 volumio volumio[880]: at async Promise.all (index 1)
Apr 15 18:36:38 volumio volumio[880]: at async fetchData (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:84:24)
Apr 15 18:36:38 volumio volumio[880]: at async OpenWeatherMapAPI.getWeather (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:97:45)
Apr 15 18:36:38 volumio volumio[880]: at async /data/plugins/user_interface/now_playing/dist/lib/api/WeatherAPI.js:297:25
Apr 15 18:36:38 volumio volumio[880]: info: [now-playing] Fetching API key...
Apr 15 18:36:38 volumio volumio[880]: 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
Apr 15 18:36:38 volumio volumio[880]: at fetchPage (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:29:15)
Apr 15 18:36:38 volumio volumio[880]: at runMicrotasks ()
Apr 15 18:36:38 volumio volumio[880]: at processTicksAndRejections (internal/process/task_queues.js:93:5)
Apr 15 18:36:38 volumio volumio[880]: at async Promise.all (index 0)
Apr 15 18:36:39 volumio volumio[880]: 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"}
Apr 15 18:36:39 volumio volumio[880]: info: Spotify Successfully logged in
Apr 15 18:36:39 volumio volumio[880]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Apr 15 18:36:39 volumio volumio[880]: info: [1744734999189] CoreMusicLibrary::Adding element Spotify
Apr 15 18:36:39 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 15 18:36:39 volumio volumio[880]: Cannot find translation for source Spotify
Apr 15 18:36:39 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Apr 15 18:36:39 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Apr 15 18:36:39 volumio volumio[880]: info: Discovery: Getting this device information
Apr 15 18:36:39 volumio volumio[880]: info: CoreCommandRouter::volumioGetState
Apr 15 18:36:39 volumio volumio[880]: info: CorePlayQueue::getTrack 0
Apr 15 18:36:39 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Apr 15 18:36:40 volumio volumio[880]: verbose: New Socket.io Connection to 192.168.1.142:3000 from 192.168.1.141 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6
Apr 15 18:36:41 volumio volumio[880]: info: CoreCommandRouter::volumioGetState
Apr 15 18:36:41 volumio volumio[880]: info: CorePlayQueue::getTrack 0
Apr 15 18:36:41 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard
Apr 15 18:36:41 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard
Apr 15 18:36:41 volumio volumio[880]: info: MyVolumio token set successfully
Apr 15 18:36:41 volumio volumio[880]: info: MYVOLUMIO: Adding device
Apr 15 18:36:41 volumio volumio[880]: info: MYVOLUMIO: Evaluating Server
Apr 15 18:36:42 volumio volumio[1323]: ................................................................................++++
Apr 15 18:36:42 volumio volumio[1323]: e is 65537 (0x010001)
Apr 15 18:36:42 volumio volumio[1323]: writing RSA key
Apr 15 18:36:42 volumio volumio[880]: info: MyVolumio status changed
Apr 15 18:36:42 volumio volumio[880]: info: Streaming services startup
Apr 15 18:36:42 volumio volumio[880]: info: Starting Streaming Daemon
Apr 15 18:36:42 volumio volumio[880]: info: Removing browser output: myVolumio user plan is not superstar
Apr 15 18:36:42 volumio volumio[880]: info: Removing audio output:
Apr 15 18:36:42 volumio volumio[880]: info: Stoppping Tunnel 1
Apr 15 18:36:42 volumio sudo[1599]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Apr 15 18:36:42 volumio sudo[1599]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 15 18:36:42 volumio sudo[1603]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service
Apr 15 18:36:42 volumio sudo[1599]: pam_unix(sudo:session): session closed for user root
Apr 15 18:36:42 volumio sudo[1603]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 15 18:36:42 volumio sudo[1603]: pam_unix(sudo:session): session closed for user root
Apr 15 18:36:42 volumio volumio[880]: info: Remote SSH Stopped
Apr 15 18:36:42 volumio volumio[880]: error: Cannot start Volumio Streaming Daemon
Apr 15 18:36:42 volumio volumio[880]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Apr 15 18:36:42 volumio volumio[880]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Apr 15 18:36:43 volumio volumio[880]: info: Setting Geolocation for MyVolumio to eu6
Apr 15 18:36:43 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 15 18:36:43 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 15 18:36:43 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 15 18:36:44 volumio volumio[880]: info: Updating MyVolumio device info
Apr 15 18:36:44 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 15 18:36:45 volumio volumio[880]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"}
Apr 15 18:36:45 volumio volumio[880]: info: [now-playing] OpenWeatherMap API key obtained.
Apr 15 18:36:47 volumio volumio[880]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"}
Apr 15 18:36:47 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Apr 15 18:36:48 volumio volumio[880]: 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
Apr 15 18:36:48 volumio volumio[880]: at fetchPage (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:29:15)
Apr 15 18:36:48 volumio volumio[880]: at runMicrotasks ()
Apr 15 18:36:48 volumio volumio[880]: at processTicksAndRejections (internal/process/task_queues.js:93:5)
Apr 15 18:36:48 volumio volumio[880]: at async Promise.all (index 1)
Apr 15 18:36:48 volumio volumio[880]: at async fetchData (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:84:24)
Apr 15 18:36:48 volumio volumio[880]: at async OpenWeatherMapAPI.getWeather (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:97:45)
Apr 15 18:36:48 volumio volumio[880]: at async /data/plugins/user_interface/now_playing/dist/lib/api/WeatherAPI.js:297:25
Apr 15 18:36:48 volumio volumio[880]: (node:880) UnhandledPromiseRejectionWarning: Error: Response error: 401 - Unauthorized
Apr 15 18:36:48 volumio volumio[880]: at fetchPage (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:29:15)
Apr 15 18:36:48 volumio volumio[880]: at runMicrotasks ()
Apr 15 18:36:48 volumio volumio[880]: at processTicksAndRejections (internal/process/task_queues.js:93:5)
Apr 15 18:36:48 volumio volumio[880]: at async Promise.all (index 1)
Apr 15 18:36:48 volumio volumio[880]: at async fetchData (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:84:24)
Apr 15 18:36:48 volumio volumio[880]: at async OpenWeatherMapAPI.getWeather (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:97:45)
Apr 15 18:36:48 volumio volumio[880]: at async /data/plugins/user_interface/now_playing/dist/lib/api/WeatherAPI.js:297:25
Apr 15 18:36:48 volumio volumio[880]: (Use `node --trace-warnings ...` to show where the warning was created)
Apr 15 18:36:48 volumio volumio[880]: (node:880) 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)
Apr 15 18:36:48 volumio volumio[880]: (node:880) [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.
Apr 15 18:36:48 volumio volumio[880]: 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
Apr 15 18:36:48 volumio volumio[880]: at fetchPage (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:29:15)
Apr 15 18:36:48 volumio volumio[880]: at runMicrotasks ()
Apr 15 18:36:48 volumio volumio[880]: at processTicksAndRejections (internal/process/task_queues.js:93:5)
Apr 15 18:36:48 volumio volumio[880]: at async Promise.all (index 0)
Apr 15 18:36:48 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Apr 15 18:36:48 volumio volumio[880]: info: Discovery: Getting this device information
Apr 15 18:36:48 volumio volumio[880]: info: CoreCommandRouter::volumioGetState
Apr 15 18:36:48 volumio volumio[880]: info: CorePlayQueue::getTrack 0
Apr 15 18:36:48 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Apr 15 18:36:49 volumio volumio[880]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7
Apr 15 18:36:49 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Apr 15 18:36:49 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 15 18:36:49 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Apr 15 18:36:50 volumio volumio[880]: info: MYVOLUMIO: Adding device
Apr 15 18:36:50 volumio volumio[880]: info: MYVOLUMIO: Evaluating Server
Apr 15 18:36:51 volumio volumio-remote-updater[626]: No test mode
Apr 15 18:36:51 volumio volumio-remote-updater[626]: No alpha test mode
Apr 15 18:36:51 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
Apr 15 18:36:52 volumio volumio[880]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
- Improved translations
NEW ADDITIONS
- Update to TIDAL Connect
- Add Install-to-disk option for the new RPi5 HW revisions
","title":"Update v3.799","updateavailable":true}
Apr 15 18:36:52 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Apr 15 18:36:52 volumio volumio[880]: info: Setting Geolocation for MyVolumio to eu6
Apr 15 18:36:52 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 15 18:36:52 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 15 18:36:52 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 15 18:36:52 volumio volumio[880]: info: CoreCommandRouter::volumioGetState
Apr 15 18:36:52 volumio volumio[880]: info: CorePlayQueue::getTrack 0
Apr 15 18:36:52 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 15 18:36:52 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 15 18:36:52 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
Apr 15 18:36:52 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Apr 15 18:36:52 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
Apr 15 18:36:52 volumio volumio[880]: info: CoreCommandRouter::volumioGetBrowseSources
Apr 15 18:36:52 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Apr 15 18:36:53 volumio volumio[880]: info: Updating MyVolumio device info
Apr 15 18:36:53 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 15 18:36:54 volumio volumio[880]: error: MyVolumio Plugin failed to authenticate in a timely fashion
Apr 15 18:36:54 volumio volumio[880]: info: Completed starting MyVolumio Plugin
Apr 15 18:36:54 volumio volumio[880]: [Metrics] CommandRouter: 45s 291.95ms
Apr 15 18:36:54 volumio volumio[880]: info: CoreCommandRouter::volumiosetStartupVolume
Apr 15 18:36:54 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 15 18:36:54 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 15 18:36:54 volumio volumio[880]: info: CoreCommandRouter::Close All Modals sent
Apr 15 18:36:54 volumio volumio[880]: info: CoreCommandRouter::Close All Modals sent
Apr 15 18:36:55 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
Apr 15 18:36:55 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Apr 15 18:36:55 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
Apr 15 18:36:56 volumio volumio[880]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"}
Apr 15 18:36:56 volumio volumio[880]: 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: 8
Apr 15 18:36:58 volumio systemd[1]: systemd-timedated.service: Succeeded.
Apr 15 18:36:59 volumio kernel: xhci_hcd 0000:01:00.0: ERROR Transfer event for disabled endpoint slot 3 ep 3
Apr 15 18:36:59 volumio kernel: xhci_hcd 0000:01:00.0: @0000000429514130 00000000 00000000 0e000000 03048000
Apr 15 18:37:01 volumio volumio[880]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined
Apr 15 18:37:01 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo
Apr 15 18:37:01 volumio volumio[880]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"}
Apr 15 18:37:01 volumio volumio[880]: info: Listing playlists
Apr 15 18:37:01 volumio volumio[880]: info: CoreCommandRouter::volumioGetQueue
Apr 15 18:37:01 volumio volumio[880]: info: CoreStateMachine::getQueue
Apr 15 18:37:01 volumio volumio[880]: info: CorePlayQueue::getQueue
Apr 15 18:37:01 volumio volumio[880]: info: CoreCommandRouter::volumioGetVisibleSources
Apr 15 18:37:01 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 15 18:37:01 volumio volumio[880]: info: CoreCommandRouter::volumioGetState
Apr 15 18:37:01 volumio volumio[880]: info: CorePlayQueue::getTrack 0
Apr 15 18:37:01 volumio volumio[880]: 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
Apr 15 18:37:01 volumio volumio[880]: at fetchPage (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:29:15)
Apr 15 18:37:01 volumio volumio[880]: at runMicrotasks ()
Apr 15 18:37:01 volumio volumio[880]: at processTicksAndRejections (internal/process/task_queues.js:93:5)
Apr 15 18:37:01 volumio volumio[880]: at async Promise.all (index 1)
Apr 15 18:37:01 volumio volumio[880]: at async fetchData (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:84:24)
Apr 15 18:37:01 volumio volumio[880]: at async OpenWeatherMapAPI.getWeather (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:97:45)
Apr 15 18:37:01 volumio volumio[880]: at async /data/plugins/user_interface/now_playing/dist/lib/api/WeatherAPI.js:297:25
Apr 15 18:37:01 volumio volumio[880]: info: [now-playing] Fetching API key...
Apr 15 18:37:01 volumio volumio[880]: 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
Apr 15 18:37:01 volumio volumio[880]: at fetchPage (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:29:15)
Apr 15 18:37:01 volumio volumio[880]: at runMicrotasks ()
Apr 15 18:37:01 volumio volumio[880]: at processTicksAndRejections (internal/process/task_queues.js:93:5)
Apr 15 18:37:01 volumio volumio[880]: at async Promise.all (index 0)
Apr 15 18:37:01 volumio volumio[880]: info: BOOT COMPLETED
Apr 15 18:37:02 volumio volumio[880]: info: [now-playing] OpenWeatherMap API key obtained.
Apr 15 18:37:02 volumio volumio[880]: 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
Apr 15 18:37:02 volumio volumio[880]: at fetchPage (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:29:15)
Apr 15 18:37:02 volumio volumio[880]: at runMicrotasks ()
Apr 15 18:37:02 volumio volumio[880]: at processTicksAndRejections (internal/process/task_queues.js:93:5)
Apr 15 18:37:02 volumio volumio[880]: at async Promise.all (index 0)
Apr 15 18:37:02 volumio volumio[880]: at async fetchData (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:84:24)
Apr 15 18:37:02 volumio volumio[880]: at async OpenWeatherMapAPI.getWeather (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:97:45)
Apr 15 18:37:02 volumio volumio[880]: at async /data/plugins/user_interface/now_playing/dist/lib/api/WeatherAPI.js:297:25
Apr 15 18:37:02 volumio volumio[880]: error: [now-playing] API endpoint weather/fetchInfo returned error: Error: Could not fetch weather: Response error: 401 - Unauthorized
Apr 15 18:37:02 volumio volumio[880]: at WeatherAPI.fetchInfo (/data/plugins/user_interface/now_playing/dist/lib/api/WeatherAPI.js:114:19)
Apr 15 18:37:02 volumio volumio[880]: at runMicrotasks ()
Apr 15 18:37:02 volumio volumio[880]: at processTicksAndRejections (internal/process/task_queues.js:93:5)
Apr 15 18:37:02 volumio volumio[880]: at async Object.api (/data/plugins/user_interface/now_playing/dist/app/Handler.js:115:28)
Apr 15 18:37:02 volumio volumio[880]: (node:880) UnhandledPromiseRejectionWarning: Error: Response error: 401 - Unauthorized
Apr 15 18:37:02 volumio volumio[880]: at fetchPage (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:29:15)
Apr 15 18:37:02 volumio volumio[880]: at runMicrotasks ()
Apr 15 18:37:02 volumio volumio[880]: at processTicksAndRejections (internal/process/task_queues.js:93:5)
Apr 15 18:37:02 volumio volumio[880]: at async Promise.all (index 0)
Apr 15 18:37:02 volumio volumio[880]: at async fetchData (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:84:24)
Apr 15 18:37:02 volumio volumio[880]: at async OpenWeatherMapAPI.getWeather (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:97:45)
Apr 15 18:37:02 volumio volumio[880]: at async /data/plugins/user_interface/now_playing/dist/lib/api/WeatherAPI.js:297:25
Apr 15 18:37:02 volumio volumio[880]: (node:880) 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)
Apr 15 18:37:02 volumio volumio[880]: 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
Apr 15 18:37:02 volumio volumio[880]: at fetchPage (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:29:15)
Apr 15 18:37:02 volumio volumio[880]: at runMicrotasks ()
Apr 15 18:37:02 volumio volumio[880]: at processTicksAndRejections (internal/process/task_queues.js:93:5)
Apr 15 18:37:02 volumio volumio[880]: at async Promise.all (index 1)
Apr 15 18:37:03 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Apr 15 18:37:03 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Apr 15 18:37:03 volumio volumio[880]: info: Discovery: Getting this device information
Apr 15 18:37:03 volumio volumio[880]: info: CoreCommandRouter::volumioGetState
Apr 15 18:37:03 volumio volumio[880]: info: CorePlayQueue::getTrack 0
Apr 15 18:37:03 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Apr 15 18:37:06 volumio nmbd[797]: [2025/04/15 18:37:06.259353, 0] ../source3/libsmb/nmblib.c:917(send_udp)
Apr 15 18:37:06 volumio nmbd[797]: Packet send failed to 169.254.255.255(138) ERRNO=Network is unreachable
Apr 15 18:37:08 volumio ntpd[839]: Soliciting pool server 217.160.163.33
Apr 15 18:37:09 volumio ntpd[839]: Soliciting pool server 208.85.20.220
Apr 15 18:37:09 volumio ntpd[839]: Soliciting pool server 195.95.153.43
Apr 15 18:37:10 volumio ntpd[839]: Soliciting pool server 195.95.153.59
Apr 15 18:37:10 volumio ntpd[839]: Soliciting pool server 90.68.206.60
Apr 15 18:37:10 volumio ntpd[839]: Soliciting pool server 162.159.200.123
Apr 15 18:37:11 volumio ntpd[839]: Soliciting pool server 185.198.109.227
Apr 15 18:37:11 volumio ntpd[839]: Soliciting pool server 82.223.203.159
Apr 15 18:37:11 volumio ntpd[839]: Soliciting pool server 94.143.139.219
Apr 15 18:37:11 volumio volumio[880]: info: CoreCommandRouter::getUIConfigOnPlugin
Apr 15 18:37:11 volumio volumio[880]: info: Retrieving Cloud Streaming UI
Apr 15 18:37:11 volumio volumio[880]: info: Getting Tidal Cloud Configuration
Apr 15 18:37:11 volumio volumio[880]: info: CoreCommandRouter::getUIConfigOnPlugin
Apr 15 18:37:11 volumio volumio[880]: info: Getting Qobuz Cloud Configuration
Apr 15 18:37:11 volumio volumio[880]: info: Asking plugin for UI Config
Apr 15 18:37:11 volumio volumio[880]: info: CoreCommandRouter::getUIConfigOnPlugin
Apr 15 18:37:11 volumio volumio[880]: info: Getting Spotify Cloud Configuration
Apr 15 18:37:11 volumio volumio[880]: info: Asking plugin for UI Config
Apr 15 18:37:11 volumio volumio[880]: info: CoreCommandRouter::getUIConfigOnPlugin
Apr 15 18:37:11 volumio volumio[880]: info: Saving Spotify Acccount
Apr 15 18:37:11 volumio volumio[880]: info: Got Tidal Cloud Configuration
Apr 15 18:37:11 volumio volumio[880]: info: Got it
Apr 15 18:37:11 volumio volumio[880]: error: Could not retrieve Spotify Config from plugin Spotify: no section found
Apr 15 18:37:11 volumio volumio[880]: info: CoreCommandRouter::getUIConfigOnPlugin
Apr 15 18:37:11 volumio volumio[880]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined
Apr 15 18:37:11 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus
Apr 15 18:37:11 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam
Apr 15 18:37:11 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam
Apr 15 18:37:11 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam
Apr 15 18:37:11 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 15 18:37:11 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 15 18:37:11 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 15 18:37:11 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 15 18:37:11 volumio volumio[880]: info: CoreCommandRouter::volumioGetBrowseSources
Apr 15 18:37:11 volumio volumio[880]: info: CoreCommandRouter::volumioGetBrowseSources
Apr 15 18:37:11 volumio volumio[880]: info: CoreCommandRouter::volumioGetBrowseSources
Apr 15 18:37:11 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Apr 15 18:37:11 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares
Apr 15 18:37:12 volumio ntpd[839]: Soliciting pool server 2001:ba0:21e:6000::2
Apr 15 18:37:12 volumio ntpd[839]: Soliciting pool server 84.77.195.114
Apr 15 18:37:12 volumio ntpd[839]: Soliciting pool server 195.20.235.143
Apr 15 18:37:13 volumio ntpd[839]: Soliciting pool server 178.215.228.24
Apr 15 18:37:13 volumio ntpd[839]: Soliciting pool server 194.0.5.123
Apr 15 18:37:14 volumio ntpd[839]: Soliciting pool server 5.250.191.170
Apr 15 18:37:14 volumio ntpd[839]: Soliciting pool server 5.250.191.170
Apr 15 18:37:15 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Apr 15 18:37:18 volumio ntpd[839]: receive: Unexpected origin timestamp 0xeba909bd.4e5212b1 does not match aorg 0000000000.00000000 from server@90.68.206.60 xmt 0xeba909be.aa68d356
Apr 15 18:37:18 volumio ntpd[839]: receive: Unexpected origin timestamp 0xeba909bd.4e4de2bd does not match aorg 0000000000.00000000 from server@178.215.228.24 xmt 0xeba909be.a931a27e
Apr 15 18:37:18 volumio ntpd[839]: receive: Unexpected origin timestamp 0xeba909bd.4e50cea9 does not match aorg 0000000000.00000000 from server@84.77.195.114 xmt 0xeba909be.a9adb86f
Apr 15 18:37:20 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Apr 15 18:37:24 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Apr 15 18:37:26 volumio volumio[880]: info: Disabling MyMusic plugin upnp
Apr 15 18:37:26 volumio sudo[2003]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service
Apr 15 18:37:26 volumio sudo[2003]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 15 18:37:26 volumio systemd[1]: Stopping UPnP Renderer front-end to MPD...
Apr 15 18:37:28 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Apr 15 18:37:29 volumio volumio[880]: info: Enabling MyMusic plugin upnp
Apr 15 18:37:29 volumio volumio[880]: info: Enabling plugin upnp
Apr 15 18:37:29 volumio volumio[880]: info: Loading plugin "upnp"...
Apr 15 18:37:29 volumio volumio[880]: info: [1744735049078] Starting Upmpd Daemon
Apr 15 18:37:29 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Apr 15 18:37:29 volumio volumio[880]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 15 18:37:29 volumio volumio[880]: Error: listen EADDRINUSE: address already in use :::6599
Apr 15 18:37:29 volumio volumio[880]: at Server.setupListenHandle [as _listen2] (net.js:1318:16)
Apr 15 18:37:29 volumio volumio[880]: at listenInCluster (net.js:1366:12)
Apr 15 18:37:29 volumio volumio[880]: at Server.listen (net.js:1452:7)
Apr 15 18:37:29 volumio volumio[880]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17)
Apr 15 18:37:29 volumio volumio[880]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38)
Apr 15 18:37:29 volumio volumio[880]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19)
Apr 15 18:37:29 volumio volumio[880]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28)
Apr 15 18:37:29 volumio volumio[880]: at processTicksAndRejections (internal/process/task_queues.js:75:11) {
Apr 15 18:37:29 volumio volumio[880]: code: 'EADDRINUSE',
Apr 15 18:37:29 volumio volumio[880]: errno: -98,
Apr 15 18:37:29 volumio volumio[880]: syscall: 'listen',
Apr 15 18:37:29 volumio volumio[880]: address: '::',
Apr 15 18:37:29 volumio volumio[880]: port: 6599
Apr 15 18:37:29 volumio volumio[880]: }
Apr 15 18:37:29 volumio volumio[880]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 15 18:37:29 volumio sudo[2015]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-15 18:36
Apr 15 18:37:29 volumio sudo[2015]: 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"