-- Logs begin at Thu 2019-02-14 13:11:59 MSK, end at Sun 2025-07-06 16:55:17 MSK. -- Jul 06 16:54:00 volumio systemd[1]: Started go-librespot Daemon. Jul 06 16:54:00 volumio go-librespot[1722]: go-librespot daemon starting... Jul 06 16:54:00 volumio go-librespot[1722]: time="2025-07-06T16:54:00+03:00" level=info msg="running go-librespot 0.2.0" Jul 06 16:54:00 volumio go-librespot[1722]: time="2025-07-06T16:54:00+03:00" level=debug msg="app state loaded" Jul 06 16:54:00 volumio go-librespot[1722]: time="2025-07-06T16:54:00+03:00" level=debug msg="stored credentials not found" Jul 06 16:54:00 volumio go-librespot[1722]: time="2025-07-06T16:54:00+03:00" level=info msg="api server listening on 127.0.0.1:9879" Jul 06 16:54:00 volumio go-librespot[1722]: time="2025-07-06T16:54:00+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 06 16:54:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 06 16:54:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 06 16:54:02 volumio volumio[947]: info: Initializing connection to go-librespot Websocket Jul 06 16:54:02 volumio volumio[947]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 06 16:54:03 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 06 16:54:03 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 16. Jul 06 16:54:03 volumio systemd[1]: Stopped go-librespot Daemon. Jul 06 16:54:03 volumio systemd[1]: Started go-librespot Daemon. Jul 06 16:54:03 volumio go-librespot[1732]: go-librespot daemon starting... Jul 06 16:54:03 volumio dhcpcd[859]: wlan0: leased 192.168.100.4 for 259200 seconds Jul 06 16:54:03 volumio avahi-daemon[563]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.100.4. Jul 06 16:54:03 volumio dhcpcd[859]: wlan0: adding route to 192.168.100.0/24 Jul 06 16:54:03 volumio dhcpcd[859]: wlan0: adding default route via 192.168.100.1 Jul 06 16:54:03 volumio avahi-daemon[563]: New relevant interface wlan0.IPv4 for mDNS. Jul 06 16:54:03 volumio avahi-daemon[563]: Registering new address record for 192.168.100.4 on wlan0.IPv4. Jul 06 16:54:03 volumio go-librespot[1732]: time="2025-07-06T16:54:03+03:00" level=info msg="running go-librespot 0.2.0" Jul 06 16:54:03 volumio go-librespot[1732]: time="2025-07-06T16:54:03+03:00" level=debug msg="app state loaded" Jul 06 16:54:03 volumio go-librespot[1732]: time="2025-07-06T16:54:03+03:00" level=debug msg="stored credentials not found" Jul 06 16:54:03 volumio go-librespot[1732]: time="2025-07-06T16:54:03+03:00" level=info msg="api server listening on 127.0.0.1:9879" Jul 06 16:54:04 volumio volumio[947]: info: Discovery: adding 2f1897fc-f11c-4839-8b99-0e2d95999821 Jul 06 16:54:04 volumio volumio[947]: info: Discovery: Found device Volumio Jul 06 16:54:04 volumio volumio[947]: info: CoreCommandRouter::volumioGetState Jul 06 16:54:04 volumio volumio[947]: info: CorePlayQueue::getTrack 0 Jul 06 16:54:05 volumio ntpd[769]: Listen normally on 4 wlan0 192.168.100.4:123 Jul 06 16:54:05 volumio ntpd[769]: new interface(s) found: waking up resolver Jul 06 16:54:05 volumio volumio[947]: info: Initializing connection to go-librespot Websocket Jul 06 16:54:05 volumio volumio[947]: info: Connection to go-librespot Websocket established Jul 06 16:54:05 volumio go-librespot[1732]: time="2025-07-06T16:54:05+03:00" level=debug msg="new websocket client" Jul 06 16:54:06 volumio go-librespot[1732]: time="2025-07-06T16:54:06+03: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]" Jul 06 16:54:06 volumio go-librespot[1732]: time="2025-07-06T16:54:06+03: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]" Jul 06 16:54:06 volumio go-librespot[1732]: time="2025-07-06T16:54:06+03: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]" Jul 06 16:54:06 volumio go-librespot[1732]: time="2025-07-06T16:54:06+03:00" level=info msg="zeroconf server listening on port 45541" Jul 06 16:54:06 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 06 16:54:06 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 06 16:54:06 volumio volumio[947]: info: Discovery: Getting this device information Jul 06 16:54:06 volumio volumio[947]: info: CoreCommandRouter::volumioGetState Jul 06 16:54:06 volumio volumio[947]: info: CorePlayQueue::getTrack 0 Jul 06 16:54:06 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 06 16:54:07 volumio volumio[947]: info: Volumio Network Manager: Network status updated: 2 Jul 06 16:54:08 volumio volumio[947]: verbose: New Socket.io Connection to 192.168.100.4:3000 from 192.168.100.3 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Jul 06 16:54:08 volumio volumio[947]: info: Getting Spotify volume Jul 06 16:54:08 volumio volumio[947]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Jul 06 16:54:08 volumio volumio[947]: info: CoreCommandRouter::volumioGetState Jul 06 16:54:08 volumio volumio[947]: info: CorePlayQueue::getTrack 0 Jul 06 16:54:08 volumio volumio[947]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Jul 06 16:54:08 volumio volumio[947]: SPOTIFY: SPOTIFY VOLUME undefined Jul 06 16:54:08 volumio volumio[947]: SPOTIFY: VOLUMIO VOLUME 50 Jul 06 16:54:08 volumio volumio[947]: info: Aligning Spotify Volume to Volumio Volume Jul 06 16:54:08 volumio volumio[947]: info: CoreCommandRouter::volumioGetState Jul 06 16:54:08 volumio volumio[947]: info: CorePlayQueue::getTrack 0 Jul 06 16:54:08 volumio volumio[947]: info: Setting Spotify Volume from Volumio: 50 Jul 06 16:54:10 volumio volumio[947]: SPOTIFY: SETTING SPOTIFY VOLUME 50 Jul 06 16:54:10 volumio volumio[947]: info: Sending Spotify command with payload to local API: /player/volume Jul 06 16:54:11 volumio nmbd[729]: [2025/07/06 16:54:11.135977, 0] ../source3/libsmb/nmblib.c:917(send_udp) Jul 06 16:54:11 volumio nmbd[729]: Packet send failed to 192.168.100.255(138) ERRNO=Network is unreachable Jul 06 16:54:11 volumio volumio[947]: info: CoreCommandRouter::volumioGetState Jul 06 16:54:11 volumio volumio[947]: info: CorePlayQueue::getTrack 0 Jul 06 16:54:11 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jul 06 16:54:11 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jul 06 16:54:11 volumio volumio[947]: info: CoreCommandRouter::volumioGetState Jul 06 16:54:11 volumio volumio[947]: info: CorePlayQueue::getTrack 0 Jul 06 16:54:14 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 06 16:54:14 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 06 16:54:14 volumio volumio[947]: info: Discovery: Getting this device information Jul 06 16:54:14 volumio volumio[947]: info: CoreCommandRouter::volumioGetState Jul 06 16:54:14 volumio volumio[947]: info: CorePlayQueue::getTrack 0 Jul 06 16:54:14 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 06 16:54:15 volumio volumio[947]: verbose: New Socket.io Connection to 192.168.100.4:3000 from 192.168.100.3 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Jul 06 16:54:15 volumio volumio[947]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jul 06 16:54:15 volumio volumio[947]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jul 06 16:54:15 volumio volumio[947]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jul 06 16:54:15 volumio volumio[947]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jul 06 16:54:15 volumio volumio[947]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jul 06 16:54:15 volumio volumio[947]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jul 06 16:54:15 volumio volumio[947]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jul 06 16:54:15 volumio volumio[947]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jul 06 16:54:15 volumio volumio[947]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jul 06 16:54:15 volumio volumio[947]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jul 06 16:54:15 volumio volumio[947]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jul 06 16:54:15 volumio volumio[947]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jul 06 16:54:15 volumio volumio[947]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jul 06 16:54:15 volumio volumio[947]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jul 06 16:54:15 volumio volumio[947]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jul 06 16:54:15 volumio volumio[947]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jul 06 16:54:15 volumio volumio[947]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jul 06 16:54:15 volumio volumio[947]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jul 06 16:54:15 volumio volumio[947]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jul 06 16:54:15 volumio volumio[947]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jul 06 16:54:15 volumio volumio[947]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jul 06 16:54:15 volumio volumio[947]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jul 06 16:54:15 volumio volumio[947]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jul 06 16:54:15 volumio volumio[947]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jul 06 16:54:15 volumio volumio[947]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jul 06 16:54:15 volumio volumio[947]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jul 06 16:54:15 volumio volumio[947]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jul 06 16:54:15 volumio volumio[947]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jul 06 16:54:15 volumio volumio[947]: info: Adding plugin bluetooth to MyMusic Plugins Jul 06 16:54:15 volumio volumio[947]: info: Adding plugin multiroom to MyMusic Plugins Jul 06 16:54:15 volumio volumio[947]: info: Adding plugin metavolumio to MyMusic Plugins Jul 06 16:54:15 volumio volumio[947]: info: Adding plugin cd_controller to MyMusic Plugins Jul 06 16:54:15 volumio volumio[947]: info: Adding plugin qobuzconnect to MyMusic Plugins Jul 06 16:54:15 volumio volumio[947]: info: Adding plugin smart_inputs to MyMusic Plugins Jul 06 16:54:15 volumio volumio[947]: info: Adding plugin tidalconnect to MyMusic Plugins Jul 06 16:54:15 volumio volumio[947]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jul 06 16:54:16 volumio volumio[947]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jul 06 16:54:16 volumio volumio[947]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jul 06 16:54:16 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 06 16:54:16 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 06 16:54:16 volumio volumio[947]: info: Starting MyVolumio Remote Streaming Endpoints Jul 06 16:54:16 volumio volumio[947]: info: MyVolumio login type: Token Jul 06 16:54:16 volumio volumio[947]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jul 06 16:54:16 volumio volumio[947]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jul 06 16:54:17 volumio volumio[947]: info: Starting Streaming Service Transparent Proxy Jul 06 16:54:17 volumio volumio[947]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jul 06 16:54:17 volumio volumio[947]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jul 06 16:54:17 volumio volumio[947]: info: Streaming services startup Jul 06 16:54:17 volumio volumio[947]: info: Starting Streaming Daemon Jul 06 16:54:17 volumio sudo[1783]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jul 06 16:54:17 volumio sudo[1783]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 06 16:54:17 volumio sudo[1783]: pam_unix(sudo:session): session closed for user root Jul 06 16:54:17 volumio volumio[947]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jul 06 16:54:17 volumio volumio[947]: info: CoreCommandRouter::volumioGetState Jul 06 16:54:17 volumio volumio[947]: info: CorePlayQueue::getTrack 0 Jul 06 16:54:17 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jul 06 16:54:17 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jul 06 16:54:17 volumio volumio[947]: info: CoreCommandRouter::volumioGetState Jul 06 16:54:17 volumio volumio[947]: info: CorePlayQueue::getTrack 0 Jul 06 16:54:17 volumio volumio[947]: error: Cannot start Volumio Streaming Daemon Jul 06 16:54:17 volumio volumio[947]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jul 06 16:54:17 volumio volumio[947]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jul 06 16:54:18 volumio volumio[947]: STREAMING PROXY: Starting server on port 3245 Jul 06 16:54:18 volumio volumio[947]: Node JS runtime: 14 Jul 06 16:54:21 volumio volumio[947]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jul 06 16:54:22 volumio volumio[947]: info: MyVolumio token set successfully Jul 06 16:54:22 volumio volumio[947]: info: MYVOLUMIO: Adding device Jul 06 16:54:22 volumio volumio[947]: info: MYVOLUMIO: Evaluating Server Jul 06 16:54:22 volumio volumio[947]: info: MyVolumio status changed Jul 06 16:54:22 volumio volumio[947]: info: Streaming services startup Jul 06 16:54:22 volumio volumio[947]: info: Starting Streaming Daemon Jul 06 16:54:22 volumio volumio[947]: info: Removing browser output: myVolumio user plan is not superstar Jul 06 16:54:22 volumio volumio[947]: info: Removing audio output: Jul 06 16:54:22 volumio volumio[947]: info: Stoppping Tunnel 1 Jul 06 16:54:22 volumio sudo[1826]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jul 06 16:54:22 volumio sudo[1826]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 06 16:54:22 volumio sudo[1829]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jul 06 16:54:22 volumio sudo[1829]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 06 16:54:22 volumio sudo[1826]: pam_unix(sudo:session): session closed for user root Jul 06 16:54:22 volumio volumio[947]: error: Cannot start Volumio Streaming Daemon Jul 06 16:54:22 volumio volumio[947]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jul 06 16:54:22 volumio volumio[947]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jul 06 16:54:23 volumio sudo[1829]: pam_unix(sudo:session): session closed for user root Jul 06 16:54:23 volumio volumio[947]: info: Remote SSH Stopped Jul 06 16:54:23 volumio volumio[947]: info: Setting Geolocation for MyVolumio to eu4 Jul 06 16:54:23 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 06 16:54:23 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 06 16:54:23 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 06 16:54:24 volumio volumio[947]: info: Updating MyVolumio device info Jul 06 16:54:24 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 06 16:54:25 volumio volumio[947]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jul 06 16:54:26 volumio volumio[947]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jul 06 16:54:26 volumio sudo[1832]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 06 16:54:26 volumio sudo[1832]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 06 16:54:26 volumio sudo[1832]: pam_unix(sudo:session): session closed for user root Jul 06 16:54:26 volumio sudo[1834]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 06 16:54:26 volumio sudo[1834]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 06 16:54:26 volumio sudo[1834]: pam_unix(sudo:session): session closed for user root Jul 06 16:54:29 volumio volumio[947]: info: MYVOLUMIO: Adding device Jul 06 16:54:29 volumio volumio[947]: info: MYVOLUMIO: Evaluating Server Jul 06 16:54:30 volumio volumio[947]: verbose: New Socket.io Connection to 192.168.100.4 from 192.168.100.3 UA: Mozilla/5.0 (Linux; Android 11; K48 Build/R01005; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.115 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Jul 06 16:54:31 volumio sudo[1867]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 06 16:54:31 volumio sudo[1867]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 06 16:54:31 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 06 16:54:31 volumio sudo[1867]: pam_unix(sudo:session): session closed for user root Jul 06 16:54:31 volumio sudo[1869]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 06 16:54:31 volumio sudo[1869]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 06 16:54:31 volumio sudo[1869]: pam_unix(sudo:session): session closed for user root Jul 06 16:54:31 volumio volumio[947]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Jul 06 16:54:31 volumio volumio[947]: info: CoreCommandRouter::volumioGetState Jul 06 16:54:31 volumio volumio[947]: info: CorePlayQueue::getTrack 0 Jul 06 16:54:31 volumio volumio[947]: info: Setting Geolocation for MyVolumio to eu10 Jul 06 16:54:31 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 06 16:54:31 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 06 16:54:31 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 06 16:54:32 volumio volumio[947]: verbose: New Socket.io Connection to 192.168.100.4 from 192.168.100.3 UA: Mozilla/5.0 (Linux; Android 11; K48 Build/R01005; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.115 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Jul 06 16:54:32 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 06 16:54:33 volumio volumio[947]: info: Updating MyVolumio device info Jul 06 16:54:33 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 06 16:54:33 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 06 16:54:33 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 06 16:54:33 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jul 06 16:54:33 volumio volumio[947]: info: CoreCommandRouter::volumioGetVisibleSources Jul 06 16:54:33 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 06 16:54:33 volumio volumio[947]: info: CoreCommandRouter::volumioGetState Jul 06 16:54:33 volumio volumio[947]: info: CorePlayQueue::getTrack 0 Jul 06 16:54:33 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 06 16:54:33 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 06 16:54:33 volumio volumio[947]: info: Received Get System Info Jul 06 16:54:33 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 06 16:54:33 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 06 16:54:33 volumio volumio[947]: info: Discovery: Getting this device information Jul 06 16:54:33 volumio volumio[947]: info: CoreCommandRouter::volumioGetState Jul 06 16:54:33 volumio volumio[947]: info: CorePlayQueue::getTrack 0 Jul 06 16:54:33 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 06 16:54:33 volumio volumio[947]: info: CoreCommandRouter::volumioGetState Jul 06 16:54:33 volumio volumio[947]: info: CorePlayQueue::getTrack 0 Jul 06 16:54:33 volumio volumio[947]: info: Listing playlists Jul 06 16:54:33 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jul 06 16:54:33 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 06 16:54:33 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 06 16:54:33 volumio volumio[947]: info: Received Get System Info Jul 06 16:54:33 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 06 16:54:33 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 06 16:54:33 volumio volumio[947]: info: Discovery: Getting this device information Jul 06 16:54:33 volumio volumio[947]: info: CoreCommandRouter::volumioGetState Jul 06 16:54:33 volumio volumio[947]: info: CorePlayQueue::getTrack 0 Jul 06 16:54:33 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 06 16:54:33 volumio volumio[947]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jul 06 16:54:33 volumio volumio[947]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 06 16:54:33 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jul 06 16:54:33 volumio volumio[947]: info: FusionDsp - no preset used Jul 06 16:54:33 volumio volumio[947]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json,Q1.json Jul 06 16:54:34 volumio volumio[947]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jul 06 16:54:36 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jul 06 16:54:36 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 06 16:54:36 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 06 16:54:36 volumio volumio[947]: info: Discovery: Getting this device information Jul 06 16:54:36 volumio volumio[947]: info: CoreCommandRouter::volumioGetState Jul 06 16:54:36 volumio volumio[947]: info: CorePlayQueue::getTrack 0 Jul 06 16:54:36 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 06 16:54:36 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 16:54:36 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 16:54:36 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jul 06 16:54:36 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jul 06 16:54:36 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jul 06 16:54:36 volumio volumio[947]: info: CoreCommandRouter::volumioGetBrowseSources Jul 06 16:54:36 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 06 16:54:37 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 06 16:54:37 volumio volumio[947]: info: Received Get System Info Jul 06 16:54:37 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 06 16:54:37 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 06 16:54:37 volumio volumio[947]: info: Discovery: Getting this device information Jul 06 16:54:37 volumio volumio[947]: info: CoreCommandRouter::volumioGetState Jul 06 16:54:37 volumio volumio[947]: info: CorePlayQueue::getTrack 0 Jul 06 16:54:37 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 06 16:54:39 volumio volumio[947]: info: CoreCommandRouter::volumioPlay Jul 06 16:54:39 volumio volumio[947]: info: CoreStateMachine::play index undefined Jul 06 16:54:39 volumio volumio[947]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 06 16:54:39 volumio volumio[947]: info: CorePlayQueue::getTrack 0 Jul 06 16:54:39 volumio volumio[947]: info: CoreStateMachine::startPlaybackTimer Jul 06 16:54:39 volumio volumio[947]: info: CorePlayQueue::getTrack 0 Jul 06 16:54:39 volumio volumio[947]: verbose: ControllerMpd::sendMpdCommand stop Jul 06 16:54:39 volumio volumio[947]: info: sendMpdCommand stop took 3 milliseconds Jul 06 16:54:39 volumio volumio[947]: verbose: ControllerMpd::sendMpdCommand clear Jul 06 16:54:39 volumio volumio[947]: info: Jul 06 16:54:39 volumio volumio[947]: ---------------------------- MPD announces system playlist update Jul 06 16:54:39 volumio volumio[947]: info: Ignoring MPD Status Update Jul 06 16:54:39 volumio volumio[947]: info: sendMpdCommand clear took 2 milliseconds Jul 06 16:54:39 volumio volumio[947]: info: Jul 06 16:54:39 volumio volumio[947]: ---------------------------- MPD announces system playlist update Jul 06 16:54:39 volumio volumio[947]: info: Ignoring MPD Status Update Jul 06 16:54:39 volumio volumio[947]: info: Jul 06 16:54:39 volumio volumio[947]: ---------------------------- MPD announces system playlist update Jul 06 16:54:39 volumio volumio[947]: info: Ignoring MPD Status Update Jul 06 16:54:39 volumio volumio[947]: error: updateQueue error: null Jul 06 16:54:39 volumio volumio[947]: error: updateQueue error: null Jul 06 16:54:39 volumio volumio[947]: error: updateQueue error: null Jul 06 16:54:39 volumio volumio[947]: info: ------------------------------ 31ms Jul 06 16:54:39 volumio volumio[947]: info: ------------------------------ 4ms Jul 06 16:54:39 volumio volumio[947]: info: ------------------------------ 4ms Jul 06 16:54:39 volumio volumio[947]: error: Upnp client error: Error: This socket has been ended by the other party Jul 06 16:54:41 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jul 06 16:54:42 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 06 16:54:43 volumio volumio[947]: verbose: ControllerMpd::sendMpdCommand addid "http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fstrm-mar-43.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dc70c2965caaf7a30b3f4dab4fc3e9c3f9a0d526f762e854b63262bde2614ea1e%2Clid%3D177%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D68781ce7%2F0%2F1678395%2F56b68ef3.73822163.6.49637980%2F320.mp3&ext=.mp3" Jul 06 16:54:43 volumio volumio[947]: info: Jul 06 16:54:43 volumio volumio[947]: ---------------------------- MPD announces system playlist update Jul 06 16:54:43 volumio volumio[947]: info: Ignoring MPD Status Update Jul 06 16:54:43 volumio volumio[947]: info: sendMpdCommand addid "http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fstrm-mar-43.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dc70c2965caaf7a30b3f4dab4fc3e9c3f9a0d526f762e854b63262bde2614ea1e%2Clid%3D177%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D68781ce7%2F0%2F1678395%2F56b68ef3.73822163.6.49637980%2F320.mp3&ext=.mp3" took 2 milliseconds Jul 06 16:54:43 volumio volumio[947]: verbose: MPD COMMAND [object Object] Jul 06 16:54:43 volumio volumio[947]: verbose: MPD COMMAND [object Object] Jul 06 16:54:43 volumio volumio[947]: verbose: MPD COMMAND [object Object] Jul 06 16:54:43 volumio volumio[947]: info: Jul 06 16:54:43 volumio volumio[947]: ---------------------------- MPD announces system playlist update Jul 06 16:54:43 volumio volumio[947]: info: Ignoring MPD Status Update Jul 06 16:54:43 volumio volumio[947]: info: Jul 06 16:54:43 volumio volumio[947]: ---------------------------- MPD announces system playlist update Jul 06 16:54:43 volumio volumio[947]: info: Ignoring MPD Status Update Jul 06 16:54:43 volumio volumio[947]: info: Jul 06 16:54:43 volumio volumio[947]: ---------------------------- MPD announces system playlist update Jul 06 16:54:43 volumio volumio[947]: info: Ignoring MPD Status Update Jul 06 16:54:43 volumio volumio[947]: info: ------------------------------ 8ms Jul 06 16:54:43 volumio volumio[947]: info: ------------------------------ 5ms Jul 06 16:54:43 volumio volumio[947]: info: ------------------------------ 4ms Jul 06 16:54:43 volumio volumio[947]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 06 16:54:43 volumio volumio[947]: verbose: ControllerMpd::sendMpdCommand play Jul 06 16:54:43 volumio volumio[947]: info: Jul 06 16:54:43 volumio volumio[947]: ---------------------------- MPD announces system playlist update Jul 06 16:54:43 volumio volumio[947]: info: Ignoring MPD Status Update Jul 06 16:54:43 volumio volumio[947]: info: Jul 06 16:54:43 volumio volumio[947]: ---------------------------- MPD announces system playlist update Jul 06 16:54:43 volumio volumio[947]: info: Ignoring MPD Status Update Jul 06 16:54:43 volumio volumio[947]: info: ------------------------------ 6ms Jul 06 16:54:43 volumio volumio[947]: info: sendMpdCommand play took 4 milliseconds Jul 06 16:54:43 volumio volumio[947]: info: ------------------------------ 4ms Jul 06 16:54:43 volumio volumio[947]: info: ------------------------------ 3ms Jul 06 16:54:44 volumio volumio[947]: info: CoreCommandRouter::volumioPlay Jul 06 16:54:44 volumio volumio[947]: info: CoreStateMachine::play index undefined Jul 06 16:54:44 volumio volumio[947]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 06 16:54:44 volumio volumio[947]: info: CorePlayQueue::getTrack 0 Jul 06 16:54:44 volumio volumio[947]: info: CoreStateMachine::startPlaybackTimer Jul 06 16:54:44 volumio volumio[947]: info: CorePlayQueue::getTrack 0 Jul 06 16:54:44 volumio volumio[947]: verbose: ControllerMpd::sendMpdCommand stop Jul 06 16:54:44 volumio volumio[947]: info: Jul 06 16:54:44 volumio volumio[947]: ---------------------------- MPD announces state update: player Jul 06 16:54:44 volumio volumio[947]: info: sendMpdCommand stop took 3 milliseconds Jul 06 16:54:44 volumio volumio[947]: info: ControllerMpd::getState Jul 06 16:54:44 volumio volumio[947]: verbose: ControllerMpd::sendMpdCommand status Jul 06 16:54:44 volumio volumio[947]: verbose: ControllerMpd::sendMpdCommand clear Jul 06 16:54:44 volumio volumio[947]: info: Jul 06 16:54:44 volumio volumio[947]: ---------------------------- MPD announces system playlist update Jul 06 16:54:44 volumio volumio[947]: info: Ignoring MPD Status Update Jul 06 16:54:44 volumio volumio[947]: info: sendMpdCommand status took 2 milliseconds Jul 06 16:54:44 volumio volumio[947]: info: sendMpdCommand clear took 2 milliseconds Jul 06 16:54:44 volumio volumio[947]: verbose: ControllerMpd::parseState Jul 06 16:54:44 volumio volumio[947]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 06 16:54:44 volumio volumio[947]: info: Jul 06 16:54:44 volumio volumio[947]: ---------------------------- MPD announces state update: player Jul 06 16:54:44 volumio volumio[947]: info: ControllerMpd::getState Jul 06 16:54:44 volumio volumio[947]: verbose: ControllerMpd::sendMpdCommand status Jul 06 16:54:44 volumio volumio[947]: info: Jul 06 16:54:44 volumio volumio[947]: ---------------------------- MPD announces state update: player Jul 06 16:54:44 volumio volumio[947]: info: ControllerMpd::getState Jul 06 16:54:44 volumio volumio[947]: verbose: ControllerMpd::sendMpdCommand status Jul 06 16:54:44 volumio volumio[947]: error: updateQueue error: null Jul 06 16:54:44 volumio volumio[947]: info: ------------------------------ 12ms Jul 06 16:54:44 volumio volumio[947]: info: sendMpdCommand playlistinfo took 9 milliseconds Jul 06 16:54:44 volumio volumio[947]: info: sendMpdCommand status took 2 milliseconds Jul 06 16:54:44 volumio volumio[947]: info: sendMpdCommand status took 2 milliseconds Jul 06 16:54:44 volumio volumio[947]: verbose: ControllerMpd::parseTrackInfo Jul 06 16:54:44 volumio volumio[947]: verbose: ControllerMpd::parseState Jul 06 16:54:44 volumio volumio[947]: verbose: ControllerMpd::parseState Jul 06 16:54:44 volumio volumio[947]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 06 16:54:44 volumio volumio[947]: info: ControllerMpd::pushState Jul 06 16:54:44 volumio volumio[947]: info: CoreCommandRouter::servicePushState Jul 06 16:54:44 volumio volumio[947]: info: CoreStateMachine::pushState Jul 06 16:54:44 volumio volumio[947]: info: CorePlayQueue::getTrack 0 Jul 06 16:54:44 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 06 16:54:44 volumio volumio[947]: info: CoreCommandRouter::volumioPushState Jul 06 16:54:44 volumio volumio[947]: info: CorePlayQueue::getTrack 0 Jul 06 16:54:44 volumio volumio[947]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current yandex_music Received mpd Jul 06 16:54:44 volumio volumio[947]: info: ControllerMpd::pushState Jul 06 16:54:44 volumio volumio[947]: info: CoreCommandRouter::servicePushState Jul 06 16:54:44 volumio volumio[947]: info: CoreStateMachine::pushState Jul 06 16:54:44 volumio volumio[947]: info: CorePlayQueue::getTrack 0 Jul 06 16:54:44 volumio volumio[947]: info: CoreCommandRouter::volumioPushState Jul 06 16:54:44 volumio volumio[947]: info: CorePlayQueue::getTrack 0 Jul 06 16:54:44 volumio volumio[947]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current yandex_music Received mpd Jul 06 16:54:44 volumio volumio[947]: info: ------------------------------ 30ms Jul 06 16:54:44 volumio volumio[947]: info: ------------------------------ 33ms Jul 06 16:54:44 volumio volumio[947]: info: ------------------------------ 33ms Jul 06 16:54:44 volumio volumio[947]: info: Jul 06 16:54:44 volumio volumio[947]: ---------------------------- MPD announces system playlist update Jul 06 16:54:44 volumio volumio[947]: info: Ignoring MPD Status Update Jul 06 16:54:44 volumio volumio[947]: info: Jul 06 16:54:44 volumio volumio[947]: ---------------------------- MPD announces system playlist update Jul 06 16:54:44 volumio volumio[947]: info: Ignoring MPD Status Update Jul 06 16:54:44 volumio volumio[947]: error: updateQueue error: null Jul 06 16:54:44 volumio volumio[947]: error: updateQueue error: null Jul 06 16:54:44 volumio volumio[947]: info: ------------------------------ 3ms Jul 06 16:54:44 volumio volumio[947]: info: ------------------------------ 2ms Jul 06 16:54:44 volumio volumio[947]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Jul 06 16:54:44 volumio volumio[947]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Jul 06 16:54:46 volumio volumio[947]: verbose: ControllerMpd::sendMpdCommand addid "http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fstrm-mar-43.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dc70c2965caaf7a30b3f4dab4fc3e9c3f9a0d526f762e854b63262bde2614ea1e%2Clid%3D177%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D68781ce7%2F0%2F1678395%2F56b68ef3.73822163.6.49637980%2F320.mp3&ext=.mp3" Jul 06 16:54:46 volumio volumio[947]: info: Jul 06 16:54:46 volumio volumio[947]: ---------------------------- MPD announces system playlist update Jul 06 16:54:46 volumio volumio[947]: info: Ignoring MPD Status Update Jul 06 16:54:46 volumio volumio[947]: info: sendMpdCommand addid "http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fstrm-mar-43.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dc70c2965caaf7a30b3f4dab4fc3e9c3f9a0d526f762e854b63262bde2614ea1e%2Clid%3D177%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D68781ce7%2F0%2F1678395%2F56b68ef3.73822163.6.49637980%2F320.mp3&ext=.mp3" took 2 milliseconds Jul 06 16:54:46 volumio volumio[947]: verbose: MPD COMMAND [object Object] Jul 06 16:54:46 volumio volumio[947]: verbose: MPD COMMAND [object Object] Jul 06 16:54:46 volumio volumio[947]: verbose: MPD COMMAND [object Object] Jul 06 16:54:46 volumio volumio[947]: info: Jul 06 16:54:46 volumio volumio[947]: ---------------------------- MPD announces system playlist update Jul 06 16:54:46 volumio volumio[947]: info: Ignoring MPD Status Update Jul 06 16:54:46 volumio volumio[947]: info: Jul 06 16:54:46 volumio volumio[947]: ---------------------------- MPD announces system playlist update Jul 06 16:54:46 volumio volumio[947]: info: Ignoring MPD Status Update Jul 06 16:54:46 volumio volumio[947]: info: Jul 06 16:54:46 volumio volumio[947]: ---------------------------- MPD announces system playlist update Jul 06 16:54:46 volumio volumio[947]: info: Ignoring MPD Status Update Jul 06 16:54:46 volumio volumio[947]: info: ------------------------------ 5ms Jul 06 16:54:46 volumio volumio[947]: info: ------------------------------ 3ms Jul 06 16:54:46 volumio volumio[947]: info: ------------------------------ 3ms Jul 06 16:54:46 volumio volumio[947]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 06 16:54:46 volumio volumio[947]: verbose: ControllerMpd::sendMpdCommand play Jul 06 16:54:46 volumio volumio[947]: info: Jul 06 16:54:46 volumio volumio[947]: ---------------------------- MPD announces system playlist update Jul 06 16:54:46 volumio volumio[947]: info: Ignoring MPD Status Update Jul 06 16:54:46 volumio volumio[947]: info: Jul 06 16:54:46 volumio volumio[947]: ---------------------------- MPD announces system playlist update Jul 06 16:54:46 volumio volumio[947]: info: Ignoring MPD Status Update Jul 06 16:54:46 volumio volumio[947]: info: ------------------------------ 4ms Jul 06 16:54:46 volumio volumio[947]: info: sendMpdCommand play took 3 milliseconds Jul 06 16:54:46 volumio volumio[947]: info: ------------------------------ 2ms Jul 06 16:54:46 volumio volumio[947]: info: ------------------------------ 2ms Jul 06 16:54:47 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jul 06 16:54:49 volumio volumio[947]: info: Jul 06 16:54:49 volumio volumio[947]: ---------------------------- MPD announces state update: player Jul 06 16:54:49 volumio volumio[947]: info: ControllerMpd::getState Jul 06 16:54:49 volumio volumio[947]: verbose: ControllerMpd::sendMpdCommand status Jul 06 16:54:49 volumio volumio[947]: info: Jul 06 16:54:49 volumio volumio[947]: ---------------------------- MPD announces state update: player Jul 06 16:54:49 volumio volumio[947]: info: ControllerMpd::getState Jul 06 16:54:49 volumio volumio[947]: verbose: ControllerMpd::sendMpdCommand status Jul 06 16:54:49 volumio volumio[947]: info: Jul 06 16:54:49 volumio volumio[947]: ---------------------------- MPD announces state update: player Jul 06 16:54:49 volumio volumio[947]: info: ControllerMpd::getState Jul 06 16:54:49 volumio volumio[947]: verbose: ControllerMpd::sendMpdCommand status Jul 06 16:54:49 volumio volumio[947]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jul 06 16:54:49 volumio volumio[947]: info: FusionDsp - ---- read samplerate from file: 44100 Jul 06 16:54:49 volumio volumio[947]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jul 06 16:54:49 volumio volumio[947]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jul 06 16:54:49 volumio volumio[947]: info: FusionDsp - ---- read samplerate from file: 44100 Jul 06 16:54:49 volumio volumio[947]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jul 06 16:54:49 volumio volumio[947]: info: Jul 06 16:54:49 volumio volumio[947]: ---------------------------- MPD announces state update: player Jul 06 16:54:49 volumio volumio[947]: info: sendMpdCommand status took 39 milliseconds Jul 06 16:54:49 volumio volumio[947]: info: ControllerMpd::getState Jul 06 16:54:49 volumio volumio[947]: verbose: ControllerMpd::sendMpdCommand status Jul 06 16:54:49 volumio volumio[947]: info: sendMpdCommand status took 38 milliseconds Jul 06 16:54:49 volumio volumio[947]: info: sendMpdCommand status took 36 milliseconds Jul 06 16:54:49 volumio volumio[947]: verbose: ControllerMpd::parseState Jul 06 16:54:49 volumio volumio[947]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 06 16:54:49 volumio volumio[947]: verbose: ControllerMpd::parseState Jul 06 16:54:49 volumio volumio[947]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 06 16:54:49 volumio volumio[947]: verbose: ControllerMpd::parseState Jul 06 16:54:49 volumio volumio[947]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 06 16:54:49 volumio volumio[947]: info: Jul 06 16:54:49 volumio volumio[947]: ---------------------------- MPD announces state update: player Jul 06 16:54:49 volumio volumio[947]: info: ControllerMpd::getState Jul 06 16:54:49 volumio volumio[947]: verbose: ControllerMpd::sendMpdCommand status Jul 06 16:54:49 volumio volumio[947]: info: Jul 06 16:54:49 volumio volumio[947]: ---------------------------- MPD announces state update: player Jul 06 16:54:49 volumio volumio[947]: info: ControllerMpd::getState Jul 06 16:54:49 volumio volumio[947]: verbose: ControllerMpd::sendMpdCommand status Jul 06 16:54:49 volumio volumio[947]: info: sendMpdCommand status took 7 milliseconds Jul 06 16:54:49 volumio volumio[947]: info: sendMpdCommand playlistinfo took 6 milliseconds Jul 06 16:54:49 volumio volumio[947]: info: sendMpdCommand playlistinfo took 7 milliseconds Jul 06 16:54:49 volumio volumio[947]: info: sendMpdCommand playlistinfo took 7 milliseconds Jul 06 16:54:49 volumio volumio[947]: info: sendMpdCommand status took 7 milliseconds Jul 06 16:54:49 volumio volumio[947]: info: sendMpdCommand status took 5 milliseconds Jul 06 16:54:49 volumio volumio[947]: verbose: ControllerMpd::parseState Jul 06 16:54:49 volumio volumio[947]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 06 16:54:49 volumio volumio[947]: verbose: ControllerMpd::parseTrackInfo Jul 06 16:54:49 volumio volumio[947]: verbose: ControllerMpd::parseTrackInfo Jul 06 16:54:49 volumio volumio[947]: verbose: ControllerMpd::parseTrackInfo Jul 06 16:54:49 volumio volumio[947]: verbose: ControllerMpd::parseState Jul 06 16:54:49 volumio volumio[947]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 06 16:54:49 volumio volumio[947]: verbose: ControllerMpd::parseState Jul 06 16:54:49 volumio volumio[947]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 06 16:54:49 volumio volumio[947]: info: ControllerMpd::pushState Jul 06 16:54:49 volumio volumio[947]: info: CoreCommandRouter::servicePushState Jul 06 16:54:49 volumio volumio[947]: info: CorePlayQueue::getTrack 0 Jul 06 16:54:49 volumio volumio[947]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":272,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Chillout Mix","artist":"Chill Out 2016","album":"Ambient Music – Bossa, Chillout Lounge, Deep Chill, Sensual Chill Lounge","uri":"http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fstrm-mar-43.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dc70c2965caaf7a30b3f4dab4fc3e9c3f9a0d526f762e854b63262bde2614ea1e%2Clid%3D177%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D68781ce7%2F0%2F1678395%2F56b68ef3.73822163.6.49637980%2F320.mp3&ext=.mp3","trackType":"mp3"} Jul 06 16:54:49 volumio volumio[947]: verbose: CURRENT POSITION 0 Jul 06 16:54:49 volumio volumio[947]: info: CoreStateMachine::syncState stateService play Jul 06 16:54:49 volumio volumio[947]: info: CoreStateMachine::syncState currentStatus stop Jul 06 16:54:49 volumio volumio[947]: info: ControllerMpd::pushState Jul 06 16:54:49 volumio volumio[947]: info: CoreCommandRouter::servicePushState Jul 06 16:54:49 volumio volumio[947]: info: CorePlayQueue::getTrack 0 Jul 06 16:54:49 volumio volumio[947]: verbose: STATE SERVICE {"status":"play","position":0,"seek":193,"duration":272,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Chillout Mix","artist":"Chill Out 2016","album":"Ambient Music – Bossa, Chillout Lounge, Deep Chill, Sensual Chill Lounge","uri":"http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fstrm-mar-43.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dc70c2965caaf7a30b3f4dab4fc3e9c3f9a0d526f762e854b63262bde2614ea1e%2Clid%3D177%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D68781ce7%2F0%2F1678395%2F56b68ef3.73822163.6.49637980%2F320.mp3&ext=.mp3","trackType":"mp3"} Jul 06 16:54:49 volumio volumio[947]: verbose: CURRENT POSITION 0 Jul 06 16:54:49 volumio volumio[947]: info: CoreStateMachine::syncState stateService play Jul 06 16:54:49 volumio volumio[947]: info: CoreStateMachine::syncState currentStatus play Jul 06 16:54:49 volumio volumio[947]: info: Received an update from plugin. extracting info from payload Jul 06 16:54:49 volumio volumio[947]: info: CoreStateMachine::pushState Jul 06 16:54:49 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 06 16:54:49 volumio volumio[947]: info: CoreCommandRouter::volumioPushState Jul 06 16:54:49 volumio volumio[947]: info: CoreStateMachine::pushState Jul 06 16:54:49 volumio volumio[947]: info: CoreCommandRouter::volumioPushState Jul 06 16:54:49 volumio volumio[947]: info: ControllerMpd::pushState Jul 06 16:54:49 volumio volumio[947]: info: CoreCommandRouter::servicePushState Jul 06 16:54:49 volumio volumio[947]: info: CorePlayQueue::getTrack 0 Jul 06 16:54:49 volumio volumio[947]: verbose: STATE SERVICE {"status":"play","position":0,"seek":251,"duration":272,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Chillout Mix","artist":"Chill Out 2016","album":"Ambient Music – Bossa, Chillout Lounge, Deep Chill, Sensual Chill Lounge","uri":"http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fstrm-mar-43.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dc70c2965caaf7a30b3f4dab4fc3e9c3f9a0d526f762e854b63262bde2614ea1e%2Clid%3D177%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D68781ce7%2F0%2F1678395%2F56b68ef3.73822163.6.49637980%2F320.mp3&ext=.mp3","trackType":"mp3"} Jul 06 16:54:49 volumio volumio[947]: verbose: CURRENT POSITION 0 Jul 06 16:54:49 volumio volumio[947]: info: CoreStateMachine::syncState stateService play Jul 06 16:54:49 volumio volumio[947]: info: CoreStateMachine::syncState currentStatus play Jul 06 16:54:49 volumio volumio[947]: info: Received an update from plugin. extracting info from payload Jul 06 16:54:49 volumio volumio[947]: info: CoreStateMachine::pushState Jul 06 16:54:49 volumio volumio[947]: info: CoreCommandRouter::volumioPushState Jul 06 16:54:49 volumio volumio[947]: info: CoreStateMachine::pushState Jul 06 16:54:49 volumio volumio[947]: info: CoreCommandRouter::volumioPushState Jul 06 16:54:49 volumio volumio[947]: info: ------------------------------ 73ms Jul 06 16:54:49 volumio volumio[947]: info: ------------------------------ 94ms Jul 06 16:54:49 volumio volumio[947]: info: ------------------------------ 92ms Jul 06 16:54:49 volumio volumio[947]: info: sendMpdCommand playlistinfo took 46 milliseconds Jul 06 16:54:49 volumio volumio[947]: info: sendMpdCommand playlistinfo took 45 milliseconds Jul 06 16:54:49 volumio volumio[947]: info: sendMpdCommand playlistinfo took 45 milliseconds Jul 06 16:54:49 volumio volumio[947]: verbose: ControllerMpd::parseTrackInfo Jul 06 16:54:49 volumio volumio[947]: verbose: ControllerMpd::parseTrackInfo Jul 06 16:54:49 volumio volumio[947]: verbose: ControllerMpd::parseTrackInfo Jul 06 16:54:49 volumio volumio[947]: info: ControllerMpd::pushState Jul 06 16:54:49 volumio volumio[947]: info: CoreCommandRouter::servicePushState Jul 06 16:54:49 volumio volumio[947]: info: CorePlayQueue::getTrack 0 Jul 06 16:54:49 volumio volumio[947]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1474,"duration":272,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Chillout Mix","artist":"Chill Out 2016","album":"Ambient Music – Bossa, Chillout Lounge, Deep Chill, Sensual Chill Lounge","uri":"http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fstrm-mar-43.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dc70c2965caaf7a30b3f4dab4fc3e9c3f9a0d526f762e854b63262bde2614ea1e%2Clid%3D177%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D68781ce7%2F0%2F1678395%2F56b68ef3.73822163.6.49637980%2F320.mp3&ext=.mp3","trackType":"mp3"} Jul 06 16:54:49 volumio volumio[947]: verbose: CURRENT POSITION 0 Jul 06 16:54:49 volumio volumio[947]: info: CoreStateMachine::syncState stateService play Jul 06 16:54:49 volumio volumio[947]: info: CoreStateMachine::syncState currentStatus play Jul 06 16:54:49 volumio volumio[947]: info: Received an update from plugin. extracting info from payload Jul 06 16:54:49 volumio volumio[947]: info: CoreStateMachine::pushState Jul 06 16:54:49 volumio volumio[947]: info: CoreCommandRouter::volumioPushState Jul 06 16:54:49 volumio volumio[947]: info: CoreStateMachine::pushState Jul 06 16:54:49 volumio volumio[947]: info: CoreCommandRouter::volumioPushState Jul 06 16:54:49 volumio volumio[947]: info: ControllerMpd::pushState Jul 06 16:54:49 volumio volumio[947]: info: CoreCommandRouter::servicePushState Jul 06 16:54:49 volumio volumio[947]: info: CorePlayQueue::getTrack 0 Jul 06 16:54:49 volumio volumio[947]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1474,"duration":272,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Chillout Mix","artist":"Chill Out 2016","album":"Ambient Music – Bossa, Chillout Lounge, Deep Chill, Sensual Chill Lounge","uri":"http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fstrm-mar-43.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dc70c2965caaf7a30b3f4dab4fc3e9c3f9a0d526f762e854b63262bde2614ea1e%2Clid%3D177%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D68781ce7%2F0%2F1678395%2F56b68ef3.73822163.6.49637980%2F320.mp3&ext=.mp3","trackType":"mp3"} Jul 06 16:54:49 volumio volumio[947]: verbose: CURRENT POSITION 0 Jul 06 16:54:49 volumio volumio[947]: info: CoreStateMachine::syncState stateService play Jul 06 16:54:49 volumio volumio[947]: info: CoreStateMachine::syncState currentStatus play Jul 06 16:54:49 volumio volumio[947]: info: Received an update from plugin. extracting info from payload Jul 06 16:54:49 volumio volumio[947]: info: CoreStateMachine::pushState Jul 06 16:54:49 volumio volumio[947]: info: CoreCommandRouter::volumioPushState Jul 06 16:54:49 volumio volumio[947]: info: CoreStateMachine::pushState Jul 06 16:54:49 volumio volumio[947]: info: CoreCommandRouter::volumioPushState Jul 06 16:54:49 volumio volumio[947]: info: ControllerMpd::pushState Jul 06 16:54:49 volumio volumio[947]: info: CoreCommandRouter::servicePushState Jul 06 16:54:49 volumio volumio[947]: info: CorePlayQueue::getTrack 0 Jul 06 16:54:49 volumio volumio[947]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1474,"duration":272,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Chillout Mix","artist":"Chill Out 2016","album":"Ambient Music – Bossa, Chillout Lounge, Deep Chill, Sensual Chill Lounge","uri":"http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fstrm-mar-43.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dc70c2965caaf7a30b3f4dab4fc3e9c3f9a0d526f762e854b63262bde2614ea1e%2Clid%3D177%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D68781ce7%2F0%2F1678395%2F56b68ef3.73822163.6.49637980%2F320.mp3&ext=.mp3","trackType":"mp3"} Jul 06 16:54:49 volumio volumio[947]: verbose: CURRENT POSITION 0 Jul 06 16:54:49 volumio volumio[947]: info: CoreStateMachine::syncState stateService play Jul 06 16:54:49 volumio volumio[947]: info: CoreStateMachine::syncState currentStatus play Jul 06 16:54:49 volumio volumio[947]: info: Received an update from plugin. extracting info from payload Jul 06 16:54:49 volumio volumio[947]: info: CoreStateMachine::pushState Jul 06 16:54:49 volumio volumio[947]: info: CoreCommandRouter::volumioPushState Jul 06 16:54:49 volumio volumio[947]: info: CoreStateMachine::pushState Jul 06 16:54:49 volumio volumio[947]: info: CoreCommandRouter::volumioPushState Jul 06 16:54:49 volumio volumio[947]: info: ------------------------------ 114ms Jul 06 16:54:49 volumio volumio[947]: info: ------------------------------ 110ms Jul 06 16:54:49 volumio volumio[947]: info: ------------------------------ 110ms Jul 06 16:54:49 volumio volumio[947]: info: CoreCommandRouter::volumioGetQueue Jul 06 16:54:49 volumio volumio[947]: info: CoreStateMachine::getQueue Jul 06 16:54:49 volumio volumio[947]: info: CorePlayQueue::getQueue Jul 06 16:54:49 volumio volumio[947]: info: CoreCommandRouter::volumioGetQueue Jul 06 16:54:49 volumio volumio[947]: info: CoreStateMachine::getQueue Jul 06 16:54:49 volumio volumio[947]: info: CorePlayQueue::getQueue Jul 06 16:54:49 volumio volumio[947]: info: CoreCommandRouter::volumioGetQueue Jul 06 16:54:49 volumio volumio[947]: info: CoreStateMachine::getQueue Jul 06 16:54:49 volumio volumio[947]: info: CorePlayQueue::getQueue Jul 06 16:54:49 volumio volumio[947]: info: CoreCommandRouter::volumioGetQueue Jul 06 16:54:49 volumio volumio[947]: info: CoreStateMachine::getQueue Jul 06 16:54:49 volumio volumio[947]: info: CorePlayQueue::getQueue Jul 06 16:54:49 volumio volumio[947]: info: CoreCommandRouter::volumioGetQueue Jul 06 16:54:49 volumio volumio[947]: info: CoreStateMachine::getQueue Jul 06 16:54:49 volumio volumio[947]: info: CorePlayQueue::getQueue Jul 06 16:54:49 volumio volumio[947]: info: CoreCommandRouter::volumioGetQueue Jul 06 16:54:49 volumio volumio[947]: info: CoreStateMachine::getQueue Jul 06 16:54:49 volumio volumio[947]: info: CorePlayQueue::getQueue Jul 06 16:54:49 volumio volumio[947]: info: CoreCommandRouter::volumioGetQueue Jul 06 16:54:49 volumio volumio[947]: info: CoreStateMachine::getQueue Jul 06 16:54:49 volumio volumio[947]: info: CorePlayQueue::getQueue Jul 06 16:54:49 volumio volumio[947]: info: CoreCommandRouter::volumioGetQueue Jul 06 16:54:49 volumio volumio[947]: info: CoreStateMachine::getQueue Jul 06 16:54:49 volumio volumio[947]: info: CorePlayQueue::getQueue Jul 06 16:54:49 volumio volumio[947]: info: CoreCommandRouter::volumioGetQueue Jul 06 16:54:49 volumio volumio[947]: info: CoreStateMachine::getQueue Jul 06 16:54:49 volumio volumio[947]: info: CorePlayQueue::getQueue Jul 06 16:54:49 volumio volumio[947]: info: CoreCommandRouter::volumioGetQueue Jul 06 16:54:49 volumio volumio[947]: info: CoreStateMachine::getQueue Jul 06 16:54:49 volumio volumio[947]: info: CorePlayQueue::getQueue Jul 06 16:54:49 volumio volumio[947]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Jul 06 16:54:49 volumio volumio[947]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Jul 06 16:54:49 volumio volumio[947]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Jul 06 16:54:49 volumio volumio[947]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Jul 06 16:54:49 volumio volumio[947]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Jul 06 16:54:49 volumio volumio[947]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Jul 06 16:54:49 volumio volumio[947]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Jul 06 16:54:49 volumio volumio[947]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Jul 06 16:54:49 volumio volumio[947]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Jul 06 16:54:49 volumio volumio[947]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Jul 06 16:54:49 volumio volumio[947]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jul 06 16:54:49 volumio volumio[947]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jul 06 16:54:50 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 06 16:54:50 volumio volumio[947]: info: Received Get System Info Jul 06 16:54:50 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 06 16:54:50 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 06 16:54:50 volumio volumio[947]: info: Discovery: Getting this device information Jul 06 16:54:50 volumio volumio[947]: info: CoreCommandRouter::volumioGetState Jul 06 16:54:50 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 06 16:54:50 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 06 16:54:50 volumio volumio[947]: info: Received Get System Info Jul 06 16:54:50 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 06 16:54:50 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 06 16:54:50 volumio volumio[947]: info: Discovery: Getting this device information Jul 06 16:54:50 volumio volumio[947]: info: CoreCommandRouter::volumioGetState Jul 06 16:54:50 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 06 16:54:51 volumio ntpd[769]: Soliciting pool server 212.113.99.6 Jul 06 16:54:54 volumio ntpd[769]: Soliciting pool server 178.208.255.95 Jul 06 16:54:54 volumio ntpd[769]: Soliciting pool server 162.159.200.1 Jul 06 16:54:55 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 06 16:54:55 volumio volumio[947]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jul 06 16:54:55 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jul 06 16:54:55 volumio volumio[947]: info: Received Get System Version Jul 06 16:54:55 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 06 16:54:55 volumio volumio[947]: info: Received Get System Info Jul 06 16:54:55 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 06 16:54:55 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 06 16:54:55 volumio volumio[947]: info: Discovery: Getting this device information Jul 06 16:54:55 volumio volumio[947]: info: CoreCommandRouter::volumioGetState Jul 06 16:54:55 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 06 16:54:55 volumio ntpd[769]: Soliciting pool server 178.217.98.201 Jul 06 16:54:56 volumio ntpd[769]: Soliciting pool server 92.255.126.11 Jul 06 16:54:56 volumio ntpd[769]: Soliciting pool server 46.148.143.150 Jul 06 16:54:56 volumio ntpd[769]: Soliciting pool server 89.109.251.22 Jul 06 16:54:57 volumio volumio[947]: info: VolumeController::SetAlsaVolume100 Jul 06 16:54:57 volumio volumio[947]: info: CoreStateMachine::pushState Jul 06 16:54:57 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 06 16:54:57 volumio volumio[947]: info: CoreCommandRouter::volumioPushState Jul 06 16:54:57 volumio volumio[947]: info: CoreCommandRouter::volumioGetQueue Jul 06 16:54:57 volumio volumio[947]: info: CoreStateMachine::getQueue Jul 06 16:54:57 volumio volumio[947]: info: CorePlayQueue::getQueue Jul 06 16:54:57 volumio volumio[947]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 06 16:54:57 volumio volumio[947]: SPOTIFY: SPOTIFY VOLUME 50 Jul 06 16:54:57 volumio volumio[947]: SPOTIFY: VOLUMIO VOLUME 100 Jul 06 16:54:57 volumio volumio[947]: SPOTIFY: DELTA VOLUME ENOUGH: true Jul 06 16:54:57 volumio volumio[947]: info: Setting Spotify Volume from Volumio: 100 Jul 06 16:54:57 volumio ntpd[769]: Soliciting pool server 51.250.110.169 Jul 06 16:54:57 volumio ntpd[769]: Soliciting pool server 89.109.251.21 Jul 06 16:54:57 volumio ntpd[769]: Soliciting pool server 82.146.53.58 Jul 06 16:54:58 volumio ntpd[769]: Soliciting pool server 51.250.53.172 Jul 06 16:54:58 volumio ntpd[769]: Soliciting pool server 92.255.126.12 Jul 06 16:54:58 volumio ntpd[769]: Soliciting pool server 94.100.180.133 Jul 06 16:54:58 volumio volumio[947]: SPOTIFY: SETTING SPOTIFY VOLUME 100 Jul 06 16:54:58 volumio volumio[947]: info: Sending Spotify command with payload to local API: /player/volume Jul 06 16:55:00 volumio ntpd[769]: Soliciting pool server 91.206.16.3 Jul 06 16:55:00 volumio ntpd[769]: Soliciting pool server 45.141.102.99 Jul 06 16:55:01 volumio ntpd[769]: Soliciting pool server 2a00:18c0:1:1::5 Jul 06 16:55:01 volumio ntpd[769]: receive: Unexpected origin timestamp 0xec14feb3.41421b4b does not match aorg 0xec14feb5.4141a49f from server@178.217.98.201 xmt 0xec14feb4.06ed81f6 Jul 06 16:55:01 volumio ntpd[769]: receive: Unexpected origin timestamp 0xec14feb3.4144390b does not match aorg 0xec14feb5.4143458a from server@212.113.99.6 xmt 0xec14feb4.0bb0294d Jul 06 16:55:01 volumio ntpd[769]: receive: Unexpected origin timestamp 0xec14feb3.414367bf does not match aorg 0xec14feb5.41427b67 from server@178.208.255.95 xmt 0xec14feb4.229850b6 Jul 06 16:55:06 volumio ntpd[769]: receive: Unexpected origin timestamp 0xec14feb9.414598ab does not match aorg 0000000000.00000000 from server@178.208.255.95 xmt 0xec14feb9.a7eacf0e Jul 06 16:55:06 volumio ntpd[769]: receive: Unexpected origin timestamp 0xec14feba.413cac7f does not match aorg 0000000000.00000000 from server@45.141.102.99 xmt 0xec14febb.05ffa4f6 Jul 06 16:55:07 volumio ntpd[769]: receive: Unexpected origin timestamp 0xec14feba.41408dd3 does not match aorg 0000000000.00000000 from server@51.250.110.169 xmt 0xec14febb.06e327aa Jul 06 16:55:07 volumio ntpd[769]: receive: Unexpected origin timestamp 0xec14feba.413f8d15 does not match aorg 0000000000.00000000 from server@51.250.53.172 xmt 0xec14febb.06c891c8 Jul 06 16:55:07 volumio ntpd[769]: receive: Unexpected origin timestamp 0xec14feba.414242bc does not match aorg 0000000000.00000000 from server@162.159.200.1 xmt 0xec14febb.07efd692 Jul 06 16:55:07 volumio ntpd[769]: receive: Unexpected origin timestamp 0xec14feba.41416a4e does not match aorg 0000000000.00000000 from server@89.109.251.22 xmt 0xec14febb.0ada103f Jul 06 16:55:07 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 06 16:55:07 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 06 16:55:07 volumio volumio[947]: info: Discovery: Getting this device information Jul 06 16:55:07 volumio volumio[947]: info: CoreCommandRouter::volumioGetState Jul 06 16:55:07 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 06 16:55:13 volumio volumio[947]: info: CoreCommandRouter::volumioGetQueue Jul 06 16:55:13 volumio volumio[947]: info: CoreStateMachine::getQueue Jul 06 16:55:13 volumio volumio[947]: info: CorePlayQueue::getQueue Jul 06 16:55:17 volumio volumio[947]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 06 16:55:17 volumio volumio[947]: Error: read ECONNRESET Jul 06 16:55:17 volumio volumio[947]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:209:20) { Jul 06 16:55:17 volumio volumio[947]: errno: -104, Jul 06 16:55:17 volumio volumio[947]: code: 'ECONNRESET', Jul 06 16:55:17 volumio volumio[947]: syscall: 'read' Jul 06 16:55:17 volumio volumio[947]: } Jul 06 16:55:17 volumio volumio[947]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 06 16:55:17 volumio sudo[2075]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-06 16:54 Jul 06 16:55:17 volumio sudo[2075]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="811ff8312d42f9da0260be1d353081a805678820" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 02 Jul 2025 04:07:25 PM CEST" VOLUMIO_VERSION="3.819" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3b9115b1943141a9d7186aa434f8f3c2"