Mar 28 14:32:20 volumio ntpd[1220]: CLOCK: time stepped by 116.285145 Mar 28 14:32:20 volumio ntpd[1220]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Mar 28 14:32:20 volumio squeezeboxserver[2171]: [14:32:20.510] mDNSsearchCallback:688 [0x195a88]: changed ip:port 172.17.0.1:5000 Mar 28 14:32:20 volumio volumio[1323]: info: Discovery: Browse raised the following error Error: dns service error: unknown Mar 28 14:32:20 volumio volumio[1323]: info: Discovery: Restarting Browsing Mar 28 14:32:20 volumio dhcpcd[1046]: veth2bc510d: using IPv4LL address 169.254.203.203 Mar 28 14:32:20 volumio avahi-daemon[952]: Joining mDNS multicast group on interface veth2bc510d.IPv4 with address 169.254.203.203. Mar 28 14:32:20 volumio avahi-daemon[952]: New relevant interface veth2bc510d.IPv4 for mDNS. Mar 28 14:32:20 volumio avahi-daemon[952]: Registering new address record for 169.254.203.203 on veth2bc510d.IPv4. Mar 28 14:32:20 volumio dhcpcd[1046]: veth2bc510d: adding route to 169.254.0.0/16 Mar 28 14:32:20 volumio systemd[1]: Reached target ip-changed@veth2bc510d.target - IP Address changed on veth2bc510d. Mar 28 14:32:20 volumio dhcpcd[1046]: veth17fc846: using IPv4LL address 169.254.9.17 Mar 28 14:32:20 volumio avahi-daemon[952]: Joining mDNS multicast group on interface veth17fc846.IPv4 with address 169.254.9.17. Mar 28 14:32:20 volumio avahi-daemon[952]: New relevant interface veth17fc846.IPv4 for mDNS. Mar 28 14:32:20 volumio avahi-daemon[952]: Registering new address record for 169.254.9.17 on veth17fc846.IPv4. Mar 28 14:32:20 volumio dhcpcd[1046]: veth17fc846: adding route to 169.254.0.0/16 Mar 28 14:32:20 volumio systemd[1]: Reached target ip-changed@veth17fc846.target - IP Address changed on veth17fc846. Mar 28 14:32:20 volumio volumio[1323]: error: [now-playing] Caught error in callback of WeatherAPI.#getFetchPromise(): Could not obtain API key Mar 28 14:32:20 volumio volumio[1323]: error: [now-playing] API endpoint weather/fetchInfo returned error: Error: Could not fetch weather: Could not obtain API key Mar 28 14:32:20 volumio volumio[1323]: at WeatherAPI.fetchInfo (/data/plugins/user_interface/now_playing/dist/lib/api/WeatherAPI.js:103:19) Mar 28 14:32:20 volumio volumio[1323]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Mar 28 14:32:20 volumio volumio[1323]: at async Object.api (/data/plugins/user_interface/now_playing/dist/app/Handler.js:116:28) Mar 28 14:32:21 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Mar 28 14:32:21 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 28 14:32:21 volumio volumio[1323]: info: Received Get System Info Mar 28 14:32:21 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 28 14:32:21 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 28 14:32:21 volumio volumio[1323]: info: Discovery: Getting this device information Mar 28 14:32:21 volumio volumio[1323]: info: CoreCommandRouter::volumioGetState Mar 28 14:32:21 volumio volumio[1323]: info: CorePlayQueue::getTrack 0 Mar 28 14:32:21 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 28 14:32:21 volumio volumio[1323]: info: Discovery: this is already registered, 5013bc9d-1f50-4699-a87f-dd417258ecca Mar 28 14:32:21 volumio volumio[1323]: info: Discovery: Found device Volumio Mar 28 14:32:21 volumio volumio[1323]: info: CoreCommandRouter::volumioGetState Mar 28 14:32:21 volumio volumio[1323]: info: CorePlayQueue::getTrack 0 Mar 28 14:32:21 volumio volumio[1323]: info: Discovery: this is already registered, 5013bc9d-1f50-4699-a87f-dd417258ecca Mar 28 14:32:21 volumio volumio[1323]: info: Discovery: Found device Volumio Mar 28 14:32:21 volumio volumio[1323]: info: CoreCommandRouter::volumioGetState Mar 28 14:32:21 volumio volumio[1323]: info: CorePlayQueue::getTrack 0 Mar 28 14:32:21 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Mar 28 14:32:22 volumio ntpd[1220]: IO: Listen normally on 5 veth17fc846 169.254.9.17:123 Mar 28 14:32:22 volumio ntpd[1220]: IO: Listen normally on 6 veth6d35c41 169.254.73.29:123 Mar 28 14:32:22 volumio ntpd[1220]: IO: Listen normally on 7 veth2bc510d 169.254.203.203:123 Mar 28 14:32:22 volumio ntpd[1220]: PROTO: 158.247.239.209 unlink local addr 172.18.0.1 -> 192.168.219.105 Mar 28 14:32:22 volumio ntpd[1220]: PROTO: 121.174.142.81 unlink local addr 172.18.0.1 -> 192.168.219.105 Mar 28 14:32:22 volumio ntpd[1220]: PROTO: 203.32.26.46 unlink local addr 172.18.0.1 -> 192.168.219.105 Mar 28 14:32:22 volumio ntpd[1220]: IO: new interface(s) found: waking up resolver Mar 28 14:32:22 volumio ntpd[1220]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Mar 28 14:32:22 volumio ntpd[1220]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Mar 28 14:32:22 volumio ntpd[1220]: DNS: Pool skipping: 203.32.26.46 Mar 28 14:32:22 volumio ntpd[1220]: DNS: Pool skipping: 121.174.142.82 Mar 28 14:32:22 volumio ntpd[1220]: DNS: Pool skipping: 121.174.142.81 Mar 28 14:32:22 volumio ntpd[1220]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 Mar 28 14:32:22 volumio volumio[1323]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=4.119&uuid=8bb605e3ec1dd29462b3332e20266be3" http://updates.volumio.org/downloader-v1/track-device Mar 28 14:32:22 volumio volumio[1323]: % Total % Received % Xferd Average Speed Time Time Time Current Mar 28 14:32:22 volumio volumio[1323]: Dload Upload Total Spent Left Speed Mar 28 14:32:22 volumio volumio[1323]: [132B blob data] Mar 28 14:32:22 volumio volumio[1323]: retrying in 5 seconds, trial 0 Mar 28 14:32:22 volumio volumio[1323]: info: Volumio Calling Home Mar 28 14:32:23 volumio volumio[1323]: info: Volumio called home Mar 28 14:32:23 volumio ntpd[1220]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Mar 28 14:32:23 volumio ntpd[1220]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Mar 28 14:32:23 volumio ntpd[1220]: DNS: Pool skipping: 211.108.117.211 Mar 28 14:32:23 volumio ntpd[1220]: DNS: Pool skipping: 121.174.142.82 Mar 28 14:32:23 volumio ntpd[1220]: DNS: Pool skipping: 121.134.215.104 Mar 28 14:32:23 volumio ntpd[1220]: DNS: Pool skipping: 240b:400d:3:3300:aeda:71da:9779:d8f1 Mar 28 14:32:23 volumio ntpd[1220]: DNS: Pool skipping: 2401:c080:1c00:24a1:5400:5ff:fe04:720 Mar 28 14:32:23 volumio ntpd[1220]: DNS: Pool skipping: 2001:678:8::123 Mar 28 14:32:23 volumio ntpd[1220]: DNS: Pool skipping: 240b:400d:3:3300:aeda:71da:9779:d4f1 Mar 28 14:32:23 volumio ntpd[1220]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 Mar 28 14:32:23 volumio volumio[1323]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Mar 28 14:32:23 volumio volumio[1323]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Mar 28 14:32:23 volumio volumio[1323]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Mar 28 14:32:23 volumio volumio[1323]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Mar 28 14:32:23 volumio volumio[1323]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Mar 28 14:32:23 volumio volumio[1323]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Mar 28 14:32:23 volumio volumio[1323]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Mar 28 14:32:23 volumio volumio[1323]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Mar 28 14:32:23 volumio volumio[1323]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Mar 28 14:32:23 volumio volumio[1323]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Mar 28 14:32:23 volumio volumio[1323]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Mar 28 14:32:23 volumio volumio[1323]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Mar 28 14:32:23 volumio volumio[1323]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Mar 28 14:32:23 volumio volumio[1323]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Mar 28 14:32:23 volumio volumio[1323]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Mar 28 14:32:23 volumio volumio[1323]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Mar 28 14:32:23 volumio volumio[1323]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Mar 28 14:32:23 volumio volumio[1323]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Mar 28 14:32:23 volumio volumio[1323]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Mar 28 14:32:23 volumio volumio[1323]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Mar 28 14:32:23 volumio volumio[1323]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Mar 28 14:32:23 volumio volumio[1323]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Mar 28 14:32:23 volumio volumio[1323]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Mar 28 14:32:23 volumio volumio[1323]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Mar 28 14:32:23 volumio volumio[1323]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Mar 28 14:32:23 volumio volumio[1323]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Mar 28 14:32:23 volumio volumio[1323]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Mar 28 14:32:23 volumio volumio[1323]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Mar 28 14:32:23 volumio volumio[1323]: info: Adding plugin bluetooth to MyMusic Plugins Mar 28 14:32:23 volumio volumio[1323]: info: Adding plugin multiroom to MyMusic Plugins Mar 28 14:32:23 volumio volumio[1323]: info: Adding plugin metavolumio to MyMusic Plugins Mar 28 14:32:23 volumio volumio[1323]: info: Adding plugin cd_controller to MyMusic Plugins Mar 28 14:32:23 volumio volumio[1323]: info: Adding plugin qobuzconnect to MyMusic Plugins Mar 28 14:32:23 volumio volumio[1323]: info: Adding plugin smart_inputs to MyMusic Plugins Mar 28 14:32:23 volumio volumio[1323]: info: Adding plugin tidalconnect to MyMusic Plugins Mar 28 14:32:23 volumio volumio[1323]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Mar 28 14:32:23 volumio volumio[1323]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Mar 28 14:32:23 volumio volumio[1323]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Mar 28 14:32:23 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 14:32:23 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 14:32:23 volumio volumio[1323]: info: Starting MyVolumio Remote Streaming Endpoints Mar 28 14:32:23 volumio volumio[1323]: info: MyVolumio login type: Token Mar 28 14:32:23 volumio volumio[1323]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Mar 28 14:32:23 volumio volumio[1323]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Mar 28 14:32:24 volumio ntpd[1220]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Mar 28 14:32:24 volumio ntpd[1220]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Mar 28 14:32:24 volumio ntpd[1220]: DNS: Pool skipping: 221.151.118.78 Mar 28 14:32:24 volumio ntpd[1220]: DNS: Pool skipping: 175.210.18.47 Mar 28 14:32:24 volumio ntpd[1220]: DNS: Pool skipping: 175.195.167.194 Mar 28 14:32:24 volumio ntpd[1220]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 Mar 28 14:32:24 volumio volumio[1323]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Mar 28 14:32:24 volumio volumio[1323]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Mar 28 14:32:24 volumio volumio[1323]: info: Streaming services startup Mar 28 14:32:24 volumio volumio[1323]: info: Starting Streaming Daemon Mar 28 14:32:24 volumio sudo[3671]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 28 14:32:24 volumio sudo[3671]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 14:32:24 volumio sudo[3671]: pam_unix(sudo:session): session closed for user root Mar 28 14:32:24 volumio volumio[1323]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Mar 28 14:32:24 volumio volumio[1323]: error: Cannot start Volumio Streaming Daemon Mar 28 14:32:24 volumio volumio[1323]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 28 14:32:24 volumio volumio[1323]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 28 14:32:24 volumio upmpdcli[3677]: writing RSA key Mar 28 14:32:25 volumio volumio[1323]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Mar 28 14:32:25 volumio ntpd[1220]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Mar 28 14:32:25 volumio ntpd[1220]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Mar 28 14:32:25 volumio ntpd[1220]: DNS: Pool skipping: 203.32.26.46 Mar 28 14:32:25 volumio ntpd[1220]: DNS: Pool skipping: 121.174.142.81 Mar 28 14:32:25 volumio ntpd[1220]: DNS: Pool skipping: 158.247.239.209 Mar 28 14:32:25 volumio ntpd[1220]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 Mar 28 14:32:25 volumio volumio[1323]: info: Discovery: this is already registered, 5013bc9d-1f50-4699-a87f-dd417258ecca Mar 28 14:32:25 volumio volumio[1323]: info: Discovery: Found device Volumio Mar 28 14:32:25 volumio volumio[1323]: info: CoreCommandRouter::volumioGetState Mar 28 14:32:25 volumio volumio[1323]: info: CorePlayQueue::getTrack 0 Mar 28 14:32:25 volumio volumio[1323]: info: Discovery: this is already registered, 5013bc9d-1f50-4699-a87f-dd417258ecca Mar 28 14:32:25 volumio volumio[1323]: info: Discovery: Found device Volumio Mar 28 14:32:25 volumio volumio[1323]: info: CoreCommandRouter::volumioGetState Mar 28 14:32:25 volumio volumio[1323]: info: CorePlayQueue::getTrack 0 Mar 28 14:32:25 volumio squeezeboxserver[2171]: [14:32:25.638] mDNSsearchCallback:688 [0x195a88]: changed ip:port 172.18.0.1:5000 Mar 28 14:32:25 volumio squeezeboxserver[2171]: [14:32:25.639] mDNSsearchCallback:688 [0x195a88]: changed ip:port 172.17.0.1:5000 Mar 28 14:32:25 volumio squeezeboxserver[2171]: [14:32:25.643] mDNSsearchCallback:688 [0x195a88]: changed ip:port 192.168.219.105:5000 Mar 28 14:32:25 volumio volumio[1323]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 28 14:32:25 volumio volumio[1323]: info: Discovery: Restarting Browsing Mar 28 14:32:25 volumio volumio[1323]: info: Discovery: this is already registered, 5013bc9d-1f50-4699-a87f-dd417258ecca Mar 28 14:32:25 volumio volumio[1323]: info: Discovery: Found device Volumio Mar 28 14:32:25 volumio volumio[1323]: info: CoreCommandRouter::volumioGetState Mar 28 14:32:25 volumio volumio[1323]: info: CorePlayQueue::getTrack 0 Mar 28 14:32:25 volumio squeezeboxserver[2171]: [14:32:25.651] mDNSsearchCallback:688 [0x195a88]: changed ip:port 127.0.0.1:5000 Mar 28 14:32:25 volumio volumio[1323]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 28 14:32:25 volumio volumio[1323]: info: Discovery: Restarting Browsing Mar 28 14:32:25 volumio volumio[1323]: info: Discovery: Restart already pending, ignoring duplicate call Mar 28 14:32:25 volumio volumio[1323]: info: Discovery: this is already registered, 5013bc9d-1f50-4699-a87f-dd417258ecca Mar 28 14:32:25 volumio volumio[1323]: info: Discovery: Found device Volumio Mar 28 14:32:25 volumio volumio[1323]: info: CoreCommandRouter::volumioGetState Mar 28 14:32:25 volumio volumio[1323]: info: CorePlayQueue::getTrack 0 Mar 28 14:32:25 volumio volumio[1323]: info: Discovery: this is already registered, 5013bc9d-1f50-4699-a87f-dd417258ecca Mar 28 14:32:25 volumio volumio[1323]: info: Discovery: Found device Volumio Mar 28 14:32:25 volumio volumio[1323]: info: CoreCommandRouter::volumioGetState Mar 28 14:32:25 volumio volumio[1323]: info: CorePlayQueue::getTrack 0 Mar 28 14:32:26 volumio volumio[1323]: info: MyVolumio token set successfully Mar 28 14:32:26 volumio volumio[1323]: info: MYVOLUMIO: Adding device Mar 28 14:32:26 volumio volumio[1323]: info: MYVOLUMIO: Evaluating Server Mar 28 14:32:27 volumio squeezeboxserver[2171]: [14:32:27.033] mDNSsearchCallback:688 [0x195a88]: changed ip:port 192.168.219.105:5000 Mar 28 14:32:27 volumio squeezeboxserver[2171]: [14:32:27.033] mDNSsearchCallback:688 [0x195a88]: changed ip:port 172.17.0.1:5000 Mar 28 14:32:27 volumio squeezeboxserver[2171]: [14:32:27.033] mDNSsearchCallback:688 [0x195a88]: changed ip:port 172.18.0.1:5000 Mar 28 14:32:27 volumio volumio[1323]: info: MyVolumio status changed Mar 28 14:32:27 volumio volumio[1323]: info: Streaming services startup Mar 28 14:32:27 volumio volumio[1323]: info: Starting Streaming Daemon Mar 28 14:32:27 volumio volumio[1323]: info: Removing browser output: myVolumio user plan is not superstar Mar 28 14:32:27 volumio volumio[1323]: info: Removing audio output: Mar 28 14:32:27 volumio volumio[1323]: info: Stoppping Tunnel 1 Mar 28 14:32:27 volumio sudo[3731]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 28 14:32:27 volumio sudo[3731]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 14:32:27 volumio sudo[3733]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Mar 28 14:32:27 volumio sudo[3733]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 14:32:27 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Mar 28 14:32:27 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Mar 28 14:32:27 volumio sudo[3731]: pam_unix(sudo:session): session closed for user root Mar 28 14:32:27 volumio volumio[1323]: error: Cannot start Volumio Streaming Daemon Mar 28 14:32:27 volumio volumio[1323]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 28 14:32:27 volumio volumio[1323]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 28 14:32:27 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Mar 28 14:32:27 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Mar 28 14:32:27 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Mar 28 14:32:27 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Mar 28 14:32:27 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Mar 28 14:32:27 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Mar 28 14:32:27 volumio sudo[3733]: pam_unix(sudo:session): session closed for user root Mar 28 14:32:27 volumio volumio[1323]: info: Remote SSH Stopped Mar 28 14:32:27 volumio volumio[1323]: info: Setting Geolocation for MyVolumio to as1 Mar 28 14:32:27 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 14:32:27 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 14:32:27 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 14:32:27 volumio volumio[1323]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Mar 28 14:32:28 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 28 14:32:28 volumio volumio[1323]: info: Updating MyVolumio device info Mar 28 14:32:28 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 14:32:28 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 14:32:28 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 14:32:28 volumio volumio[1323]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Mar 28 14:32:29 volumio squeezeboxserver[2171]: [14:32:29.107] mDNSsearchCallback:688 [0x195a88]: changed ip:port 127.0.0.1:5000 Mar 28 14:32:29 volumio squeezeboxserver[2171]: [14:32:29.107] mDNSsearchCallback:688 [0x195a88]: changed ip:port 192.168.219.105:5000 Mar 28 14:32:29 volumio squeezeboxserver[2171]: [14:32:29.107] mDNSsearchCallback:688 [0x195a88]: changed ip:port 172.17.0.1:5000 Mar 28 14:32:29 volumio squeezeboxserver[2171]: [14:32:29.107] mDNSsearchCallback:688 [0x195a88]: changed ip:port 172.18.0.1:5000 Mar 28 14:32:30 volumio volumio[1323]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 28 14:32:30 volumio volumio[1323]: info: Discovery: Restarting Browsing Mar 28 14:32:30 volumio volumio[1323]: info: Discovery: this is already registered, 5013bc9d-1f50-4699-a87f-dd417258ecca Mar 28 14:32:30 volumio volumio[1323]: info: Discovery: Found device Volumio Mar 28 14:32:30 volumio volumio[1323]: info: CoreCommandRouter::volumioGetState Mar 28 14:32:30 volumio volumio[1323]: info: CorePlayQueue::getTrack 0 Mar 28 14:32:30 volumio volumio[1323]: info: Discovery: this is already registered, 5013bc9d-1f50-4699-a87f-dd417258ecca Mar 28 14:32:30 volumio volumio[1323]: info: Discovery: Found device Volumio Mar 28 14:32:30 volumio volumio[1323]: info: CoreCommandRouter::volumioGetState Mar 28 14:32:30 volumio volumio[1323]: info: CorePlayQueue::getTrack 0 Mar 28 14:32:30 volumio volumio[1323]: info: Discovery: this is already registered, 5013bc9d-1f50-4699-a87f-dd417258ecca Mar 28 14:32:30 volumio volumio[1323]: info: Discovery: Found device Volumio Mar 28 14:32:30 volumio volumio[1323]: info: CoreCommandRouter::volumioGetState Mar 28 14:32:30 volumio volumio[1323]: info: CorePlayQueue::getTrack 0 Mar 28 14:32:30 volumio volumio[1323]: info: Discovery: this is already registered, 5013bc9d-1f50-4699-a87f-dd417258ecca Mar 28 14:32:30 volumio volumio[1323]: info: Discovery: Found device Volumio Mar 28 14:32:30 volumio volumio[1323]: info: CoreCommandRouter::volumioGetState Mar 28 14:32:30 volumio volumio[1323]: info: CorePlayQueue::getTrack 0 Mar 28 14:32:30 volumio volumio[1323]: info: Discovery: this is already registered, 5013bc9d-1f50-4699-a87f-dd417258ecca Mar 28 14:32:30 volumio volumio[1323]: info: Discovery: Found device Volumio Mar 28 14:32:30 volumio volumio[1323]: info: CoreCommandRouter::volumioGetState Mar 28 14:32:30 volumio volumio[1323]: info: CorePlayQueue::getTrack 0 Mar 28 14:32:30 volumio volumio[1323]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 28 14:32:30 volumio volumio[1323]: info: Discovery: Restarting Browsing Mar 28 14:32:30 volumio volumio[1323]: info: Discovery: Restart already pending, ignoring duplicate call Mar 28 14:32:31 volumio volumio[1323]: info: MYVOLUMIO: Adding device Mar 28 14:32:31 volumio volumio[1323]: info: MYVOLUMIO: Evaluating Server Mar 28 14:32:32 volumio volumio[1323]: info: Setting Geolocation for MyVolumio to as1 Mar 28 14:32:32 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 14:32:32 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 14:32:32 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 14:32:32 volumio systemd[1]: systemd-fsckd.service: Deactivated successfully. Mar 28 14:32:32 volumio volumio[1323]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Mar 28 14:32:33 volumio volumio[1323]: info: Updating MyVolumio device info Mar 28 14:32:33 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 14:32:33 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 14:32:33 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 14:32:33 volumio systemd[1]: systemd-hostnamed.service: Deactivated successfully. Mar 28 14:32:33 volumio volumio[1323]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Mar 28 14:32:35 volumio volumio[1323]: info: Discovery: this is already registered, 5013bc9d-1f50-4699-a87f-dd417258ecca Mar 28 14:32:35 volumio volumio[1323]: info: Discovery: Found device Volumio Mar 28 14:32:35 volumio volumio[1323]: info: CoreCommandRouter::volumioGetState Mar 28 14:32:35 volumio volumio[1323]: info: CorePlayQueue::getTrack 0 Mar 28 14:32:35 volumio volumio[1323]: info: Discovery: this is already registered, 5013bc9d-1f50-4699-a87f-dd417258ecca Mar 28 14:32:35 volumio volumio[1323]: info: Discovery: Found device Volumio Mar 28 14:32:35 volumio volumio[1323]: info: CoreCommandRouter::volumioGetState Mar 28 14:32:35 volumio volumio[1323]: info: CorePlayQueue::getTrack 0 Mar 28 14:32:35 volumio volumio[1323]: info: Discovery: this is already registered, 5013bc9d-1f50-4699-a87f-dd417258ecca Mar 28 14:32:35 volumio volumio[1323]: info: Discovery: Found device Volumio Mar 28 14:32:35 volumio volumio[1323]: info: CoreCommandRouter::volumioGetState Mar 28 14:32:35 volumio volumio[1323]: info: CorePlayQueue::getTrack 0 Mar 28 14:32:35 volumio volumio[1323]: info: Discovery: this is already registered, 5013bc9d-1f50-4699-a87f-dd417258ecca Mar 28 14:32:35 volumio volumio[1323]: info: Discovery: Found device Volumio Mar 28 14:32:35 volumio volumio[1323]: info: CoreCommandRouter::volumioGetState Mar 28 14:32:35 volumio volumio[1323]: info: CorePlayQueue::getTrack 0 Mar 28 14:32:35 volumio volumio[1323]: info: Discovery: this is already registered, 5013bc9d-1f50-4699-a87f-dd417258ecca Mar 28 14:32:35 volumio volumio[1323]: info: Discovery: Found device Volumio Mar 28 14:32:35 volumio volumio[1323]: info: CoreCommandRouter::volumioGetState Mar 28 14:32:35 volumio volumio[1323]: info: CorePlayQueue::getTrack 0 Mar 28 14:32:35 volumio volumio[1323]: info: Discovery: this is already registered, 5013bc9d-1f50-4699-a87f-dd417258ecca Mar 28 14:32:35 volumio volumio[1323]: info: Discovery: Found device Volumio Mar 28 14:32:35 volumio volumio[1323]: info: CoreCommandRouter::volumioGetState Mar 28 14:32:35 volumio volumio[1323]: info: CorePlayQueue::getTrack 0 Mar 28 14:32:35 volumio volumio[1323]: info: Discovery: this is already registered, 5013bc9d-1f50-4699-a87f-dd417258ecca Mar 28 14:32:35 volumio volumio[1323]: info: Discovery: Found device Volumio Mar 28 14:32:35 volumio volumio[1323]: info: CoreCommandRouter::volumioGetState Mar 28 14:32:35 volumio volumio[1323]: info: CorePlayQueue::getTrack 0 Mar 28 14:32:36 volumio squeezeboxserver[2171]: [14:32:36.703] mDNSsearchCallback:688 [0x195a88]: changed ip:port 192.168.219.105:5000 Mar 28 14:32:37 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 28 14:32:37 volumio volumio[1323]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 28 14:32:37 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 28 14:32:37 volumio volumio[1323]: info: Received Get System Version Mar 28 14:32:37 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 28 14:32:37 volumio volumio[1323]: info: Received Get System Info Mar 28 14:32:37 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 28 14:32:37 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 28 14:32:37 volumio volumio[1323]: info: Discovery: Getting this device information Mar 28 14:32:37 volumio volumio[1323]: info: CoreCommandRouter::volumioGetState Mar 28 14:32:37 volumio volumio[1323]: info: CorePlayQueue::getTrack 0 Mar 28 14:32:37 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 28 14:32:37 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 28 14:32:37 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 28 14:32:38 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 28 14:32:38 volumio volumio[1323]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Mar 28 14:32:38 volumio volumio[1323]: info: CoreCommandRouter::volumioGetState Mar 28 14:32:38 volumio volumio[1323]: info: CorePlayQueue::getTrack 0 Mar 28 14:32:41 volumio volumio[1323]: info: Enabling plugin carrot_radio Mar 28 14:32:41 volumio volumio[1323]: info: Loading plugin "carrot_radio"... Mar 28 14:32:41 volumio volumio[1323]: info: ControllerCarrotRadio::constructor Mar 28 14:32:41 volumio volumio[1323]: info: PLUGIN START: carrot_radio Mar 28 14:32:41 volumio volumio[1323]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 28 14:32:41 volumio volumio[1323]: info: [1774675961974] CoreMusicLibrary::Adding element 스트리밍 라디오 Mar 28 14:32:41 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 28 14:32:41 volumio volumio[1323]: Cannot find translation for source 스트리밍 라디오 Mar 28 14:32:41 volumio volumio[1323]: info: Done. Mar 28 14:32:42 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 28 14:32:42 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 14:32:42 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 28 14:32:42 volumio volumio-remote-updater[969]: Test mode disabled Mar 28 14:32:42 volumio volumio-remote-updater[969]: Alpha mode disabled Mar 28 14:32:42 volumio volumio-remote-updater[969]: Alpha legacy test mode disabled Mar 28 14:32:42 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Mar 28 14:32:43 volumio volumio[1323]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Mar 28 14:32:43 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 28 14:32:43 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 14:32:43 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 14:32:43 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Mar 28 14:32:43 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 28 14:32:43 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Mar 28 14:32:43 volumio volumio[1323]: info: CoreCommandRouter::volumioGetBrowseSources Mar 28 14:32:43 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 28 14:32:44 volumio volumio[1323]: error: MyVolumio Plugin failed to authenticate in a timely fashion Mar 28 14:32:44 volumio volumio[1323]: info: Completed starting MyVolumio Plugin Mar 28 14:32:44 volumio volumio[1323]: [Metrics] CommandRouter: 37s 399.33ms Mar 28 14:32:44 volumio volumio[1323]: info: CoreCommandRouter::volumiosetStartupVolume Mar 28 14:32:44 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 14:32:44 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 14:32:44 volumio volumio[1323]: info: CoreCommandRouter::Close All Modals sent Mar 28 14:32:44 volumio volumio[1323]: info: CoreCommandRouter::Close All Modals sent Mar 28 14:32:44 volumio volumio[1323]: info: Enabling plugin peppy_screensaver Mar 28 14:32:44 volumio volumio[1323]: info: Loading plugin "peppy_screensaver"... Mar 28 14:32:44 volumio volumio[1323]: info: Applying required configuration parameters for plugin peppy_screensaver Mar 28 14:32:44 volumio volumio[1323]: info: Preparing to generate the ALSA configuration file Mar 28 14:32:44 volumio volumio[1323]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Mar 28 14:32:44 volumio volumio[1323]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Mar 28 14:32:44 volumio volumio[1323]: info: Reading ALSA contributions from plugins. Mar 28 14:32:44 volumio volumio[1323]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Mar 28 14:32:44 volumio volumio[1323]: info: Asound.conf file written Mar 28 14:32:44 volumio sudo[3816]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Mar 28 14:32:44 volumio sudo[3816]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 14:32:44 volumio sudo[3816]: pam_unix(sudo:session): session closed for user root Mar 28 14:32:44 volumio volumio[1323]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Mar 28 14:32:44 volumio volumio[1323]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Mar 28 14:32:44 volumio volumio[1323]: info: Output device has changed, restarting MPD Mar 28 14:32:44 volumio volumio[1323]: info: Output device has changed, restarting Shairport Sync Mar 28 14:32:44 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 14:32:44 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 14:32:44 volumio sudo[3822]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 28 14:32:44 volumio sudo[3822]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 14:32:44 volumio sudo[3822]: pam_unix(sudo:session): session closed for user root Mar 28 14:32:44 volumio volumio[1323]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 28 14:32:44 volumio volumio[1323]: info: PLUGIN START: peppy_screensaver Mar 28 14:32:44 volumio sudo[3826]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 28 14:32:44 volumio sudo[3826]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 14:32:44 volumio volumio[1323]: info: Loading i18n strings for locale ko Mar 28 14:32:44 volumio volumio[1323]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Mar 28 14:32:44 volumio volumio[1323]: info: /tmp/myfifo created Mar 28 14:32:44 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Mar 28 14:32:44 volumio volumio[1323]: info: /tmp/myfifosa created Mar 28 14:32:44 volumio sudo[3842]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd-dummy index=7 pcm_substreams=1 fake_buffer=0 Mar 28 14:32:44 volumio sudo[3842]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 14:32:44 volumio sudo[3842]: pam_unix(sudo:session): session closed for user root Mar 28 14:32:44 volumio volumio[1323]: info: snd-dummy loaded Mar 28 14:32:44 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 14:32:44 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 14:32:44 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startShairportSync Mar 28 14:32:44 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 14:32:44 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 14:32:44 volumio volumio[1323]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 28 14:32:44 volumio volumio[1323]: info: Adding peppy_screensaver REST Endpoint for plugin: user_interface/peppy_screensaver Mar 28 14:32:44 volumio volumio[1323]: info: peppy_screensaver: REST endpoint registered: peppy_screensaver Mar 28 14:32:44 volumio volumio[1323]: info: Adding peppy_screensaver_font REST Endpoint for plugin: user_interface/peppy_screensaver Mar 28 14:32:44 volumio volumio[1323]: info: peppy_screensaver: REST endpoint registered: peppy_screensaver_font Mar 28 14:32:44 volumio volumio[1323]: info: Adding peppy_screensaver_vinyl REST Endpoint for plugin: user_interface/peppy_screensaver Mar 28 14:32:44 volumio volumio[1323]: info: peppy_screensaver: REST endpoint registered: peppy_screensaver_vinyl Mar 28 14:32:44 volumio volumio[1323]: info: peppy_screensaver: Config version updated: 1f1225e7 Mar 28 14:32:44 volumio volumio[1323]: info: Done. Mar 28 14:32:44 volumio volumio[1323]: info: CoreCommandRouter::volumioGetState Mar 28 14:32:44 volumio volumio[1323]: info: CorePlayQueue::getTrack 0 Mar 28 14:32:44 volumio volumio[1323]: info: MPD Permissions set Mar 28 14:32:44 volumio volumio[1323]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false Mar 28 14:32:44 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 14:32:44 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 14:32:44 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 14:32:44 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 14:32:44 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 14:32:44 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 14:32:44 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 14:32:44 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 14:32:44 volumio sudo[3883]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount --bind /tmp/shairport-sync.conf.tmpl /volumio/app/plugins/music_service/airplay_emulation/shairport-sync.conf.tmpl Mar 28 14:32:44 volumio sudo[3883]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 14:32:44 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 14:32:44 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 14:32:44 volumio volumio[1323]: info: Starting Shairport Sync Mar 28 14:32:44 volumio sudo[3883]: pam_unix(sudo:session): session closed for user root Mar 28 14:32:44 volumio volumio[1323]: info: Starting Shairport Sync Mar 28 14:32:44 volumio sudo[3892]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 28 14:32:44 volumio sudo[3892]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 14:32:44 volumio sudo[3893]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 28 14:32:44 volumio sudo[3893]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 14:32:44 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Mar 28 14:32:44 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Mar 28 14:32:44 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 28 14:32:44 volumio systemd[1]: shairport-sync.service: Consumed 1.548s CPU time. Mar 28 14:32:44 volumio squeezeboxserver[2171]: [14:32:44.835] mDNSsearchCallback:679 [0x195a88]: removing renderer (volumio) Mar 28 14:32:44 volumio squeezeboxserver[2171]: [14:32:44.835] slimproto_close:906 [0x13c288] slimproto stop for volumio Mar 28 14:32:44 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 28 14:32:44 volumio systemd[1]: mpd.service: Deactivated successfully. Mar 28 14:32:44 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Mar 28 14:32:44 volumio systemd[1]: mpd.service: Consumed 1.256s CPU time. Mar 28 14:32:44 volumio sudo[3893]: pam_unix(sudo:session): session closed for user root Mar 28 14:32:44 volumio sudo[3892]: pam_unix(sudo:session): session closed for user root Mar 28 14:32:44 volumio systemd[1]: mpd.socket: Deactivated successfully. Mar 28 14:32:44 volumio volumio[1323]: info: Shairport-Sync Started Mar 28 14:32:44 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Mar 28 14:32:44 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Mar 28 14:32:44 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Mar 28 14:32:44 volumio volumio[1323]: info: Shairport-Sync Started Mar 28 14:32:44 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Mar 28 14:32:44 volumio volumio[1323]: info: peppy_screensaver: ALSA template: /data/plugins/user_interface/peppy_screensaver/Peppyalsa.postPeppyalsa.5.conf.tmpl (isX64=false) Mar 28 14:32:44 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Mar 28 14:32:44 volumio volumio[1323]: info: Preparing to generate the ALSA configuration file Mar 28 14:32:44 volumio sudo[3922]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount --bind /tmp/mpd.conf.tmpl /volumio/app/plugins/music_service/mpd/mpd.conf.tmpl Mar 28 14:32:44 volumio sudo[3922]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 14:32:44 volumio volumio[1323]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Mar 28 14:32:44 volumio volumio[1323]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Mar 28 14:32:44 volumio volumio[1323]: info: Reading ALSA contributions from plugins. Mar 28 14:32:44 volumio sudo[3922]: pam_unix(sudo:session): session closed for user root Mar 28 14:32:44 volumio sudo[3900]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 28 14:32:44 volumio volumio[1323]: info: Asound.conf file unchanged, so no further update is needed Mar 28 14:32:44 volumio volumio[1323]: info: Output device has changed, restarting MPD Mar 28 14:32:44 volumio sudo[3900]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 28 14:32:44 volumio sudo[3900]: pam_unix(sudo:session): session closed for user root Mar 28 14:32:44 volumio volumio[1323]: info: Output device has changed, restarting Shairport Sync Mar 28 14:32:44 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 14:32:44 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 14:32:44 volumio sudo[3926]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 28 14:32:44 volumio sudo[3926]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 14:32:44 volumio squeezeboxserver[2171]: [14:32:44.946] DelRaopDevice:1008 [0x195a88]: Raop device stopped Mar 28 14:32:44 volumio squeezeboxserver[2171]: [14:32:44.946] AddRaopDevice:862 [0x195a88]: AirPort Express Mar 28 14:32:44 volumio squeezeboxserver[2171]: [14:32:44.946] AddRaopDevice:926 [0x195a88]: adding renderer (volumio@172.18.0.1) with mac AAAA-DF0F404D Mar 28 14:32:44 volumio squeezeboxserver[2171]: [14:32:44.946] raopcl_create:717 [0xf6c08e08]: using PCM coding Mar 28 14:32:44 volumio squeezeboxserver[2171]: [14:32:44.947] output_raop_thread_init:119 [0x13c288]: init output raop Mar 28 14:32:44 volumio squeezeboxserver[2171]: [14:32:44.947] output_init_common:290 allocating 1764000 Mar 28 14:32:44 volumio squeezeboxserver[2171]: [14:32:44.947] mDNSsearchCallback:679 [0x195a88]: removing renderer (volumio) Mar 28 14:32:44 volumio squeezeboxserver[2171]: [14:32:44.948] slimproto_close:906 [0x13c288] slimproto stop for volumio Mar 28 14:32:44 volumio squeezeboxserver[2171]: [14:32:44.948] slimproto:834 squeezelite [0x13c288] <=> player [0x195a88] Mar 28 14:32:44 volumio squeezeboxserver[2171]: [14:32:44.948] slimproto:835 [0x13c288] connecting to 192.168.219.105:3483 Mar 28 14:32:44 volumio sudo[3926]: pam_unix(sudo:session): session closed for user root Mar 28 14:32:44 volumio sudo[3931]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 28 14:32:44 volumio sudo[3931]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 14:32:44 volumio volumio[1323]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 28 14:32:44 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: mpd , createMPDFile Mar 28 14:32:44 volumio volumio[1323]: info: MPD Permissions set Mar 28 14:32:44 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 14:32:44 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 14:32:44 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 14:32:44 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 14:32:44 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 14:32:44 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 14:32:44 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 14:32:44 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 14:32:44 volumio systemd[1]: mpd.service: Deactivated successfully. Mar 28 14:32:44 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 14:32:44 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Mar 28 14:32:44 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 14:32:44 volumio systemd[1]: mpd.socket: Deactivated successfully. Mar 28 14:32:44 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Mar 28 14:32:44 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Mar 28 14:32:44 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 14:32:44 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 14:32:44 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 14:32:44 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 14:32:44 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 14:32:44 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 14:32:44 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 14:32:44 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 14:32:44 volumio sudo[3942]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 28 14:32:44 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Mar 28 14:32:44 volumio sudo[3942]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 14:32:44 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Mar 28 14:32:44 volumio sudo[3942]: pam_unix(sudo:session): session closed for user root Mar 28 14:32:44 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 14:32:44 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 14:32:44 volumio volumio[1323]: info: Starting Shairport Sync Mar 28 14:32:45 volumio volumio[1323]: info: MPD Permissions set Mar 28 14:32:45 volumio sudo[3957]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 28 14:32:45 volumio sudo[3957]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 14:32:45 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Mar 28 14:32:45 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Mar 28 14:32:45 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 28 14:32:45 volumio sudo[3952]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 28 14:32:45 volumio sudo[3952]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 28 14:32:45 volumio sudo[3952]: pam_unix(sudo:session): session closed for user root Mar 28 14:32:45 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 28 14:32:45 volumio sudo[3957]: pam_unix(sudo:session): session closed for user root Mar 28 14:32:45 volumio volumio[1323]: info: Shairport-Sync Started Mar 28 14:32:45 volumio squeezeboxserver[2171]: [14:32:45.148] DelRaopDevice:1008 [0x195a88]: Raop device stopped Mar 28 14:32:45 volumio squeezeboxserver[2171]: [14:32:45.148] mDNSsearchCallback:743 Updating configuration /var/lib/squeezeboxserver/prefs/raopbridge.xml Mar 28 14:32:45 volumio squeezeboxserver[2171]: [14:32:45.148] AddRaopDevice:862 [0x195a88]: AirPort Express Mar 28 14:32:45 volumio squeezeboxserver[2171]: [14:32:45.148] AddRaopDevice:926 [0x195a88]: adding renderer (volumio@172.17.0.1) with mac AAAA-DF0F404D Mar 28 14:32:45 volumio squeezeboxserver[2171]: [14:32:45.148] raopcl_create:717 [0xf6c08e08]: using PCM coding Mar 28 14:32:45 volumio squeezeboxserver[2171]: [14:32:45.149] output_raop_thread_init:119 [0x13c288]: init output raop Mar 28 14:32:45 volumio squeezeboxserver[2171]: [14:32:45.150] output_init_common:290 allocating 1764000 Mar 28 14:32:45 volumio squeezeboxserver[2171]: [14:32:45.150] mDNSsearchCallback:679 [0x195a88]: removing renderer (volumio) Mar 28 14:32:45 volumio squeezeboxserver[2171]: [14:32:45.150] slimproto_close:906 [0x13c288] slimproto stop for volumio Mar 28 14:32:45 volumio squeezeboxserver[2171]: [14:32:45.152] slimproto:834 squeezelite [0x13c288] <=> player [0x195a88] Mar 28 14:32:45 volumio squeezeboxserver[2171]: [14:32:45.152] slimproto:835 [0x13c288] connecting to 192.168.219.105:3483 Mar 28 14:32:45 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Mar 28 14:32:45 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 28 14:32:45 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Mar 28 14:32:45 volumio volumio[1323]: info: Version has changed, forcing UI Reload Mar 28 14:32:45 volumio volumio[1323]: info: CoreCommandRouter::Reload Ui Mar 28 14:32:45 volumio squeezeboxserver[2171]: [14:32:45.351] DelRaopDevice:1008 [0x195a88]: Raop device stopped Mar 28 14:32:45 volumio squeezeboxserver[2171]: [14:32:45.351] mDNSsearchCallback:743 Updating configuration /var/lib/squeezeboxserver/prefs/raopbridge.xml Mar 28 14:32:45 volumio squeezeboxserver[2171]: [14:32:45.351] AddRaopDevice:862 [0x195a88]: AirPort Express Mar 28 14:32:45 volumio squeezeboxserver[2171]: [14:32:45.351] AddRaopDevice:926 [0x195a88]: adding renderer (volumio@192.168.219.105) with mac AAAA-DF0F404D Mar 28 14:32:45 volumio squeezeboxserver[2171]: [14:32:45.351] raopcl_create:717 [0xf6c08e08]: using PCM coding Mar 28 14:32:45 volumio squeezeboxserver[2171]: [14:32:45.352] output_raop_thread_init:119 [0x13c288]: init output raop Mar 28 14:32:45 volumio squeezeboxserver[2171]: [14:32:45.352] output_init_common:290 allocating 1764000 Mar 28 14:32:45 volumio squeezeboxserver[2171]: [14:32:45.352] mDNSsearchCallback:743 Updating configuration /var/lib/squeezeboxserver/prefs/raopbridge.xml Mar 28 14:32:45 volumio squeezeboxserver[2171]: [14:32:45.353] slimproto:834 squeezelite [0x13c288] <=> player [0x195a88] Mar 28 14:32:45 volumio squeezeboxserver[2171]: [14:32:45.353] slimproto:835 [0x13c288] connecting to 192.168.219.105:3483 Mar 28 14:32:45 volumio squeezeboxserver[2171]: [14:32:45.353] slimproto:865 [0x13c288] connected Mar 28 14:32:45 volumio squeezeboxserver[2171]: [14:32:45.353] sendHELO:107 [0x13c288] cap: CanHTTPS=1,Model=squeezelite,ModelName=RaopBridge,AccuratePlayPoints=0,HasDigitalOut=1,MaxSampleRate=96000,aac,ogg,ops,ogf,flc,alc,wav,aif,pcm,mp3 Mar 28 14:32:45 volumio squeezeboxserver[2171]: [14:32:45.354] output_flush:331 [0x13c288]: flush output buffer (full) Mar 28 14:32:45 volumio squeezeboxserver[2171]: [14:32:45.355] mDNSsearchCallback:679 [0x195a88]: removing renderer (volumio) Mar 28 14:32:45 volumio squeezeboxserver[2171]: [14:32:45.355] slimproto_close:906 [0x13c288] slimproto stop for volumio Mar 28 14:32:45 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Mar 28 14:32:45 volumio sudo[3991]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 28 14:32:45 volumio sudo[3991]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 14:32:45 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 14:32:45 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 14:32:45 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Mar 28 14:32:45 volumio systemd[1]: mpd.service: Deactivated successfully. Mar 28 14:32:45 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Mar 28 14:32:45 volumio systemd[1]: mpd.socket: Deactivated successfully. Mar 28 14:32:45 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Mar 28 14:32:45 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Mar 28 14:32:45 volumio sudo[3995]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 28 14:32:45 volumio sudo[3995]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 14:32:45 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Mar 28 14:32:45 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Mar 28 14:32:45 volumio systemd[1]: mpd.service: Deactivated successfully. Mar 28 14:32:45 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Mar 28 14:32:45 volumio systemd[1]: mpd.socket: Deactivated successfully. Mar 28 14:32:45 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Mar 28 14:32:45 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Mar 28 14:32:45 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Mar 28 14:32:45 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Mar 28 14:32:45 volumio sudo[3999]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 28 14:32:45 volumio sudo[3999]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 28 14:32:45 volumio sudo[3999]: pam_unix(sudo:session): session closed for user root Mar 28 14:32:45 volumio squeezeboxserver[2171]: [14:32:45.656] DelRaopDevice:1008 [0x195a88]: Raop device stopped Mar 28 14:32:45 volumio sudo[4004]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 28 14:32:45 volumio sudo[4004]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 14:32:45 volumio sudo[4005]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 28 14:32:45 volumio sudo[4005]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 14:32:45 volumio sudo[4004]: pam_unix(sudo:session): session closed for user root Mar 28 14:32:45 volumio sudo[4005]: pam_unix(sudo:session): session closed for user root Mar 28 14:32:45 volumio sudo[4009]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 28 14:32:45 volumio sudo[4009]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 14:32:45 volumio sudo[4009]: pam_unix(sudo:session): session closed for user root Mar 28 14:32:45 volumio sudo[4011]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 28 14:32:45 volumio sudo[4011]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 14:32:45 volumio sudo[4011]: pam_unix(sudo:session): session closed for user root Mar 28 14:32:45 volumio volumio[1323]: verbose: New Socket.io Connection to 192.168.219.105 from 192.168.219.119 UA: Mozilla/5.0 (Linux; Android 15; TB375FC Build/AP3A.240905.015; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/141.0.7390.122 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Mar 28 14:32:45 volumio squeezeboxserver[2171]: [14:32:45.950] AddRaopDevice:862 [0x195a88]: AirPort Express Mar 28 14:32:45 volumio squeezeboxserver[2171]: [14:32:45.950] AddRaopDevice:926 [0x195a88]: adding renderer (volumio@127.0.0.1) with mac AAAA-DF0F404D Mar 28 14:32:45 volumio squeezeboxserver[2171]: [14:32:45.951] raopcl_create:717 [0xf6c08de8]: using PCM coding Mar 28 14:32:45 volumio squeezeboxserver[2171]: [14:32:45.952] output_raop_thread_init:119 [0x13c288]: init output raop Mar 28 14:32:45 volumio squeezeboxserver[2171]: [14:32:45.952] output_init_common:290 allocating 1764000 Mar 28 14:32:45 volumio squeezeboxserver[2171]: [14:32:45.952] mDNSsearchCallback:743 Updating configuration /var/lib/squeezeboxserver/prefs/raopbridge.xml Mar 28 14:32:45 volumio squeezeboxserver[2171]: [14:32:45.952] slimproto:834 squeezelite [0x13c288] <=> player [0x195a88] Mar 28 14:32:45 volumio squeezeboxserver[2171]: [14:32:45.952] slimproto:835 [0x13c288] connecting to 192.168.219.105:3483 Mar 28 14:32:45 volumio squeezeboxserver[2171]: [14:32:45.952] slimproto:865 [0x13c288] connected Mar 28 14:32:45 volumio squeezeboxserver[2171]: [14:32:45.952] sendHELO:107 [0x13c288] cap: CanHTTPS=1,Model=squeezelite,ModelName=RaopBridge,AccuratePlayPoints=0,HasDigitalOut=1,MaxSampleRate=96000,aac,ogg,ops,ogf,flc,alc,wav,aif,pcm,mp3 Mar 28 14:32:45 volumio squeezeboxserver[2171]: [14:32:45.953] mDNSsearchCallback:688 [0x195a88]: changed ip:port 192.168.219.105:5000 Mar 28 14:32:45 volumio squeezeboxserver[2171]: [14:32:45.953] mDNSsearchCallback:688 [0x195a88]: changed ip:port 172.17.0.1:5000 Mar 28 14:32:45 volumio squeezeboxserver[2171]: [14:32:45.953] mDNSsearchCallback:688 [0x195a88]: changed ip:port 172.18.0.1:5000 Mar 28 14:32:45 volumio squeezeboxserver[2171]: [14:32:45.954] output_flush:331 [0x13c288]: flush output buffer (full) Mar 28 14:32:45 volumio squeezeboxserver[2171]: [14:32:45.954] PlayerThread:595 [0x195a88]: processing volume device:49 request:-15.31 Mar 28 14:32:45 volumio mpd[4001]: 2026-03-28T14:32:45 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 28 14:32:46 volumio volumio[1323]: verbose: New Socket.io Connection to 192.168.219.105 from 192.168.219.119 UA: Mozilla/5.0 (Linux; Android 15; TB375FC Build/AP3A.240905.015; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/141.0.7390.122 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Mar 28 14:32:46 volumio systemd[1]: Started mpd.service - Music Player Daemon. Mar 28 14:32:46 volumio sudo[3826]: pam_unix(sudo:session): session closed for user root Mar 28 14:32:46 volumio sudo[3991]: pam_unix(sudo:session): session closed for user root Mar 28 14:32:46 volumio sudo[3931]: pam_unix(sudo:session): session closed for user root Mar 28 14:32:46 volumio sudo[3995]: pam_unix(sudo:session): session closed for user root Mar 28 14:32:46 volumio volumio[1323]: error: MPD error: The expression evaluated to a falsy value: Mar 28 14:32:46 volumio volumio[1323]: assert.ok(self.idling) Mar 28 14:32:46 volumio volumio[1323]: error: The expression evaluated to a falsy value: Mar 28 14:32:46 volumio volumio[1323]: assert.ok(self.idling) Mar 28 14:32:46 volumio volumio[1323]: error: MPD error: The expression evaluated to a falsy value: Mar 28 14:32:46 volumio volumio[1323]: assert.ok(self.idling) Mar 28 14:32:46 volumio volumio[1323]: error: The expression evaluated to a falsy value: Mar 28 14:32:46 volumio volumio[1323]: assert.ok(self.idling) Mar 28 14:32:46 volumio volumio[1323]: error: MPD error: The expression evaluated to a falsy value: Mar 28 14:32:46 volumio volumio[1323]: assert.ok(self.idling) Mar 28 14:32:46 volumio volumio[1323]: error: The expression evaluated to a falsy value: Mar 28 14:32:46 volumio volumio[1323]: assert.ok(self.idling) Mar 28 14:32:46 volumio volumio[1323]: error: updateQueue error: null Mar 28 14:32:46 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 28 14:32:46 volumio volumio[1323]: info: CoreCommandRouter::volumioGetVisibleSources Mar 28 14:32:46 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 28 14:32:46 volumio volumio[1323]: info: CoreCommandRouter::volumioGetState Mar 28 14:32:46 volumio volumio[1323]: info: CorePlayQueue::getTrack 0 Mar 28 14:32:46 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 28 14:32:46 volumio volumio[1323]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 28 14:32:46 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 28 14:32:46 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 28 14:32:46 volumio volumio[1323]: info: Received Get System Info Mar 28 14:32:46 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 28 14:32:46 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 28 14:32:46 volumio volumio[1323]: info: Discovery: Getting this device information Mar 28 14:32:46 volumio volumio[1323]: info: CoreCommandRouter::volumioGetState Mar 28 14:32:46 volumio volumio[1323]: info: CorePlayQueue::getTrack 0 Mar 28 14:32:46 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 28 14:32:46 volumio volumio[1323]: info: CoreCommandRouter::volumioGetState Mar 28 14:32:46 volumio volumio[1323]: info: CorePlayQueue::getTrack 0 Mar 28 14:32:46 volumio volumio[1323]: info: Listing playlists Mar 28 14:32:46 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 28 14:32:46 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 28 14:32:46 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 28 14:32:46 volumio volumio[1323]: Disk list : [] Mar 28 14:32:46 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 28 14:32:46 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 28 14:32:46 volumio volumio[1323]: info: CoreCommandRouter::volumioGetVisibleSources Mar 28 14:32:46 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 28 14:32:46 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 28 14:32:46 volumio volumio[1323]: info: CoreCommandRouter::volumioGetState Mar 28 14:32:46 volumio volumio[1323]: info: CorePlayQueue::getTrack 0 Mar 28 14:32:46 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 28 14:32:46 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 28 14:32:46 volumio volumio[1323]: info: Received Get System Info Mar 28 14:32:46 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 28 14:32:46 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 28 14:32:46 volumio volumio[1323]: info: Discovery: Getting this device information Mar 28 14:32:46 volumio volumio[1323]: info: CoreCommandRouter::volumioGetState Mar 28 14:32:46 volumio volumio[1323]: info: CorePlayQueue::getTrack 0 Mar 28 14:32:46 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 28 14:32:46 volumio volumio[1323]: info: CoreCommandRouter::volumioGetState Mar 28 14:32:46 volumio volumio[1323]: info: CorePlayQueue::getTrack 0 Mar 28 14:32:46 volumio volumio[1323]: info: Listing playlists Mar 28 14:32:46 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 28 14:32:46 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 28 14:32:46 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 28 14:32:46 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 28 14:32:46 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 28 14:32:46 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Mar 28 14:32:46 volumio volumio[1323]: info: Received Get System Version Mar 28 14:32:46 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 28 14:32:46 volumio volumio[1323]: info: peppy_screensaver: MPD output 1 enabled Mar 28 14:32:47 volumio squeezeboxserver[2171]: [14:32:47.083] mDNSsearchCallback:688 [0x195a88]: changed ip:port 127.0.0.1:5000 Mar 28 14:32:47 volumio squeezeboxserver[2171]: [14:32:47.083] mDNSsearchCallback:688 [0x195a88]: changed ip:port 192.168.219.105:5000 Mar 28 14:32:47 volumio squeezeboxserver[2171]: [14:32:47.083] mDNSsearchCallback:688 [0x195a88]: changed ip:port 172.17.0.1:5000 Mar 28 14:32:47 volumio squeezeboxserver[2171]: [14:32:47.083] mDNSsearchCallback:688 [0x195a88]: changed ip:port 172.18.0.1:5000 Mar 28 14:32:47 volumio volumio[1323]: info: peppy_screensaver: Startup - MPD output 1 enabled Mar 28 14:32:47 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 28 14:32:47 volumio volumio[1323]: info: Received Get System Info Mar 28 14:32:47 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 28 14:32:47 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 28 14:32:47 volumio volumio[1323]: info: Discovery: Getting this device information Mar 28 14:32:47 volumio volumio[1323]: info: CoreCommandRouter::volumioGetState Mar 28 14:32:47 volumio volumio[1323]: info: CorePlayQueue::getTrack 0 Mar 28 14:32:47 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 28 14:32:47 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Mar 28 14:32:48 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Mar 28 14:32:48 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 28 14:32:48 volumio volumio[1323]: info: Received Get System Info Mar 28 14:32:48 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 28 14:32:48 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 28 14:32:48 volumio volumio[1323]: info: Discovery: Getting this device information Mar 28 14:32:48 volumio volumio[1323]: info: CoreCommandRouter::volumioGetState Mar 28 14:32:48 volumio volumio[1323]: info: CorePlayQueue::getTrack 0 Mar 28 14:32:48 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 28 14:32:48 volumio volumio[1323]: info: CoreCommandRouter::volumioGetState Mar 28 14:32:48 volumio volumio[1323]: info: CorePlayQueue::getTrack 0 Mar 28 14:32:48 volumio volumio[1323]: info: CoreCommandRouter::volumioGetQueue Mar 28 14:32:48 volumio volumio[1323]: info: CoreStateMachine::getQueue Mar 28 14:32:48 volumio volumio[1323]: info: CorePlayQueue::getQueue Mar 28 14:32:49 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 28 14:32:49 volumio volumio[1323]: info: Received Get System Info Mar 28 14:32:49 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 28 14:32:49 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 28 14:32:49 volumio volumio[1323]: info: Discovery: Getting this device information Mar 28 14:32:49 volumio volumio[1323]: info: CoreCommandRouter::volumioGetState Mar 28 14:32:49 volumio volumio[1323]: info: CorePlayQueue::getTrack 0 Mar 28 14:32:49 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 28 14:32:49 volumio squeezeboxserver[2171]: [14:32:49.221] mDNSsearchCallback:688 [0x195a88]: changed ip:port 127.0.0.1:5000 Mar 28 14:32:49 volumio squeezeboxserver[2171]: [14:32:49.221] mDNSsearchCallback:688 [0x195a88]: changed ip:port 192.168.219.105:5000 Mar 28 14:32:49 volumio squeezeboxserver[2171]: [14:32:49.221] mDNSsearchCallback:688 [0x195a88]: changed ip:port 172.17.0.1:5000 Mar 28 14:32:49 volumio squeezeboxserver[2171]: [14:32:49.221] mDNSsearchCallback:688 [0x195a88]: changed ip:port 172.18.0.1:5000 Mar 28 14:32:49 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 28 14:32:49 volumio volumio[1323]: info: Received Get System Info Mar 28 14:32:49 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 28 14:32:49 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 28 14:32:49 volumio volumio[1323]: info: Discovery: Getting this device information Mar 28 14:32:49 volumio volumio[1323]: info: CoreCommandRouter::volumioGetState Mar 28 14:32:49 volumio volumio[1323]: info: CorePlayQueue::getTrack 0 Mar 28 14:32:49 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 28 14:32:51 volumio volumio[1323]: info: BOOT COMPLETED Mar 28 14:32:51 volumio squeezeboxserver[2218]: [14:32:51.484] AddMRDevice:1454 [0x1f5298]: adding renderer (Volumio-UPnP/AV) 192.168.219.105 with mac BBBB-61062AF5 Mar 28 14:32:51 volumio squeezeboxserver[2218]: [14:32:51.487] MasterHandler:1076 [0x1f5298]: subscribe fail, re-trying 1 Mar 28 14:32:51 volumio squeezeboxserver[2218]: [14:32:51.487] MasterHandler:1076 [0x1f5298]: subscribe fail, re-trying 2 Mar 28 14:32:51 volumio squeezeboxserver[2218]: [14:32:51.488] MasterHandler:1076 [0x1f5298]: subscribe fail, re-trying 3 Mar 28 14:32:51 volumio squeezeboxserver[2218]: [14:32:51.488] MasterHandler:1079 [0x1f5298]: subscribe fail, volume feedback will not work Mar 28 14:32:51 volumio squeezeboxserver[2218]: [14:32:51.492] slimproto:885 squeezelite [0x178738] <=> player [0x1f5298] Mar 28 14:32:51 volumio squeezeboxserver[2218]: [14:32:51.492] slimproto:886 [0x178738] connecting to 192.168.219.105:3483 Mar 28 14:32:51 volumio squeezeboxserver[2218]: [14:32:51.493] slimproto:916 [0x178738] connected Mar 28 14:32:51 volumio squeezeboxserver[2218]: [14:32:51.493] sendHELO:131 [0x178738] cap: CanHTTPS=1,Model=squeezelite,ModelName=UPnPBridge,AccuratePlayPoints=0,HasDigitalOut=1,MaxSampleRate=384000,aac,ogg,ops,ogf,flc,alc,wav,aif,pcm,mp3 Mar 28 14:32:51 volumio volumio[1323]: info: Preload queue cleared Mar 28 14:32:51 volumio volumio[1323]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 28 14:32:51 volumio volumio[1323]: info: CoreStateMachine::ClearQueue Mar 28 14:32:51 volumio volumio[1323]: info: CoreStateMachine::stop Mar 28 14:32:51 volumio volumio[1323]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 14:32:51 volumio volumio[1323]: info: CorePlayQueue::clearPlayQueue Mar 28 14:32:51 volumio volumio[1323]: info: CorePlayQueue::saveQueue Mar 28 14:32:51 volumio volumio[1323]: info: CoreCommandRouter::volumioPushQueue Mar 28 14:32:51 volumio volumio[1323]: info: CoreStateMachine::addQueueItems Mar 28 14:32:51 volumio volumio[1323]: info: CorePlayQueue::addQueueItems Mar 28 14:32:51 volumio volumio[1323]: info: Preload queue cleared Mar 28 14:32:51 volumio volumio[1323]: info: Adding Item to queue: albums://Cannonball%20Adderley/Somethin'%20Else%20(Rudy%20Van%20Gelder%20Edition) Mar 28 14:32:51 volumio volumio[1323]: info: Exploding uri albums://Cannonball%20Adderley/Somethin'%20Else%20(Rudy%20Van%20Gelder%20Edition) in service mpd Mar 28 14:32:51 volumio volumio[1323]: info: CoreCommandRouter::volumioPushQueue Mar 28 14:32:51 volumio volumio[1323]: info: CorePlayQueue::saveQueue Mar 28 14:32:51 volumio volumio[1323]: info: CoreStateMachine::updateTrackBlock Mar 28 14:32:51 volumio volumio[1323]: info: CorePlayQueue::getTrackBlock Mar 28 14:32:51 volumio volumio[1323]: info: CoreCommandRouter::volumioPlay Mar 28 14:32:51 volumio volumio[1323]: info: CoreStateMachine::play index 0 Mar 28 14:32:51 volumio volumio[1323]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 14:32:51 volumio volumio[1323]: info: CoreStateMachine::stop Mar 28 14:32:51 volumio volumio[1323]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 14:32:51 volumio volumio[1323]: info: CoreStateMachine::play index undefined Mar 28 14:32:51 volumio volumio[1323]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 14:32:51 volumio volumio[1323]: info: CorePlayQueue::getTrack 0 Mar 28 14:32:51 volumio volumio[1323]: info: CoreStateMachine::startPlaybackTimer Mar 28 14:32:51 volumio volumio[1323]: info: CorePlayQueue::getTrack 0 Mar 28 14:32:51 volumio volumio[1323]: verbose: ControllerMpd::clearAddPlayTracks USB/MQA/Cannonball Adderley - Somethin' Else (Rudy Van Gelder Edition)/01. Autumn Leaves.flac Mar 28 14:32:51 volumio volumio[1323]: verbose: ControllerMpd::sendMpdCommand stop Mar 28 14:32:51 volumio volumio[1323]: info: sendMpdCommand stop took 6 milliseconds Mar 28 14:32:51 volumio volumio[1323]: verbose: ControllerMpd::sendMpdCommand clear Mar 28 14:32:51 volumio volumio[1323]: info: Mar 28 14:32:51 volumio volumio[1323]: ---------------------------- MPD announces system playlist update Mar 28 14:32:51 volumio volumio[1323]: info: Ignoring MPD Status Update Mar 28 14:32:51 volumio volumio[1323]: info: sendMpdCommand clear took 1 milliseconds Mar 28 14:32:51 volumio volumio[1323]: verbose: ControllerMpd::sendMpdCommand add "USB/MQA/Cannonball Adderley - Somethin' Else (Rudy Van Gelder Edition)/01. Autumn Leaves.flac" Mar 28 14:32:51 volumio volumio[1323]: info: Mar 28 14:32:51 volumio volumio[1323]: ---------------------------- MPD announces system playlist update Mar 28 14:32:51 volumio volumio[1323]: info: Ignoring MPD Status Update Mar 28 14:32:51 volumio volumio[1323]: info: Mar 28 14:32:51 volumio volumio[1323]: ---------------------------- MPD announces system playlist update Mar 28 14:32:51 volumio volumio[1323]: info: Ignoring MPD Status Update Mar 28 14:32:51 volumio volumio[1323]: info: Mar 28 14:32:51 volumio volumio[1323]: ---------------------------- MPD announces system playlist update Mar 28 14:32:51 volumio volumio[1323]: info: Ignoring MPD Status Update Mar 28 14:32:51 volumio volumio[1323]: error: updateQueue error: null Mar 28 14:32:51 volumio volumio[1323]: info: Mar 28 14:32:51 volumio volumio[1323]: ---------------------------- MPD announces system playlist update Mar 28 14:32:51 volumio volumio[1323]: info: Ignoring MPD Status Update Mar 28 14:32:51 volumio volumio[1323]: info: ------------------------------ 3ms Mar 28 14:32:51 volumio volumio[1323]: info: sendMpdCommand add "USB/MQA/Cannonball Adderley - Somethin' Else (Rudy Van Gelder Edition)/01. Autumn Leaves.flac" took 2 milliseconds Mar 28 14:32:51 volumio volumio[1323]: info: ------------------------------ 2ms Mar 28 14:32:51 volumio volumio[1323]: info: ------------------------------ 1ms Mar 28 14:32:51 volumio volumio[1323]: info: ------------------------------ 1ms Mar 28 14:32:51 volumio volumio[1323]: verbose: ControllerMpd::sendMpdCommand play Mar 28 14:32:51 volumio volumio[1323]: info: Mar 28 14:32:51 volumio volumio[1323]: ---------------------------- MPD announces system playlist update Mar 28 14:32:51 volumio volumio[1323]: info: Ignoring MPD Status Update Mar 28 14:32:51 volumio volumio[1323]: info: Mar 28 14:32:51 volumio volumio[1323]: ---------------------------- MPD announces system playlist update Mar 28 14:32:51 volumio volumio[1323]: info: Ignoring MPD Status Update Mar 28 14:32:51 volumio volumio[1323]: info: Mar 28 14:32:51 volumio volumio[1323]: ---------------------------- MPD announces system playlist update Mar 28 14:32:51 volumio volumio[1323]: info: Ignoring MPD Status Update Mar 28 14:32:51 volumio volumio[1323]: info: ------------------------------ 2ms Mar 28 14:32:51 volumio volumio[1323]: info: sendMpdCommand play took 1 milliseconds Mar 28 14:32:51 volumio volumio[1323]: info: ------------------------------ 1ms Mar 28 14:32:51 volumio volumio[1323]: info: ------------------------------ 1ms Mar 28 14:32:51 volumio volumio[1323]: info: ------------------------------ 1ms Mar 28 14:32:51 volumio volumio[1323]: info: Mar 28 14:32:51 volumio volumio[1323]: ---------------------------- MPD announces state update: player Mar 28 14:32:51 volumio volumio[1323]: info: ControllerMpd::getState Mar 28 14:32:51 volumio volumio[1323]: verbose: ControllerMpd::sendMpdCommand status Mar 28 14:32:51 volumio volumio[1323]: info: Mar 28 14:32:51 volumio volumio[1323]: ---------------------------- MPD announces state update: player Mar 28 14:32:51 volumio volumio[1323]: info: ControllerMpd::getState Mar 28 14:32:51 volumio volumio[1323]: verbose: ControllerMpd::sendMpdCommand status Mar 28 14:32:51 volumio volumio[1323]: info: Mar 28 14:32:51 volumio volumio[1323]: ---------------------------- MPD announces state update: player Mar 28 14:32:51 volumio volumio[1323]: info: ControllerMpd::getState Mar 28 14:32:51 volumio volumio[1323]: verbose: ControllerMpd::sendMpdCommand status Mar 28 14:32:51 volumio volumio[1323]: info: Mar 28 14:32:51 volumio volumio[1323]: ---------------------------- MPD announces state update: player Mar 28 14:32:51 volumio volumio[1323]: info: ControllerMpd::getState Mar 28 14:32:51 volumio volumio[1323]: verbose: ControllerMpd::sendMpdCommand status Mar 28 14:32:51 volumio volumio[1323]: info: sendMpdCommand status took 50 milliseconds Mar 28 14:32:51 volumio volumio[1323]: verbose: ControllerMpd::parseState Mar 28 14:32:51 volumio volumio[1323]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 14:32:51 volumio volumio[1323]: info: Mar 28 14:32:51 volumio volumio[1323]: ---------------------------- MPD announces state update: player Mar 28 14:32:51 volumio volumio[1323]: info: ControllerMpd::getState Mar 28 14:32:51 volumio volumio[1323]: verbose: ControllerMpd::sendMpdCommand status Mar 28 14:32:51 volumio volumio[1323]: info: Mar 28 14:32:51 volumio volumio[1323]: ---------------------------- MPD announces state update: player Mar 28 14:32:51 volumio volumio[1323]: info: ControllerMpd::getState Mar 28 14:32:51 volumio volumio[1323]: verbose: ControllerMpd::sendMpdCommand status Mar 28 14:32:51 volumio volumio[1323]: info: sendMpdCommand status took 51 milliseconds Mar 28 14:32:51 volumio volumio[1323]: info: sendMpdCommand status took 51 milliseconds Mar 28 14:32:51 volumio volumio[1323]: info: sendMpdCommand status took 50 milliseconds Mar 28 14:32:51 volumio volumio[1323]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 28 14:32:51 volumio volumio[1323]: info: sendMpdCommand status took 1 milliseconds Mar 28 14:32:51 volumio volumio[1323]: verbose: ControllerMpd::parseState Mar 28 14:32:51 volumio volumio[1323]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 14:32:51 volumio volumio[1323]: verbose: ControllerMpd::parseState Mar 28 14:32:51 volumio volumio[1323]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 14:32:51 volumio volumio[1323]: verbose: ControllerMpd::parseState Mar 28 14:32:51 volumio volumio[1323]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 14:32:51 volumio volumio[1323]: verbose: ControllerMpd::parseTrackInfo Mar 28 14:32:51 volumio volumio[1323]: verbose: ControllerMpd::parseState Mar 28 14:32:51 volumio volumio[1323]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 14:32:51 volumio volumio[1323]: info: ControllerMpd::pushState Mar 28 14:32:51 volumio volumio[1323]: info: CoreCommandRouter::servicePushState Mar 28 14:32:51 volumio volumio[1323]: info: CorePlayQueue::getTrack 0 Mar 28 14:32:51 volumio volumio[1323]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":660,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Autumn Leaves","artist":"Miles Davis","album":"Somethin' Else (Rudy Van Gelder Edition)","uri":"USB/MQA/Cannonball Adderley - Somethin' Else (Rudy Van Gelder Edition)/01. Autumn Leaves.flac","trackType":"flac"} Mar 28 14:32:51 volumio volumio[1323]: verbose: CURRENT POSITION 0 Mar 28 14:32:51 volumio volumio[1323]: info: CoreStateMachine::syncState stateService play Mar 28 14:32:51 volumio volumio[1323]: info: CoreStateMachine::syncState currentStatus stop Mar 28 14:32:51 volumio volumio[1323]: info: ------------------------------ 55ms Mar 28 14:32:51 volumio volumio[1323]: info: Mar 28 14:32:51 volumio volumio[1323]: ---------------------------- MPD announces state update: player Mar 28 14:32:51 volumio volumio[1323]: info: ControllerMpd::getState Mar 28 14:32:51 volumio volumio[1323]: verbose: ControllerMpd::sendMpdCommand status Mar 28 14:32:51 volumio volumio[1323]: info: Mar 28 14:32:51 volumio volumio[1323]: ---------------------------- MPD announces state update: player Mar 28 14:32:51 volumio volumio[1323]: info: ControllerMpd::getState Mar 28 14:32:51 volumio volumio[1323]: verbose: ControllerMpd::sendMpdCommand status Mar 28 14:32:51 volumio volumio[1323]: info: sendMpdCommand status took 4 milliseconds Mar 28 14:32:51 volumio volumio[1323]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 28 14:32:51 volumio volumio[1323]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 28 14:32:51 volumio volumio[1323]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 28 14:32:51 volumio volumio[1323]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 28 14:32:51 volumio volumio[1323]: info: sendMpdCommand status took 1 milliseconds Mar 28 14:32:51 volumio volumio[1323]: info: sendMpdCommand status took 1 milliseconds Mar 28 14:32:51 volumio volumio[1323]: verbose: ControllerMpd::parseState Mar 28 14:32:51 volumio volumio[1323]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 14:32:51 volumio volumio[1323]: verbose: ControllerMpd::parseTrackInfo Mar 28 14:32:51 volumio volumio[1323]: verbose: ControllerMpd::parseTrackInfo Mar 28 14:32:51 volumio volumio[1323]: verbose: ControllerMpd::parseTrackInfo Mar 28 14:32:51 volumio volumio[1323]: verbose: ControllerMpd::parseTrackInfo Mar 28 14:32:51 volumio volumio[1323]: verbose: ControllerMpd::parseState Mar 28 14:32:51 volumio volumio[1323]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 14:32:51 volumio volumio[1323]: verbose: ControllerMpd::parseState Mar 28 14:32:51 volumio volumio[1323]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 14:32:51 volumio volumio[1323]: info: ControllerMpd::pushState Mar 28 14:32:51 volumio volumio[1323]: info: CoreCommandRouter::servicePushState Mar 28 14:32:51 volumio volumio[1323]: info: CorePlayQueue::getTrack 0 Mar 28 14:32:51 volumio volumio[1323]: verbose: STATE SERVICE {"status":"play","position":0,"seek":91,"duration":660,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1 Kbps","isStreaming":false,"title":"Autumn Leaves","artist":"Miles Davis","album":"Somethin' Else (Rudy Van Gelder Edition)","uri":"USB/MQA/Cannonball Adderley - Somethin' Else (Rudy Van Gelder Edition)/01. Autumn Leaves.flac","trackType":"flac"} Mar 28 14:32:51 volumio volumio[1323]: verbose: CURRENT POSITION 0 Mar 28 14:32:51 volumio volumio[1323]: info: CoreStateMachine::syncState stateService play Mar 28 14:32:51 volumio volumio[1323]: info: CoreStateMachine::syncState currentStatus play Mar 28 14:32:51 volumio volumio[1323]: info: Received an update from plugin. extracting info from payload Mar 28 14:32:51 volumio volumio[1323]: info: CoreStateMachine::pushState Mar 28 14:32:51 volumio volumio[1323]: info: CorePlayQueue::getTrack 0 Mar 28 14:32:51 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 14:32:51 volumio volumio[1323]: info: CoreCommandRouter::volumioPushState Mar 28 14:32:51 volumio volumio[1323]: info: CoreStateMachine::pushState Mar 28 14:32:51 volumio volumio[1323]: info: CorePlayQueue::getTrack 0 Mar 28 14:32:51 volumio volumio[1323]: info: CoreCommandRouter::volumioPushState Mar 28 14:32:51 volumio volumio[1323]: info: ControllerMpd::pushState Mar 28 14:32:51 volumio volumio[1323]: info: CoreCommandRouter::servicePushState Mar 28 14:32:51 volumio volumio[1323]: info: CorePlayQueue::getTrack 0 Mar 28 14:32:51 volumio volumio[1323]: verbose: STATE SERVICE {"status":"play","position":0,"seek":137,"duration":660,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1 Kbps","isStreaming":false,"title":"Autumn Leaves","artist":"Miles Davis","album":"Somethin' Else (Rudy Van Gelder Edition)","uri":"USB/MQA/Cannonball Adderley - Somethin' Else (Rudy Van Gelder Edition)/01. Autumn Leaves.flac","trackType":"flac"} Mar 28 14:32:51 volumio volumio[1323]: verbose: CURRENT POSITION 0 Mar 28 14:32:51 volumio volumio[1323]: info: CoreStateMachine::syncState stateService play Mar 28 14:32:51 volumio volumio[1323]: info: CoreStateMachine::syncState currentStatus play Mar 28 14:32:51 volumio volumio[1323]: info: Received an update from plugin. extracting info from payload Mar 28 14:32:51 volumio volumio[1323]: info: CoreStateMachine::pushState Mar 28 14:32:51 volumio volumio[1323]: info: CorePlayQueue::getTrack 0 Mar 28 14:32:51 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 14:32:51 volumio volumio[1323]: info: CoreCommandRouter::volumioPushState Mar 28 14:32:51 volumio volumio[1323]: info: CoreStateMachine::pushState Mar 28 14:32:51 volumio volumio[1323]: info: CorePlayQueue::getTrack 0 Mar 28 14:32:51 volumio volumio[1323]: info: CoreCommandRouter::volumioPushState Mar 28 14:32:51 volumio volumio[1323]: info: ControllerMpd::pushState Mar 28 14:32:51 volumio volumio[1323]: info: CoreCommandRouter::servicePushState Mar 28 14:32:51 volumio volumio[1323]: info: CorePlayQueue::getTrack 0 Mar 28 14:32:51 volumio volumio[1323]: verbose: STATE SERVICE {"status":"play","position":0,"seek":160,"duration":660,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1 Kbps","isStreaming":false,"title":"Autumn Leaves","artist":"Miles Davis","album":"Somethin' Else (Rudy Van Gelder Edition)","uri":"USB/MQA/Cannonball Adderley - Somethin' Else (Rudy Van Gelder Edition)/01. Autumn Leaves.flac","trackType":"flac"} Mar 28 14:32:51 volumio volumio[1323]: verbose: CURRENT POSITION 0 Mar 28 14:32:51 volumio volumio[1323]: info: CoreStateMachine::syncState stateService play Mar 28 14:32:51 volumio volumio[1323]: info: CoreStateMachine::syncState currentStatus play Mar 28 14:32:51 volumio volumio[1323]: info: Received an update from plugin. extracting info from payload Mar 28 14:32:51 volumio volumio[1323]: info: CoreStateMachine::pushState Mar 28 14:32:51 volumio volumio[1323]: info: CorePlayQueue::getTrack 0 Mar 28 14:32:51 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 14:32:51 volumio volumio[1323]: info: CoreCommandRouter::volumioPushState Mar 28 14:32:51 volumio volumio[1323]: info: CoreStateMachine::pushState Mar 28 14:32:51 volumio volumio[1323]: info: CorePlayQueue::getTrack 0 Mar 28 14:32:51 volumio volumio[1323]: info: CoreCommandRouter::volumioPushState Mar 28 14:32:51 volumio volumio[1323]: info: ControllerMpd::pushState Mar 28 14:32:51 volumio volumio[1323]: info: CoreCommandRouter::servicePushState Mar 28 14:32:51 volumio volumio[1323]: info: CorePlayQueue::getTrack 0 Mar 28 14:32:51 volumio volumio[1323]: verbose: STATE SERVICE {"status":"play","position":0,"seek":710,"duration":660,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"670 Kbps","isStreaming":false,"title":"Autumn Leaves","artist":"Miles Davis","album":"Somethin' Else (Rudy Van Gelder Edition)","uri":"USB/MQA/Cannonball Adderley - Somethin' Else (Rudy Van Gelder Edition)/01. Autumn Leaves.flac","trackType":"flac"} Mar 28 14:32:51 volumio volumio[1323]: verbose: CURRENT POSITION 0 Mar 28 14:32:51 volumio volumio[1323]: info: CoreStateMachine::syncState stateService play Mar 28 14:32:51 volumio volumio[1323]: info: CoreStateMachine::syncState currentStatus play Mar 28 14:32:51 volumio volumio[1323]: info: Received an update from plugin. extracting info from payload Mar 28 14:32:51 volumio volumio[1323]: info: CoreStateMachine::pushState Mar 28 14:32:51 volumio volumio[1323]: info: CorePlayQueue::getTrack 0 Mar 28 14:32:51 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 14:32:51 volumio volumio[1323]: info: CoreCommandRouter::volumioPushState Mar 28 14:32:51 volumio volumio[1323]: info: CoreStateMachine::pushState Mar 28 14:32:51 volumio volumio[1323]: info: CorePlayQueue::getTrack 0 Mar 28 14:32:51 volumio volumio[1323]: info: CoreCommandRouter::volumioPushState Mar 28 14:32:51 volumio volumio[1323]: info: ------------------------------ 86ms Mar 28 14:32:51 volumio volumio[1323]: info: ------------------------------ 86ms Mar 28 14:32:51 volumio volumio[1323]: info: ------------------------------ 85ms Mar 28 14:32:51 volumio volumio[1323]: info: ------------------------------ 35ms Mar 28 14:32:51 volumio volumio[1323]: info: sendMpdCommand playlistinfo took 32 milliseconds Mar 28 14:32:51 volumio volumio[1323]: info: sendMpdCommand playlistinfo took 32 milliseconds Mar 28 14:32:51 volumio volumio[1323]: info: sendMpdCommand playlistinfo took 31 milliseconds Mar 28 14:32:51 volumio volumio[1323]: verbose: ControllerMpd::parseTrackInfo Mar 28 14:32:51 volumio volumio[1323]: verbose: ControllerMpd::parseTrackInfo Mar 28 14:32:51 volumio volumio[1323]: verbose: ControllerMpd::parseTrackInfo Mar 28 14:32:51 volumio volumio[1323]: info: ControllerMpd::pushState Mar 28 14:32:51 volumio volumio[1323]: info: CoreCommandRouter::servicePushState Mar 28 14:32:51 volumio volumio[1323]: info: CorePlayQueue::getTrack 0 Mar 28 14:32:51 volumio volumio[1323]: verbose: STATE SERVICE {"status":"play","position":0,"seek":710,"duration":660,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"675 Kbps","isStreaming":false,"title":"Autumn Leaves","artist":"Miles Davis","album":"Somethin' Else (Rudy Van Gelder Edition)","uri":"USB/MQA/Cannonball Adderley - Somethin' Else (Rudy Van Gelder Edition)/01. Autumn Leaves.flac","trackType":"flac"} Mar 28 14:32:51 volumio volumio[1323]: verbose: CURRENT POSITION 0 Mar 28 14:32:51 volumio volumio[1323]: info: CoreStateMachine::syncState stateService play Mar 28 14:32:51 volumio volumio[1323]: info: CoreStateMachine::syncState currentStatus play Mar 28 14:32:51 volumio volumio[1323]: info: Received an update from plugin. extracting info from payload Mar 28 14:32:51 volumio volumio[1323]: info: CoreStateMachine::pushState Mar 28 14:32:51 volumio volumio[1323]: info: CorePlayQueue::getTrack 0 Mar 28 14:32:51 volumio volumio[1323]: info: CoreCommandRouter::volumioPushState Mar 28 14:32:51 volumio volumio[1323]: info: CoreStateMachine::pushState Mar 28 14:32:51 volumio volumio[1323]: info: CorePlayQueue::getTrack 0 Mar 28 14:32:51 volumio volumio[1323]: info: CoreCommandRouter::volumioPushState Mar 28 14:32:51 volumio volumio[1323]: info: ControllerMpd::pushState Mar 28 14:32:51 volumio volumio[1323]: info: CoreCommandRouter::servicePushState Mar 28 14:32:51 volumio volumio[1323]: info: CorePlayQueue::getTrack 0 Mar 28 14:32:51 volumio volumio[1323]: verbose: STATE SERVICE {"status":"play","position":0,"seek":710,"duration":660,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"675 Kbps","isStreaming":false,"title":"Autumn Leaves","artist":"Miles Davis","album":"Somethin' Else (Rudy Van Gelder Edition)","uri":"USB/MQA/Cannonball Adderley - Somethin' Else (Rudy Van Gelder Edition)/01. Autumn Leaves.flac","trackType":"flac"} Mar 28 14:32:51 volumio volumio[1323]: verbose: CURRENT POSITION 0 Mar 28 14:32:51 volumio volumio[1323]: info: CoreStateMachine::syncState stateService play Mar 28 14:32:51 volumio volumio[1323]: info: CoreStateMachine::syncState currentStatus play Mar 28 14:32:51 volumio volumio[1323]: info: Received an update from plugin. extracting info from payload Mar 28 14:32:51 volumio volumio[1323]: info: CoreStateMachine::pushState Mar 28 14:32:51 volumio volumio[1323]: info: CorePlayQueue::getTrack 0 Mar 28 14:32:51 volumio volumio[1323]: info: CoreCommandRouter::volumioPushState Mar 28 14:32:51 volumio volumio[1323]: info: CoreStateMachine::pushState Mar 28 14:32:51 volumio volumio[1323]: info: CorePlayQueue::getTrack 0 Mar 28 14:32:51 volumio volumio[1323]: info: CoreCommandRouter::volumioPushState Mar 28 14:32:51 volumio volumio[1323]: info: ControllerMpd::pushState Mar 28 14:32:51 volumio volumio[1323]: info: CoreCommandRouter::servicePushState Mar 28 14:32:51 volumio volumio[1323]: info: CorePlayQueue::getTrack 0 Mar 28 14:32:51 volumio volumio[1323]: verbose: STATE SERVICE {"status":"play","position":0,"seek":710,"duration":660,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"675 Kbps","isStreaming":false,"title":"Autumn Leaves","artist":"Miles Davis","album":"Somethin' Else (Rudy Van Gelder Edition)","uri":"USB/MQA/Cannonball Adderley - Somethin' Else (Rudy Van Gelder Edition)/01. Autumn Leaves.flac","trackType":"flac"} Mar 28 14:32:51 volumio volumio[1323]: verbose: CURRENT POSITION 0 Mar 28 14:32:51 volumio volumio[1323]: info: CoreStateMachine::syncState stateService play Mar 28 14:32:51 volumio volumio[1323]: info: CoreStateMachine::syncState currentStatus play Mar 28 14:32:51 volumio volumio[1323]: info: Received an update from plugin. extracting info from payload Mar 28 14:32:51 volumio volumio[1323]: info: CoreStateMachine::pushState Mar 28 14:32:51 volumio volumio[1323]: info: CorePlayQueue::getTrack 0 Mar 28 14:32:51 volumio volumio[1323]: info: CoreCommandRouter::volumioPushState Mar 28 14:32:51 volumio volumio[1323]: info: CoreStateMachine::pushState Mar 28 14:32:51 volumio volumio[1323]: info: CorePlayQueue::getTrack 0 Mar 28 14:32:51 volumio volumio[1323]: info: CoreCommandRouter::volumioPushState Mar 28 14:32:51 volumio volumio[1323]: info: ------------------------------ 76ms Mar 28 14:32:51 volumio volumio[1323]: info: ------------------------------ 74ms Mar 28 14:32:51 volumio volumio[1323]: info: ------------------------------ 74ms Mar 28 14:32:51 volumio volumio[1323]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Mar 28 14:32:51 volumio volumio[1323]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Mar 28 14:32:51 volumio volumio[1323]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Mar 28 14:32:51 volumio volumio[1323]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Mar 28 14:32:51 volumio volumio[1323]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Mar 28 14:32:51 volumio volumio[1323]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Mar 28 14:32:51 volumio volumio[1323]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Mar 28 14:32:51 volumio volumio[1323]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Mar 28 14:32:51 volumio volumio[1323]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Mar 28 14:32:51 volumio volumio[1323]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Mar 28 14:32:51 volumio volumio[1323]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Mar 28 14:32:51 volumio volumio[1323]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Mar 28 14:32:51 volumio volumio[1323]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Mar 28 14:32:51 volumio volumio[1323]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Mar 28 14:32:51 volumio volumio[1323]: info: touch_display: Setting screensaver timeout to 0 seconds. Mar 28 14:32:56 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 28 14:32:56 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Mar 28 14:32:57 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 28 14:32:57 volumio volumio[1323]: info: CoreCommandRouter::volumioGetState Mar 28 14:32:57 volumio volumio[1323]: info: CorePlayQueue::getTrack 0 Mar 28 14:32:57 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Mar 28 14:32:57 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Mar 28 14:32:58 volumio volumio[1323]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: unknown Engine version: 4 Transport: websocket Total Clients: 11 Mar 28 14:32:58 volumio volumio[1323]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: unknown Engine version: 4 Transport: websocket Total Clients: 11 Mar 28 14:32:58 volumio volumio[1323]: info: CoreCommandRouter::volumioGetState Mar 28 14:32:58 volumio volumio[1323]: info: CorePlayQueue::getTrack 0 Mar 28 14:32:58 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 28 14:32:58 volumio volumio[1323]: info: CoreCommandRouter::volumioGetQueue Mar 28 14:32:58 volumio volumio[1323]: info: CoreStateMachine::getQueue Mar 28 14:32:58 volumio volumio[1323]: info: CorePlayQueue::getQueue Mar 28 14:33:04 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 28 14:33:04 volumio volumio[1323]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 28 14:33:04 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 28 14:33:04 volumio volumio[1323]: info: Received Get System Version Mar 28 14:33:04 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 28 14:33:04 volumio volumio[1323]: info: Received Get System Info Mar 28 14:33:04 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 28 14:33:04 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 28 14:33:04 volumio volumio[1323]: info: Discovery: Getting this device information Mar 28 14:33:04 volumio volumio[1323]: info: CoreCommandRouter::volumioGetState Mar 28 14:33:04 volumio volumio[1323]: info: CorePlayQueue::getTrack 0 Mar 28 14:33:04 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 28 14:33:04 volumio volumio[1323]: info: CoreCommandRouter::volumioPause Mar 28 14:33:04 volumio volumio[1323]: info: CoreStateMachine::pause Mar 28 14:33:04 volumio volumio[1323]: info: CoreStateMachine::stPlaybackTimer Mar 28 14:33:04 volumio volumio[1323]: info: CoreStateMachine::servicePause Mar 28 14:33:04 volumio volumio[1323]: info: CorePlayQueue::getTrack 0 Mar 28 14:33:04 volumio volumio[1323]: info: CoreCommandRouter::servicePause Mar 28 14:33:04 volumio volumio[1323]: info: ControllerMpd::pause Mar 28 14:33:04 volumio volumio[1323]: verbose: ControllerMpd::sendMpdCommand pause Mar 28 14:33:04 volumio volumio[1323]: info: Mar 28 14:33:04 volumio volumio[1323]: ---------------------------- MPD announces state update: player Mar 28 14:33:04 volumio volumio[1323]: info: sendMpdCommand pause took 1 milliseconds Mar 28 14:33:04 volumio volumio[1323]: info: ControllerMpd::getState Mar 28 14:33:04 volumio volumio[1323]: verbose: ControllerMpd::sendMpdCommand status Mar 28 14:33:04 volumio volumio[1323]: info: Mar 28 14:33:04 volumio volumio[1323]: ---------------------------- MPD announces state update: player Mar 28 14:33:04 volumio volumio[1323]: info: ControllerMpd::getState Mar 28 14:33:04 volumio volumio[1323]: verbose: ControllerMpd::sendMpdCommand status Mar 28 14:33:04 volumio volumio[1323]: info: Mar 28 14:33:04 volumio volumio[1323]: ---------------------------- MPD announces state update: player Mar 28 14:33:04 volumio volumio[1323]: info: ControllerMpd::getState Mar 28 14:33:04 volumio volumio[1323]: verbose: ControllerMpd::sendMpdCommand status Mar 28 14:33:04 volumio volumio[1323]: info: Mar 28 14:33:04 volumio volumio[1323]: ---------------------------- MPD announces state update: player Mar 28 14:33:04 volumio volumio[1323]: info: ControllerMpd::getState Mar 28 14:33:04 volumio volumio[1323]: verbose: ControllerMpd::sendMpdCommand status Mar 28 14:33:04 volumio volumio[1323]: info: sendMpdCommand status took 1 milliseconds Mar 28 14:33:04 volumio volumio[1323]: info: sendMpdCommand status took 1 milliseconds Mar 28 14:33:04 volumio volumio[1323]: info: sendMpdCommand status took 0 milliseconds Mar 28 14:33:04 volumio volumio[1323]: info: sendMpdCommand status took 0 milliseconds Mar 28 14:33:04 volumio volumio[1323]: verbose: ControllerMpd::parseState Mar 28 14:33:04 volumio volumio[1323]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 14:33:04 volumio volumio[1323]: verbose: ControllerMpd::parseState Mar 28 14:33:04 volumio volumio[1323]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 14:33:04 volumio volumio[1323]: verbose: ControllerMpd::parseState Mar 28 14:33:04 volumio volumio[1323]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 14:33:04 volumio volumio[1323]: verbose: ControllerMpd::parseState Mar 28 14:33:04 volumio volumio[1323]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 14:33:04 volumio volumio[1323]: info: sendMpdCommand playlistinfo took 0 milliseconds Mar 28 14:33:04 volumio volumio[1323]: info: sendMpdCommand playlistinfo took 0 milliseconds Mar 28 14:33:04 volumio volumio[1323]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 28 14:33:04 volumio volumio[1323]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 28 14:33:04 volumio volumio[1323]: verbose: ControllerMpd::parseTrackInfo Mar 28 14:33:04 volumio volumio[1323]: verbose: ControllerMpd::parseTrackInfo Mar 28 14:33:04 volumio volumio[1323]: verbose: ControllerMpd::parseTrackInfo Mar 28 14:33:04 volumio volumio[1323]: verbose: ControllerMpd::parseTrackInfo Mar 28 14:33:04 volumio volumio[1323]: info: ControllerMpd::pushState Mar 28 14:33:04 volumio volumio[1323]: info: CoreCommandRouter::servicePushState Mar 28 14:33:04 volumio volumio[1323]: info: CorePlayQueue::getTrack 0 Mar 28 14:33:04 volumio volumio[1323]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":13159,"duration":660,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"832 Kbps","isStreaming":false,"title":"Autumn Leaves","artist":"Miles Davis","album":"Somethin' Else (Rudy Van Gelder Edition)","uri":"USB/MQA/Cannonball Adderley - Somethin' Else (Rudy Van Gelder Edition)/01. Autumn Leaves.flac","trackType":"flac"} Mar 28 14:33:04 volumio volumio[1323]: verbose: CURRENT POSITION 0 Mar 28 14:33:04 volumio volumio[1323]: info: CoreStateMachine::syncState stateService pause Mar 28 14:33:04 volumio volumio[1323]: info: CoreStateMachine::syncState currentStatus pause Mar 28 14:33:04 volumio volumio[1323]: info: CoreStateMachine::pushState Mar 28 14:33:04 volumio volumio[1323]: info: CorePlayQueue::getTrack 0 Mar 28 14:33:04 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 14:33:04 volumio volumio[1323]: info: CoreCommandRouter::volumioPushState Mar 28 14:33:04 volumio volumio[1323]: info: CoreStateMachine::stPlaybackTimer Mar 28 14:33:04 volumio volumio[1323]: info: ControllerMpd::pushState Mar 28 14:33:04 volumio volumio[1323]: info: CoreCommandRouter::servicePushState Mar 28 14:33:04 volumio volumio[1323]: info: CorePlayQueue::getTrack 0 Mar 28 14:33:04 volumio volumio[1323]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":13159,"duration":660,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"832 Kbps","isStreaming":false,"title":"Autumn Leaves","artist":"Miles Davis","album":"Somethin' Else (Rudy Van Gelder Edition)","uri":"USB/MQA/Cannonball Adderley - Somethin' Else (Rudy Van Gelder Edition)/01. Autumn Leaves.flac","trackType":"flac"} Mar 28 14:33:04 volumio volumio[1323]: verbose: CURRENT POSITION 0 Mar 28 14:33:04 volumio volumio[1323]: info: CoreStateMachine::syncState stateService pause Mar 28 14:33:04 volumio volumio[1323]: info: CoreStateMachine::syncState currentStatus pause Mar 28 14:33:04 volumio volumio[1323]: info: CoreStateMachine::pushState Mar 28 14:33:04 volumio volumio[1323]: info: CorePlayQueue::getTrack 0 Mar 28 14:33:04 volumio volumio[1323]: info: CoreCommandRouter::volumioPushState Mar 28 14:33:04 volumio volumio[1323]: info: CoreStateMachine::stPlaybackTimer Mar 28 14:33:04 volumio volumio[1323]: info: ControllerMpd::pushState Mar 28 14:33:04 volumio volumio[1323]: info: CoreCommandRouter::servicePushState Mar 28 14:33:04 volumio volumio[1323]: info: CorePlayQueue::getTrack 0 Mar 28 14:33:04 volumio volumio[1323]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":13159,"duration":660,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"832 Kbps","isStreaming":false,"title":"Autumn Leaves","artist":"Miles Davis","album":"Somethin' Else (Rudy Van Gelder Edition)","uri":"USB/MQA/Cannonball Adderley - Somethin' Else (Rudy Van Gelder Edition)/01. Autumn Leaves.flac","trackType":"flac"} Mar 28 14:33:04 volumio volumio[1323]: verbose: CURRENT POSITION 0 Mar 28 14:33:04 volumio volumio[1323]: info: CoreStateMachine::syncState stateService pause Mar 28 14:33:04 volumio volumio[1323]: info: CoreStateMachine::syncState currentStatus pause Mar 28 14:33:04 volumio volumio[1323]: info: CoreStateMachine::pushState Mar 28 14:33:04 volumio volumio[1323]: info: CorePlayQueue::getTrack 0 Mar 28 14:33:04 volumio volumio[1323]: info: CoreCommandRouter::volumioPushState Mar 28 14:33:04 volumio volumio[1323]: info: CoreStateMachine::stPlaybackTimer Mar 28 14:33:04 volumio volumio[1323]: info: ControllerMpd::pushState Mar 28 14:33:04 volumio volumio[1323]: info: CoreCommandRouter::servicePushState Mar 28 14:33:04 volumio volumio[1323]: info: CorePlayQueue::getTrack 0 Mar 28 14:33:04 volumio volumio[1323]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":13159,"duration":660,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"832 Kbps","isStreaming":false,"title":"Autumn Leaves","artist":"Miles Davis","album":"Somethin' Else (Rudy Van Gelder Edition)","uri":"USB/MQA/Cannonball Adderley - Somethin' Else (Rudy Van Gelder Edition)/01. Autumn Leaves.flac","trackType":"flac"} Mar 28 14:33:04 volumio volumio[1323]: verbose: CURRENT POSITION 0 Mar 28 14:33:04 volumio volumio[1323]: info: CoreStateMachine::syncState stateService pause Mar 28 14:33:04 volumio volumio[1323]: info: CoreStateMachine::syncState currentStatus pause Mar 28 14:33:04 volumio volumio[1323]: info: CoreStateMachine::pushState Mar 28 14:33:04 volumio volumio[1323]: info: CorePlayQueue::getTrack 0 Mar 28 14:33:04 volumio volumio[1323]: info: CoreCommandRouter::volumioPushState Mar 28 14:33:04 volumio volumio[1323]: info: CoreStateMachine::stPlaybackTimer Mar 28 14:33:04 volumio volumio[1323]: info: ------------------------------ 33ms Mar 28 14:33:04 volumio volumio[1323]: info: ------------------------------ 33ms Mar 28 14:33:04 volumio volumio[1323]: info: ------------------------------ 33ms Mar 28 14:33:04 volumio volumio[1323]: info: ------------------------------ 32ms Mar 28 14:33:04 volumio volumio[1323]: info: peppy_screensaver: pushState - status=pause service=mpd volatile=false Mar 28 14:33:04 volumio volumio[1323]: info: peppy_screensaver: Starting persist timer - 30s Mar 28 14:33:04 volumio volumio[1323]: info: peppy_screensaver: pushState - status=pause service=mpd volatile=false Mar 28 14:33:04 volumio volumio[1323]: info: peppy_screensaver: Starting persist timer - 30s Mar 28 14:33:04 volumio volumio[1323]: info: peppy_screensaver: pushState - status=pause service=mpd volatile=false Mar 28 14:33:04 volumio volumio[1323]: info: peppy_screensaver: Starting persist timer - 30s Mar 28 14:33:04 volumio volumio[1323]: info: peppy_screensaver: pushState - status=pause service=mpd volatile=false Mar 28 14:33:04 volumio volumio[1323]: info: peppy_screensaver: Starting persist timer - 30s Mar 28 14:33:04 volumio volumio[1323]: info: touch_display: Setting screensaver timeout to 0 seconds. Mar 28 14:33:06 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 28 14:33:06 volumio volumio[1323]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 28 14:33:06 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 28 14:33:06 volumio volumio[1323]: info: Received Get System Version Mar 28 14:33:06 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 28 14:33:06 volumio volumio[1323]: info: Received Get System Info Mar 28 14:33:06 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 28 14:33:06 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 28 14:33:06 volumio volumio[1323]: info: Discovery: Getting this device information Mar 28 14:33:06 volumio volumio[1323]: info: CoreCommandRouter::volumioGetState Mar 28 14:33:06 volumio volumio[1323]: info: CorePlayQueue::getTrack 0 Mar 28 14:33:06 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 28 14:33:08 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: carrot_radio , handleBrowseUri Mar 28 14:33:08 volumio volumio[1323]: info: Preload queue cleared Mar 28 14:33:08 volumio volumio[1323]: info: Preloading song: webcarrot/155 Mar 28 14:33:08 volumio volumio[1323]: info: Preloading song: webcarrot/157 Mar 28 14:33:08 volumio volumio[1323]: info: Preloading song: webcarrot/158 Mar 28 14:33:08 volumio volumio[1323]: info: Preloading song: webcarrot/161 Mar 28 14:33:08 volumio volumio[1323]: info: Preloading song: webcarrot/3 Mar 28 14:33:08 volumio volumio[1323]: info: Preloading song: webcarrot/7 Mar 28 14:33:08 volumio volumio[1323]: info: Preloading song: webcarrot/10 Mar 28 14:33:08 volumio volumio[1323]: info: Preloading song: webcarrot/19 Mar 28 14:33:08 volumio volumio[1323]: info: Exploding uri webcarrot/155 in service carrot_radio Mar 28 14:33:08 volumio volumio[1323]: info: [explodeUri] currentStation set: Music Classic FM program=no Mar 28 14:33:08 volumio volumio[1323]: info: Exploding uri webcarrot/157 in service carrot_radio Mar 28 14:33:08 volumio volumio[1323]: info: [explodeUri] currentStation set: LINN Jazz program=no Mar 28 14:33:08 volumio volumio[1323]: info: Exploding uri webcarrot/158 in service carrot_radio Mar 28 14:33:08 volumio volumio[1323]: info: [explodeUri] currentStation set: Sveriges Radio P2 program=no Mar 28 14:33:08 volumio volumio[1323]: info: Exploding uri webcarrot/161 in service carrot_radio Mar 28 14:33:08 volumio volumio[1323]: info: [explodeUri] currentStation set: Naim Jazz program=no Mar 28 14:33:08 volumio volumio[1323]: info: Exploding uri webcarrot/3 in service carrot_radio Mar 28 14:33:08 volumio volumio[1323]: info: [explodeUri] currentStation set: KBS ClassicFM program=yes Mar 28 14:33:08 volumio volumio[1323]: info: Exploding uri webcarrot/7 in service carrot_radio Mar 28 14:33:08 volumio volumio[1323]: info: [explodeUri] currentStation set: MBC FM4U program=yes Mar 28 14:33:09 volumio volumio[1323]: info: Exploding uri webcarrot/10 in service carrot_radio Mar 28 14:33:09 volumio volumio[1323]: info: [explodeUri] currentStation set: SBS 파워FM program=yes Mar 28 14:33:09 volumio volumio[1323]: info: Exploding uri webcarrot/19 in service carrot_radio Mar 28 14:33:09 volumio volumio[1323]: info: [explodeUri] currentStation set: CBS 음악FM program=yes Mar 28 14:33:09 volumio volumio[1323]: info: [_resolveRecursive] 301 -> https://radio.yuntae.in/api/stream?stn=kbs&ch=1fm Mar 28 14:33:09 volumio volumio[1323]: info: [_resolveRecursive] 301 -> https://radio.yuntae.in/api/stream?stn=mbc&ch=fm4u Mar 28 14:33:09 volumio volumio[1323]: info: [_resolveRecursive] 301 -> https://radio.yuntae.in/api/stream?stn=sbs&ch=powerfm Mar 28 14:33:09 volumio volumio[1323]: info: [_resolveRecursive] 301 -> https://radio.yuntae.in/api/stream?stn=cbs&ch=mfm Mar 28 14:33:09 volumio volumio[1323]: info: [_resolveRecursive] Content-Type: audio/mpeg url: http://radio.linn.co.uk:8000/autodj Mar 28 14:33:09 volumio volumio[1323]: info: [ControllerCarrotRadio::explodeUri] resolved: http://radio.linn.co.uk:8000/autodj Mar 28 14:33:09 volumio volumio[1323]: info: [_resolveRecursive] Content-Type: audio/aac url: https://media-ssl.musicradio.com/ClassicFM Mar 28 14:33:09 volumio volumio[1323]: info: [ControllerCarrotRadio::explodeUri] resolved: https://media-ssl.musicradio.com/ClassicFM Mar 28 14:33:09 volumio volumio[1323]: info: [_resolveRecursive] 302 -> https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLyoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3NzQ4NDg3MTN9fX1dfQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA&Signature=ct2ASAyJJfi962JWWOuk7t5TzqieD6tSX1T84l7eL1BQC9f5-5nQF8nOOXpUACfmc5JK6jkS8VoYYe0SCgRRXInXYLybTJFPqgMvJVo88c7nSZn29bPuM83uq6OBzAuKd1HQxvIX~l0MWdWBHI4UKH9kMZZ1RUGqR8Yf-WDHFX-~pdMNFnyU4Uphmnql08QQAOV9eZrfDuyqzVoQhT7pU8Ev5C8dF-IsXTO15bDuVMV6csfMuPDiL5dymykMw1OHzQD3a1qRwDogxqf1uILQRX7Dk4U2JiM4sjTrR2UEC209BAxPgGpBZRR37DWELkOWCEC0xm8C1neoVu3ShHXjmA__ Mar 28 14:33:09 volumio volumio[1323]: info: [ControllerCarrotRadio::explodeUri] resolved: https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLyoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3NzQ4NDg3MTN9fX1dfQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA&Signature=ct2ASAyJJfi962JWWOuk7t5TzqieD6tSX1T84l7eL1BQC9f5-5nQF8nOOXpUACfmc5JK6jkS8VoYYe0SCgRRXInXYLybTJFPqgMvJVo88c7nSZn29bPuM83uq6OBzAuKd1HQxvIX~l0MWdWBHI4UKH9kMZZ1RUGqR8Yf-WDHFX-~pdMNFnyU4Uphmnql08QQAOV9eZrfDuyqzVoQhT7pU8Ev5C8dF-IsXTO15bDuVMV6csfMuPDiL5dymykMw1OHzQD3a1qRwDogxqf1uILQRX7Dk4U2JiM4sjTrR2UEC209BAxPgGpBZRR37DWELkOWCEC0xm8C1neoVu3ShHXjmA__ Mar 28 14:33:09 volumio volumio[1323]: info: [_resolveRecursive] 302 -> https://m-aac.cbs.co.kr/mweb_cbs939/_definst_/cbs939.stream/playlist.m3u8 Mar 28 14:33:09 volumio volumio[1323]: info: [ControllerCarrotRadio::explodeUri] resolved: https://m-aac.cbs.co.kr/mweb_cbs939/_definst_/cbs939.stream/playlist.m3u8 Mar 28 14:33:09 volumio volumio[1323]: info: [_resolveRecursive] 302 -> https://minimw.imbc.com/dmfm/_definst_/mfm.stream/playlist.m3u8?_lsu_sa_=6861A41013EC3854CC49854432E1FA4305A43BC5040CC21B35B0D5a766353676D3a193DD3FF23B4E401A399168b391C8FD6F33B68B180444BCE6AA45AFAF192D910EF031D1A61C06EDFAC13CF295450F2BFE2B1FFE1AF5F498DD652C17BAE878A181D057665C5E4C32B6513596009116 Mar 28 14:33:09 volumio volumio[1323]: info: [ControllerCarrotRadio::explodeUri] resolved: https://minimw.imbc.com/dmfm/_definst_/mfm.stream/playlist.m3u8?_lsu_sa_=6861A41013EC3854CC49854432E1FA4305A43BC5040CC21B35B0D5a766353676D3a193DD3FF23B4E401A399168b391C8FD6F33B68B180444BCE6AA45AFAF192D910EF031D1A61C06EDFAC13CF295450F2BFE2B1FFE1AF5F498DD652C17BAE878A181D057665C5E4C32B6513596009116 Mar 28 14:33:09 volumio volumio[1323]: info: [_resolveRecursive] 302 -> https://radiolive.sbs.co.kr/powerpc/powerfm.stream/playlist.m3u8?token=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJleHAiOjE3NzQ3MTkxODksInBhdGgiOiIvcG93ZXJmbS5zdHJlYW0iLCJkdXJhdGlvbiI6LTEsInVubyI6IjBiMTE4MjM5LTI5ODctNDMwMS05YTZkLTg1ZjAyYjFjMDNkYyIsImlhdCI6MTc3NDY3NTk4OX0.PIRoR1TLVCOjy62261BJc8im5JHF0BxAVeXHltnrYEA Mar 28 14:33:09 volumio volumio[1323]: info: [ControllerCarrotRadio::explodeUri] resolved: https://radiolive.sbs.co.kr/powerpc/powerfm.stream/playlist.m3u8?token=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJleHAiOjE3NzQ3MTkxODksInBhdGgiOiIvcG93ZXJmbS5zdHJlYW0iLCJkdXJhdGlvbiI6LTEsInVubyI6IjBiMTE4MjM5LTI5ODctNDMwMS05YTZkLTg1ZjAyYjFjMDNkYyIsImlhdCI6MTc3NDY3NTk4OX0.PIRoR1TLVCOjy62261BJc8im5JHF0BxAVeXHltnrYEA Mar 28 14:33:10 volumio volumio[1323]: info: [_resolveRecursive] Content-Type: audio/ogg url: https://mscp3.live-streams.nl:8342/jazz-flac.flac Mar 28 14:33:10 volumio volumio[1323]: info: [ControllerCarrotRadio::explodeUri] resolved: https://mscp3.live-streams.nl:8342/jazz-flac.flac Mar 28 14:33:11 volumio volumio[1323]: info: Preload queue cleared Mar 28 14:33:11 volumio volumio[1323]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 28 14:33:11 volumio volumio[1323]: info: CoreStateMachine::ClearQueue Mar 28 14:33:11 volumio volumio[1323]: info: CoreStateMachine::stop Mar 28 14:33:11 volumio volumio[1323]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 14:33:11 volumio volumio[1323]: info: CoreStateMachine::updateTrackBlock Mar 28 14:33:11 volumio volumio[1323]: info: CorePlayQueue::getTrackBlock Mar 28 14:33:11 volumio volumio[1323]: info: CoreStateMachine::stPlaybackTimer Mar 28 14:33:11 volumio volumio[1323]: info: CoreStateMachine::pushState Mar 28 14:33:11 volumio volumio[1323]: info: CorePlayQueue::getTrack 0 Mar 28 14:33:11 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 14:33:11 volumio volumio[1323]: info: CoreCommandRouter::volumioPushState Mar 28 14:33:11 volumio volumio[1323]: info: CoreStateMachine::serviceStop Mar 28 14:33:11 volumio volumio[1323]: info: CorePlayQueue::getTrack 0 Mar 28 14:33:11 volumio volumio[1323]: info: CoreCommandRouter::serviceStop Mar 28 14:33:11 volumio volumio[1323]: info: ControllerMpd::stop Mar 28 14:33:11 volumio volumio[1323]: verbose: ControllerMpd::sendMpdCommand stop Mar 28 14:33:11 volumio volumio[1323]: info: CorePlayQueue::clearPlayQueue Mar 28 14:33:11 volumio volumio[1323]: info: CorePlayQueue::saveQueue Mar 28 14:33:11 volumio volumio[1323]: info: CoreCommandRouter::volumioPushQueue Mar 28 14:33:11 volumio volumio[1323]: info: CoreStateMachine::addQueueItems Mar 28 14:33:11 volumio volumio[1323]: info: CorePlayQueue::addQueueItems Mar 28 14:33:11 volumio volumio[1323]: info: Preload queue cleared Mar 28 14:33:11 volumio volumio[1323]: info: Adding Item to queue: webcarrot/155 Mar 28 14:33:11 volumio volumio[1323]: info: Using cached record of: webcarrot/155 Mar 28 14:33:11 volumio volumio[1323]: info: Adding Item to queue: webcarrot/157 Mar 28 14:33:11 volumio volumio[1323]: info: Using cached record of: webcarrot/157 Mar 28 14:33:11 volumio volumio[1323]: info: Adding Item to queue: webcarrot/158 Mar 28 14:33:11 volumio volumio[1323]: info: Exploding uri webcarrot/158 in service carrot_radio Mar 28 14:33:11 volumio volumio[1323]: info: [explodeUri] currentStation set: Sveriges Radio P2 program=no Mar 28 14:33:11 volumio volumio[1323]: info: Adding Item to queue: webcarrot/161 Mar 28 14:33:11 volumio volumio[1323]: info: Using cached record of: webcarrot/161 Mar 28 14:33:11 volumio volumio[1323]: info: Adding Item to queue: webcarrot/3 Mar 28 14:33:11 volumio volumio[1323]: info: Using cached record of: webcarrot/3 Mar 28 14:33:11 volumio volumio[1323]: info: Adding Item to queue: webcarrot/7 Mar 28 14:33:11 volumio volumio[1323]: info: Using cached record of: webcarrot/7 Mar 28 14:33:11 volumio volumio[1323]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false Mar 28 14:33:11 volumio volumio[1323]: info: peppy_screensaver: Starting persist timer - 30s Mar 28 14:33:11 volumio volumio[1323]: info: Mar 28 14:33:11 volumio volumio[1323]: ---------------------------- MPD announces state update: player Mar 28 14:33:11 volumio volumio[1323]: info: sendMpdCommand stop took 20 milliseconds Mar 28 14:33:11 volumio volumio[1323]: info: ControllerMpd::getState Mar 28 14:33:11 volumio volumio[1323]: verbose: ControllerMpd::sendMpdCommand status Mar 28 14:33:11 volumio volumio[1323]: info: Mar 28 14:33:11 volumio volumio[1323]: ---------------------------- MPD announces state update: player Mar 28 14:33:11 volumio volumio[1323]: info: ControllerMpd::getState Mar 28 14:33:11 volumio volumio[1323]: verbose: ControllerMpd::sendMpdCommand status Mar 28 14:33:11 volumio volumio[1323]: info: Mar 28 14:33:11 volumio volumio[1323]: ---------------------------- MPD announces state update: player Mar 28 14:33:11 volumio volumio[1323]: info: ControllerMpd::getState Mar 28 14:33:11 volumio volumio[1323]: verbose: ControllerMpd::sendMpdCommand status Mar 28 14:33:11 volumio volumio[1323]: info: Mar 28 14:33:11 volumio volumio[1323]: ---------------------------- MPD announces state update: player Mar 28 14:33:11 volumio volumio[1323]: info: ControllerMpd::getState Mar 28 14:33:11 volumio volumio[1323]: verbose: ControllerMpd::sendMpdCommand status Mar 28 14:33:11 volumio volumio[1323]: info: sendMpdCommand status took 4 milliseconds Mar 28 14:33:11 volumio volumio[1323]: info: sendMpdCommand status took 5 milliseconds Mar 28 14:33:11 volumio volumio[1323]: info: sendMpdCommand status took 4 milliseconds Mar 28 14:33:11 volumio volumio[1323]: info: sendMpdCommand status took 4 milliseconds Mar 28 14:33:11 volumio volumio[1323]: verbose: ControllerMpd::parseState Mar 28 14:33:11 volumio volumio[1323]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 14:33:11 volumio volumio[1323]: verbose: ControllerMpd::parseState Mar 28 14:33:11 volumio volumio[1323]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 14:33:11 volumio volumio[1323]: verbose: ControllerMpd::parseState Mar 28 14:33:11 volumio volumio[1323]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 14:33:11 volumio volumio[1323]: verbose: ControllerMpd::parseState Mar 28 14:33:11 volumio volumio[1323]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 14:33:11 volumio volumio[1323]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 28 14:33:11 volumio volumio[1323]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 28 14:33:11 volumio volumio[1323]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 28 14:33:11 volumio volumio[1323]: verbose: ControllerMpd::parseTrackInfo Mar 28 14:33:11 volumio volumio[1323]: verbose: ControllerMpd::parseTrackInfo Mar 28 14:33:11 volumio volumio[1323]: verbose: ControllerMpd::parseTrackInfo Mar 28 14:33:11 volumio volumio[1323]: info: ControllerMpd::pushState Mar 28 14:33:11 volumio volumio[1323]: info: CoreCommandRouter::servicePushState Mar 28 14:33:11 volumio volumio[1323]: info: CoreStateMachine::pushState Mar 28 14:33:11 volumio volumio[1323]: info: CorePlayQueue::getTrack 0 Mar 28 14:33:11 volumio volumio[1323]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 14:33:11 volumio volumio[1323]: info: CoreCommandRouter::volumioPushState Mar 28 14:33:11 volumio volumio[1323]: info: CorePlayQueue::getTrack 0 Mar 28 14:33:11 volumio volumio[1323]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Autumn Leaves","artist":"Miles Davis","album":"Somethin' Else (Rudy Van Gelder Edition)","uri":"USB/MQA/Cannonball Adderley - Somethin' Else (Rudy Van Gelder Edition)/01. Autumn Leaves.flac","trackType":"flac"} Mar 28 14:33:11 volumio volumio[1323]: verbose: CURRENT POSITION 0 Mar 28 14:33:11 volumio volumio[1323]: info: CoreStateMachine::syncState stateService stop Mar 28 14:33:11 volumio volumio[1323]: info: CoreStateMachine::syncState currentStatus stop Mar 28 14:33:11 volumio volumio[1323]: info: CoreStateMachine::pushState Mar 28 14:33:11 volumio volumio[1323]: info: CorePlayQueue::getTrack 0 Mar 28 14:33:11 volumio volumio[1323]: info: CoreCommandRouter::volumioPushState Mar 28 14:33:11 volumio volumio[1323]: info: No code Mar 28 14:33:11 volumio volumio[1323]: info: CoreStateMachine::pushState Mar 28 14:33:11 volumio volumio[1323]: info: CorePlayQueue::getTrack 0 Mar 28 14:33:11 volumio volumio[1323]: info: CoreCommandRouter::volumioPushState Mar 28 14:33:11 volumio volumio[1323]: info: ControllerMpd::pushState Mar 28 14:33:11 volumio volumio[1323]: info: CoreCommandRouter::servicePushState Mar 28 14:33:11 volumio volumio[1323]: info: CoreStateMachine::pushState Mar 28 14:33:11 volumio volumio[1323]: info: CorePlayQueue::getTrack 0 Mar 28 14:33:11 volumio volumio[1323]: info: CoreCommandRouter::volumioPushState Mar 28 14:33:11 volumio volumio[1323]: info: CorePlayQueue::getTrack 0 Mar 28 14:33:11 volumio volumio[1323]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Autumn Leaves","artist":"Miles Davis","album":"Somethin' Else (Rudy Van Gelder Edition)","uri":"USB/MQA/Cannonball Adderley - Somethin' Else (Rudy Van Gelder Edition)/01. Autumn Leaves.flac","trackType":"flac"} Mar 28 14:33:11 volumio volumio[1323]: verbose: CURRENT POSITION 0 Mar 28 14:33:11 volumio volumio[1323]: info: CoreStateMachine::syncState stateService stop Mar 28 14:33:11 volumio volumio[1323]: info: CoreStateMachine::syncState currentStatus stop Mar 28 14:33:11 volumio volumio[1323]: info: CoreStateMachine::pushState Mar 28 14:33:11 volumio volumio[1323]: info: CorePlayQueue::getTrack 0 Mar 28 14:33:11 volumio volumio[1323]: info: CoreCommandRouter::volumioPushState Mar 28 14:33:11 volumio volumio[1323]: info: No code Mar 28 14:33:11 volumio volumio[1323]: info: CoreStateMachine::pushState Mar 28 14:33:11 volumio volumio[1323]: info: CorePlayQueue::getTrack 0 Mar 28 14:33:11 volumio volumio[1323]: info: CoreCommandRouter::volumioPushState Mar 28 14:33:11 volumio volumio[1323]: info: ControllerMpd::pushState Mar 28 14:33:11 volumio volumio[1323]: info: CoreCommandRouter::servicePushState Mar 28 14:33:11 volumio volumio[1323]: info: CoreStateMachine::pushState Mar 28 14:33:11 volumio volumio[1323]: info: CorePlayQueue::getTrack 0 Mar 28 14:33:11 volumio volumio[1323]: info: CoreCommandRouter::volumioPushState Mar 28 14:33:11 volumio volumio[1323]: info: CorePlayQueue::getTrack 0 Mar 28 14:33:11 volumio volumio[1323]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Autumn Leaves","artist":"Miles Davis","album":"Somethin' Else (Rudy Van Gelder Edition)","uri":"USB/MQA/Cannonball Adderley - Somethin' Else (Rudy Van Gelder Edition)/01. Autumn Leaves.flac","trackType":"flac"} Mar 28 14:33:11 volumio volumio[1323]: verbose: CURRENT POSITION 0 Mar 28 14:33:11 volumio volumio[1323]: info: CoreStateMachine::syncState stateService stop Mar 28 14:33:11 volumio volumio[1323]: info: CoreStateMachine::syncState currentStatus stop Mar 28 14:33:11 volumio volumio[1323]: info: CoreStateMachine::pushState Mar 28 14:33:11 volumio volumio[1323]: info: CorePlayQueue::getTrack 0 Mar 28 14:33:11 volumio volumio[1323]: info: CoreCommandRouter::volumioPushState Mar 28 14:33:11 volumio volumio[1323]: info: No code Mar 28 14:33:11 volumio volumio[1323]: info: CoreStateMachine::pushState Mar 28 14:33:11 volumio volumio[1323]: info: CorePlayQueue::getTrack 0 Mar 28 14:33:11 volumio volumio[1323]: info: CoreCommandRouter::volumioPushState Mar 28 14:33:11 volumio volumio[1323]: info: ------------------------------ 49ms Mar 28 14:33:11 volumio volumio[1323]: info: ------------------------------ 50ms Mar 28 14:33:11 volumio volumio[1323]: info: ------------------------------ 49ms Mar 28 14:33:11 volumio volumio[1323]: info: sendMpdCommand playlistinfo took 46 milliseconds Mar 28 14:33:11 volumio volumio[1323]: verbose: ControllerMpd::parseTrackInfo Mar 28 14:33:11 volumio volumio[1323]: info: ControllerMpd::pushState Mar 28 14:33:11 volumio volumio[1323]: info: CoreCommandRouter::servicePushState Mar 28 14:33:11 volumio volumio[1323]: info: CoreStateMachine::pushState Mar 28 14:33:11 volumio volumio[1323]: info: CorePlayQueue::getTrack 0 Mar 28 14:33:11 volumio volumio[1323]: info: CoreCommandRouter::volumioPushState Mar 28 14:33:11 volumio volumio[1323]: info: CorePlayQueue::getTrack 0 Mar 28 14:33:11 volumio volumio[1323]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Autumn Leaves","artist":"Miles Davis","album":"Somethin' Else (Rudy Van Gelder Edition)","uri":"USB/MQA/Cannonball Adderley - Somethin' Else (Rudy Van Gelder Edition)/01. Autumn Leaves.flac","trackType":"flac"} Mar 28 14:33:11 volumio volumio[1323]: verbose: CURRENT POSITION 0 Mar 28 14:33:11 volumio volumio[1323]: info: CoreStateMachine::syncState stateService stop Mar 28 14:33:11 volumio volumio[1323]: info: CoreStateMachine::syncState currentStatus stop Mar 28 14:33:11 volumio volumio[1323]: info: CoreStateMachine::pushState Mar 28 14:33:11 volumio volumio[1323]: info: CorePlayQueue::getTrack 0 Mar 28 14:33:11 volumio volumio[1323]: info: CoreCommandRouter::volumioPushState Mar 28 14:33:11 volumio volumio[1323]: info: No code Mar 28 14:33:11 volumio volumio[1323]: info: CoreStateMachine::pushState Mar 28 14:33:11 volumio volumio[1323]: info: CorePlayQueue::getTrack 0 Mar 28 14:33:11 volumio volumio[1323]: info: CoreCommandRouter::volumioPushState Mar 28 14:33:11 volumio volumio[1323]: info: ------------------------------ 70ms Mar 28 14:33:11 volumio volumio[1323]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=undefined Mar 28 14:33:11 volumio volumio[1323]: info: peppy_screensaver: pushState: transitional stop (volatile=undefined, getEmptyState), cleared persist file Mar 28 14:33:11 volumio volumio[1323]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=undefined Mar 28 14:33:11 volumio volumio[1323]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=undefined Mar 28 14:33:11 volumio volumio[1323]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=undefined Mar 28 14:33:11 volumio volumio[1323]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=undefined Mar 28 14:33:11 volumio volumio[1323]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=undefined Mar 28 14:33:11 volumio volumio[1323]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=undefined Mar 28 14:33:11 volumio volumio[1323]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=undefined Mar 28 14:33:11 volumio volumio[1323]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=undefined Mar 28 14:33:11 volumio volumio[1323]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=undefined Mar 28 14:33:11 volumio volumio[1323]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=undefined Mar 28 14:33:11 volumio volumio[1323]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=undefined Mar 28 14:33:14 volumio volumio[1323]: error: [resolveStreamUrl] failed: Error: timeout, fallback to: https://live1.sr.se/p2-flac Mar 28 14:33:14 volumio volumio[1323]: info: [ControllerCarrotRadio::explodeUri] resolved: https://live1.sr.se/p2-flac Mar 28 14:33:14 volumio volumio[1323]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 28 14:33:14 volumio volumio[1323]: Error: Unable to resolve or reject the same promise twice Mar 28 14:33:14 volumio volumio[1323]: at Promise.reject (/data/plugins/music_service/carrot_radio/node_modules/kew/kew.js:189:43) Mar 28 14:33:14 volumio volumio[1323]: at ClientRequest. (/data/plugins/music_service/carrot_radio/index.js:739:39) Mar 28 14:33:14 volumio volumio[1323]: at ClientRequest.emit (node:events:514:28) Mar 28 14:33:14 volumio volumio[1323]: at TLSSocket.socketCloseListener (node:_http_client:468:11) Mar 28 14:33:14 volumio volumio[1323]: at TLSSocket.emit (node:events:526:35) Mar 28 14:33:14 volumio volumio[1323]: at node:net:337:12 Mar 28 14:33:14 volumio volumio[1323]: at TCP.done (node:_tls_wrap:631:7) Mar 28 14:33:14 volumio volumio[1323]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 28 14:33:14 volumio sudo[4226]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-28 14:32' Mar 28 14:33:14 volumio sudo[4226]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"