Dec 20 17:00:00 volumio607 volumio[1318]: info: Initializing connection to go-librespot Websocket Dec 20 17:00:00 volumio607 volumio[1318]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 20 17:00:01 volumio607 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Dec 20 17:00:01 volumio607 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 20 17:00:01 volumio607 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 20 17:00:01 volumio607 go-librespot[1631]: go-librespot daemon starting... Dec 20 17:00:01 volumio607 go-librespot[1632]: time="2025-12-20T17:00:01+08:00" level=info msg="running go-librespot 0.4.0" Dec 20 17:00:01 volumio607 go-librespot[1632]: time="2025-12-20T17:00:01+08:00" level=debug msg="app state loaded" Dec 20 17:00:01 volumio607 go-librespot[1632]: time="2025-12-20T17:00:01+08:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 20 17:00:01 volumio607 go-librespot[1632]: time="2025-12-20T17:00:01+08:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 20 17:00:01 volumio607 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 20 17:00:01 volumio607 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 20 17:00:01 volumio607 volumio[1318]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=4.073&uuid=4ca6c72589cfa599966ff016238eabd7" http://updates.volumio.org/downloader-v1/track-device Dec 20 17:00:01 volumio607 volumio[1318]: % Total % Received % Xferd Average Speed Time Time Time Current Dec 20 17:00:01 volumio607 volumio[1318]: Dload Upload Total Spent Left Speed Dec 20 17:00:01 volumio607 volumio[1318]: [132B blob data] Dec 20 17:00:01 volumio607 volumio[1318]: retrying in 5 seconds, trial 0 Dec 20 17:00:01 volumio607 volumio[1318]: info: Volumio Calling Home Dec 20 17:00:03 volumio607 volumio[1318]: info: Initializing connection to go-librespot Websocket Dec 20 17:00:03 volumio607 volumio[1318]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 20 17:00:04 volumio607 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Dec 20 17:00:04 volumio607 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 20 17:00:04 volumio607 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 20 17:00:04 volumio607 go-librespot[1641]: go-librespot daemon starting... Dec 20 17:00:04 volumio607 go-librespot[1642]: time="2025-12-20T17:00:04+08:00" level=info msg="running go-librespot 0.4.0" Dec 20 17:00:04 volumio607 go-librespot[1642]: time="2025-12-20T17:00:04+08:00" level=debug msg="app state loaded" Dec 20 17:00:04 volumio607 go-librespot[1642]: time="2025-12-20T17:00:04+08:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 20 17:00:04 volumio607 go-librespot[1642]: time="2025-12-20T17:00:04+08:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 20 17:00:04 volumio607 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 20 17:00:04 volumio607 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 20 17:00:06 volumio607 volumio[1318]: info: Initializing connection to go-librespot Websocket Dec 20 17:00:06 volumio607 volumio[1318]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 20 17:00:07 volumio607 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Dec 20 17:00:07 volumio607 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 20 17:00:07 volumio607 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 20 17:00:07 volumio607 go-librespot[1648]: go-librespot daemon starting... Dec 20 17:00:07 volumio607 go-librespot[1649]: time="2025-12-20T17:00:07+08:00" level=info msg="running go-librespot 0.4.0" Dec 20 17:00:07 volumio607 go-librespot[1649]: time="2025-12-20T17:00:07+08:00" level=debug msg="app state loaded" Dec 20 17:00:07 volumio607 go-librespot[1649]: time="2025-12-20T17:00:07+08:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 20 17:00:07 volumio607 go-librespot[1649]: time="2025-12-20T17:00:07+08:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 20 17:00:07 volumio607 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 20 17:00:07 volumio607 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 20 17:00:09 volumio607 volumio[1318]: info: Initializing connection to go-librespot Websocket Dec 20 17:00:09 volumio607 volumio[1318]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 20 17:00:10 volumio607 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Dec 20 17:00:10 volumio607 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 20 17:00:10 volumio607 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 20 17:00:10 volumio607 go-librespot[1669]: go-librespot daemon starting... Dec 20 17:00:10 volumio607 go-librespot[1670]: time="2025-12-20T17:00:10+08:00" level=info msg="running go-librespot 0.4.0" Dec 20 17:00:10 volumio607 go-librespot[1670]: time="2025-12-20T17:00:10+08:00" level=debug msg="app state loaded" Dec 20 17:00:10 volumio607 go-librespot[1670]: time="2025-12-20T17:00:10+08:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 20 17:00:10 volumio607 go-librespot[1670]: time="2025-12-20T17:00:10+08:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 20 17:00:10 volumio607 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 20 17:00:10 volumio607 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 20 17:00:11 volumio607 volumio[1318]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=4.073&uuid=4ca6c72589cfa599966ff016238eabd7" http://updates.volumio.org/downloader-v1/track-device Dec 20 17:00:11 volumio607 volumio[1318]: % Total % Received % Xferd Average Speed Time Time Time Current Dec 20 17:00:11 volumio607 volumio[1318]: Dload Upload Total Spent Left Speed Dec 20 17:00:11 volumio607 volumio[1318]: [132B blob data] Dec 20 17:00:11 volumio607 volumio[1318]: retrying in 5 seconds, trial 1 Dec 20 17:00:11 volumio607 volumio[1318]: info: Volumio Calling Home Dec 20 17:00:11 volumio607 systemd[1]: systemd-fsckd.service: Deactivated successfully. Dec 20 17:00:12 volumio607 volumio[1318]: error: MyVolumio Plugin failed to start in a timely fashion Dec 20 17:00:12 volumio607 volumio[1318]: [Metrics] CommandRouter: 23s 521.31ms Dec 20 17:00:12 volumio607 volumio[1318]: info: CoreCommandRouter::volumiosetStartupVolume Dec 20 17:00:12 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 17:00:12 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 17:00:12 volumio607 volumio[1318]: info: CoreCommandRouter::Close All Modals sent Dec 20 17:00:12 volumio607 volumio[1318]: info: CoreCommandRouter::Close All Modals sent Dec 20 17:00:12 volumio607 volumio[1318]: info: Initializing connection to go-librespot Websocket Dec 20 17:00:12 volumio607 volumio[1318]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 20 17:00:12 volumio607 dhcpcd[931]: timed out Dec 20 17:00:12 volumio607 sh[916]: timed out Dec 20 17:00:12 volumio607 sh[849]: ifup: failed to bring up eth0 Dec 20 17:00:12 volumio607 systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Dec 20 17:00:12 volumio607 systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Dec 20 17:00:13 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Dec 20 17:00:13 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 20 17:00:13 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Dec 20 17:00:14 volumio607 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Dec 20 17:00:14 volumio607 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 20 17:00:14 volumio607 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 20 17:00:14 volumio607 go-librespot[1685]: go-librespot daemon starting... Dec 20 17:00:14 volumio607 systemd[1]: systemd-hostnamed.service: Deactivated successfully. Dec 20 17:00:14 volumio607 go-librespot[1686]: time="2025-12-20T17:00:14+08:00" level=info msg="running go-librespot 0.4.0" Dec 20 17:00:14 volumio607 go-librespot[1686]: time="2025-12-20T17:00:14+08:00" level=debug msg="app state loaded" Dec 20 17:00:14 volumio607 go-librespot[1686]: time="2025-12-20T17:00:14+08:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 20 17:00:14 volumio607 go-librespot[1686]: time="2025-12-20T17:00:14+08:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 20 17:00:14 volumio607 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 20 17:00:14 volumio607 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 20 17:00:14 volumio607 systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. Dec 20 17:00:14 volumio607 systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 20 17:00:14 volumio607 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 20 17:00:14 volumio607 sudo[1628]: pam_unix(sudo:session): session closed for user root Dec 20 17:00:14 volumio607 volumio[1318]: info: Upmpdcli Daemon Started Dec 20 17:00:15 volumio607 volumio[1318]: info: Initializing connection to go-librespot Websocket Dec 20 17:00:15 volumio607 volumio[1318]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 20 17:00:17 volumio607 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Dec 20 17:00:17 volumio607 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 20 17:00:17 volumio607 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 20 17:00:17 volumio607 go-librespot[1706]: go-librespot daemon starting... Dec 20 17:00:17 volumio607 go-librespot[1707]: time="2025-12-20T17:00:17+08:00" level=info msg="running go-librespot 0.4.0" Dec 20 17:00:17 volumio607 go-librespot[1707]: time="2025-12-20T17:00:17+08:00" level=debug msg="app state loaded" Dec 20 17:00:17 volumio607 go-librespot[1707]: time="2025-12-20T17:00:17+08:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 20 17:00:17 volumio607 go-librespot[1707]: time="2025-12-20T17:00:17+08:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 20 17:00:17 volumio607 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 20 17:00:17 volumio607 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 20 17:00:18 volumio607 volumio[1318]: info: Initializing connection to go-librespot Websocket Dec 20 17:00:18 volumio607 volumio[1318]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 20 17:00:19 volumio607 volumio[1318]: info: BOOT COMPLETED Dec 20 17:00:20 volumio607 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Dec 20 17:00:20 volumio607 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 20 17:00:20 volumio607 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 20 17:00:20 volumio607 go-librespot[1727]: go-librespot daemon starting... Dec 20 17:00:20 volumio607 go-librespot[1728]: time="2025-12-20T17:00:20+08:00" level=info msg="running go-librespot 0.4.0" Dec 20 17:00:20 volumio607 go-librespot[1728]: time="2025-12-20T17:00:20+08:00" level=debug msg="app state loaded" Dec 20 17:00:20 volumio607 go-librespot[1728]: time="2025-12-20T17:00:20+08:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 20 17:00:20 volumio607 go-librespot[1728]: time="2025-12-20T17:00:20+08:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 20 17:00:20 volumio607 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 20 17:00:20 volumio607 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 20 17:00:21 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 20 17:00:21 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 17:00:21 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 20 17:00:21 volumio607 volumio[1318]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=4.073&uuid=4ca6c72589cfa599966ff016238eabd7" http://updates.volumio.org/downloader-v1/track-device Dec 20 17:00:21 volumio607 volumio[1318]: % Total % Received % Xferd Average Speed Time Time Time Current Dec 20 17:00:21 volumio607 volumio[1318]: Dload Upload Total Spent Left Speed Dec 20 17:00:21 volumio607 volumio[1318]: [132B blob data] Dec 20 17:00:21 volumio607 volumio[1318]: retrying in 5 seconds, trial 2 Dec 20 17:00:21 volumio607 volumio[1318]: info: Volumio Calling Home Dec 20 17:00:21 volumio607 volumio[1318]: info: Initializing connection to go-librespot Websocket Dec 20 17:00:21 volumio607 volumio[1318]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 20 17:00:23 volumio607 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10. Dec 20 17:00:23 volumio607 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 20 17:00:23 volumio607 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 20 17:00:23 volumio607 go-librespot[1740]: go-librespot daemon starting... Dec 20 17:00:23 volumio607 go-librespot[1741]: time="2025-12-20T17:00:23+08:00" level=info msg="running go-librespot 0.4.0" Dec 20 17:00:23 volumio607 go-librespot[1741]: time="2025-12-20T17:00:23+08:00" level=debug msg="app state loaded" Dec 20 17:00:23 volumio607 go-librespot[1741]: time="2025-12-20T17:00:23+08:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 20 17:00:23 volumio607 go-librespot[1741]: time="2025-12-20T17:00:23+08:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 20 17:00:23 volumio607 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 20 17:00:23 volumio607 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 20 17:00:24 volumio607 volumio[1318]: info: Initializing connection to go-librespot Websocket Dec 20 17:00:24 volumio607 volumio[1318]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 20 17:00:26 volumio607 dhcpcd[1086]: eth0: carrier acquired Dec 20 17:00:26 volumio607 kernel: macb 1f00100000.ethernet eth0: Link is Up - 100Mbps/Full - flow control off Dec 20 17:00:26 volumio607 dhcpcd[1086]: eth0: IAID 67:2b:e9:21 Dec 20 17:00:26 volumio607 dhcpcd[1086]: eth0: adding address fe80::2ecf:67ff:fe2b:e921 Dec 20 17:00:26 volumio607 dhcpcd[1086]: ipv6_addaddr1: Permission denied Dec 20 17:00:26 volumio607 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 11. Dec 20 17:00:26 volumio607 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 20 17:00:26 volumio607 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 20 17:00:26 volumio607 go-librespot[1748]: go-librespot daemon starting... Dec 20 17:00:26 volumio607 go-librespot[1749]: time="2025-12-20T17:00:26+08:00" level=info msg="running go-librespot 0.4.0" Dec 20 17:00:26 volumio607 go-librespot[1749]: time="2025-12-20T17:00:26+08:00" level=debug msg="app state loaded" Dec 20 17:00:26 volumio607 go-librespot[1749]: time="2025-12-20T17:00:26+08:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 20 17:00:26 volumio607 go-librespot[1749]: time="2025-12-20T17:00:26+08:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 20 17:00:26 volumio607 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 20 17:00:26 volumio607 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 20 17:00:27 volumio607 dhcpcd[1086]: eth0: soliciting a DHCP lease Dec 20 17:00:27 volumio607 ifplugd(eth0)[1188]: Link beat detected. Dec 20 17:00:27 volumio607 volumio[1318]: info: Initializing connection to go-librespot Websocket Dec 20 17:00:27 volumio607 volumio[1318]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 20 17:00:27 volumio607 ifplugd(eth0)[1188]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Dec 20 17:00:27 volumio607 ifplugd(eth0)[1188]: client: sending commands to dhcpcd process Dec 20 17:00:27 volumio607 dhcpcd[1086]: control_free: No such file or directory Dec 20 17:00:27 volumio607 dhcpcd[1086]: control command: dhcpcd eth0 Dec 20 17:00:27 volumio607 ifplugd(eth0)[1188]: Program executed successfully. Dec 20 17:00:27 volumio607 dhcpcd[1086]: eth0: soliciting an IPv6 router Dec 20 17:00:30 volumio607 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 12. Dec 20 17:00:30 volumio607 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 20 17:00:30 volumio607 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 20 17:00:30 volumio607 go-librespot[1819]: go-librespot daemon starting... Dec 20 17:00:30 volumio607 systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Dec 20 17:00:30 volumio607 go-librespot[1820]: time="2025-12-20T17:00:30+08:00" level=info msg="running go-librespot 0.4.0" Dec 20 17:00:30 volumio607 go-librespot[1820]: time="2025-12-20T17:00:30+08:00" level=debug msg="app state loaded" Dec 20 17:00:30 volumio607 go-librespot[1820]: time="2025-12-20T17:00:30+08:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 20 17:00:30 volumio607 go-librespot[1820]: time="2025-12-20T17:00:30+08:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 20 17:00:30 volumio607 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 20 17:00:30 volumio607 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 20 17:00:30 volumio607 volumio[1318]: info: Initializing connection to go-librespot Websocket Dec 20 17:00:30 volumio607 volumio[1318]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 20 17:00:31 volumio607 dhcpcd[1086]: eth0: offered 192.168.0.16 from 192.168.0.1 Dec 20 17:00:31 volumio607 dhcpcd[1086]: eth0: probing address 192.168.0.16/24 Dec 20 17:00:33 volumio607 volumio[1318]: info: Initializing connection to go-librespot Websocket Dec 20 17:00:33 volumio607 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13. Dec 20 17:00:33 volumio607 volumio[1318]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 20 17:00:33 volumio607 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 20 17:00:33 volumio607 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 20 17:00:33 volumio607 go-librespot[1867]: go-librespot daemon starting... Dec 20 17:00:33 volumio607 go-librespot[1868]: time="2025-12-20T17:00:33+08:00" level=info msg="running go-librespot 0.4.0" Dec 20 17:00:33 volumio607 go-librespot[1868]: time="2025-12-20T17:00:33+08:00" level=debug msg="app state loaded" Dec 20 17:00:33 volumio607 go-librespot[1868]: time="2025-12-20T17:00:33+08:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 20 17:00:33 volumio607 go-librespot[1868]: time="2025-12-20T17:00:33+08:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 20 17:00:33 volumio607 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 20 17:00:33 volumio607 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 20 17:00:35 volumio607 bash[1828]: setdatetime-helper: all HTTPS Date fallbacks failed Dec 20 17:00:35 volumio607 systemd[1]: setdatetime-helper.service: Deactivated successfully. Dec 20 17:00:35 volumio607 systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Dec 20 17:00:36 volumio607 volumio[1318]: info: Initializing connection to go-librespot Websocket Dec 20 17:00:36 volumio607 volumio[1318]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 20 17:00:36 volumio607 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 14. Dec 20 17:00:36 volumio607 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 20 17:00:36 volumio607 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 20 17:00:36 volumio607 go-librespot[1884]: go-librespot daemon starting... Dec 20 17:00:36 volumio607 go-librespot[1885]: time="2025-12-20T17:00:36+08:00" level=info msg="running go-librespot 0.4.0" Dec 20 17:00:36 volumio607 go-librespot[1885]: time="2025-12-20T17:00:36+08:00" level=debug msg="app state loaded" Dec 20 17:00:36 volumio607 go-librespot[1885]: time="2025-12-20T17:00:36+08:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 20 17:00:36 volumio607 go-librespot[1885]: time="2025-12-20T17:00:36+08:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 20 17:00:36 volumio607 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 20 17:00:36 volumio607 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 20 17:00:36 volumio607 dhcpcd[1086]: eth0: leased 192.168.0.16 for 86400 seconds Dec 20 17:00:36 volumio607 avahi-daemon[1008]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.0.16. Dec 20 17:00:36 volumio607 avahi-daemon[1008]: New relevant interface eth0.IPv4 for mDNS. Dec 20 17:00:36 volumio607 avahi-daemon[1008]: Registering new address record for 192.168.0.16 on eth0.IPv4. Dec 20 17:00:36 volumio607 dhcpcd[1086]: eth0: adding route to 192.168.0.0/24 Dec 20 17:00:36 volumio607 dhcpcd[1086]: eth0: adding default route via 192.168.0.1 Dec 20 17:00:36 volumio607 systemd[1]: welcome.service: Deactivated successfully. Dec 20 17:00:36 volumio607 systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 20 17:00:36 volumio607 systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 20 17:00:37 volumio607 systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 20 17:00:37 volumio607 welcome[1910]: Resolved ip:[1] 192.168.0.16 Dec 20 17:00:37 volumio607 systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 20 17:00:37 volumio607 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 20 17:00:37 volumio607 systemd[1]: Started nmbd.service - Samba NMB Daemon. Dec 20 17:00:37 volumio607 systemd[1]: Starting winbind.service - Samba Winbind Daemon... Dec 20 17:00:37 volumio607 winbindd[1918]: [2025/12/20 17:00:37.208554, 0] ../../source3/winbindd/winbindd.c:1440(main) Dec 20 17:00:37 volumio607 winbindd[1918]: winbindd version 4.17.8-Raspbian started. Dec 20 17:00:37 volumio607 winbindd[1918]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Dec 20 17:00:37 volumio607 winbindd[1918]: [2025/12/20 17:00:37.213396, 0] ../../source3/winbindd/winbindd_cache.c:3116(initialize_winbindd_cache) Dec 20 17:00:37 volumio607 winbindd[1918]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Dec 20 17:00:37 volumio607 systemd[1]: Started winbind.service - Samba Winbind Daemon. Dec 20 17:00:37 volumio607 systemd[1]: Starting smbd.service - Samba SMB Daemon... Dec 20 17:00:37 volumio607 smbd[1935]: [2025/12/20 17:00:37.375643, 0] ../../source3/smbd/server.c:1741(main) Dec 20 17:00:37 volumio607 smbd[1935]: smbd version 4.17.8-Raspbian started. Dec 20 17:00:37 volumio607 smbd[1935]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Dec 20 17:00:37 volumio607 systemd[1]: Started smbd.service - Samba SMB Daemon. Dec 20 17:00:37 volumio607 systemd[1]: Reached target multi-user.target - Multi-User System. Dec 20 17:00:37 volumio607 systemd[1]: Reached target graphical.target - Graphical Interface. Dec 20 17:00:37 volumio607 systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP... Dec 20 17:00:37 volumio607 systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Dec 20 17:00:37 volumio607 systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP. Dec 20 17:00:37 volumio607 systemd[1]: Startup finished in 11.349s (kernel) + 56.378s (userspace) = 1min 7.727s. Dec 20 17:00:37 volumio607 volumio[1318]: info: Discovery: this is already registered, f96e122f-1b37-416d-9d32-cde5b90bafcd Dec 20 17:00:37 volumio607 volumio[1318]: info: Discovery: Found device Volumio607 Dec 20 17:00:37 volumio607 volumio[1318]: info: CoreCommandRouter::volumioGetState Dec 20 17:00:37 volumio607 volumio[1318]: info: CorePlayQueue::getTrack 0 Dec 20 17:00:38 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 20 17:00:38 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 17:00:38 volumio607 volumio[1318]: info: Discovery: Getting this device information Dec 20 17:00:38 volumio607 volumio[1318]: info: CoreCommandRouter::volumioGetState Dec 20 17:00:38 volumio607 volumio[1318]: info: CorePlayQueue::getTrack 0 Dec 20 17:00:38 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 17:00:38 volumio607 volumio[1318]: verbose: New Socket.io Connection to 192.168.0.16:3000 from 192.168.0.17 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 3 Dec 20 17:00:38 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 20 17:00:38 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 20 17:00:38 volumio607 ntpd[1173]: IO: Listen normally on 3 eth0 192.168.0.16:123 Dec 20 17:00:38 volumio607 ntpd[1173]: IO: new interface(s) found: waking up resolver Dec 20 17:00:38 volumio607 ntpd[1173]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 20 17:00:38 volumio607 ntpd[1173]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Dec 20 17:00:38 volumio607 ntpd[1173]: DNS: Pool taking: 202.155.10.23 Dec 20 17:00:38 volumio607 ntpd[1173]: DNS: Pool taking: 111.90.158.16 Dec 20 17:00:38 volumio607 ntpd[1173]: DNS: Pool taking: 103.16.182.23 Dec 20 17:00:38 volumio607 ntpd[1173]: DNS: Pool taking: 111.90.158.134 Dec 20 17:00:38 volumio607 ntpd[1173]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 Dec 20 17:00:38 volumio607 volumio[1318]: verbose: New Socket.io Connection to 192.168.0.16 from 192.168.0.10 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Edg/143.0.0.0 Engine version: 3 Transport: polling Total Clients: 3 Dec 20 17:00:38 volumio607 volumio[1318]: verbose: New Socket.io Connection to 192.168.0.16 from 192.168.0.10 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Edg/143.0.0.0 Engine version: 3 Transport: polling Total Clients: 4 Dec 20 17:00:38 volumio607 volumio[1318]: verbose: New Socket.io Connection to 192.168.0.16 from 192.168.0.10 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Edg/143.0.0.0 Engine version: 3 Transport: polling Total Clients: 5 Dec 20 17:00:38 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 20 17:00:38 volumio607 volumio[1318]: info: CoreCommandRouter::volumioGetVisibleSources Dec 20 17:00:38 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 17:00:38 volumio607 volumio[1318]: info: CoreCommandRouter::volumioGetState Dec 20 17:00:38 volumio607 volumio[1318]: info: CorePlayQueue::getTrack 0 Dec 20 17:00:38 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 20 17:00:38 volumio607 volumio[1318]: info: CoreCommandRouter::volumioGetQueue Dec 20 17:00:38 volumio607 volumio[1318]: info: CoreStateMachine::getQueue Dec 20 17:00:38 volumio607 volumio[1318]: info: CorePlayQueue::getQueue Dec 20 17:00:38 volumio607 volumio[1318]: info: Listing playlists Dec 20 17:00:38 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 20 17:00:38 volumio607 volumio[1318]: info: Received Get System Info Dec 20 17:00:38 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 20 17:00:38 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 17:00:38 volumio607 volumio[1318]: info: Discovery: Getting this device information Dec 20 17:00:38 volumio607 volumio[1318]: info: CoreCommandRouter::volumioGetState Dec 20 17:00:38 volumio607 volumio[1318]: info: CorePlayQueue::getTrack 0 Dec 20 17:00:38 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 17:00:38 volumio607 volumio[1318]: info: CoreCommandRouter::volumioGetState Dec 20 17:00:38 volumio607 volumio[1318]: info: CorePlayQueue::getTrack 0 Dec 20 17:00:39 volumio607 volumio[1318]: verbose: New Socket.io Connection to 192.168.0.16:3000 from 192.168.0.17 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Dec 20 17:00:39 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 20 17:00:39 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 17:00:39 volumio607 volumio[1318]: info: Discovery: Getting this device information Dec 20 17:00:39 volumio607 volumio[1318]: info: CoreCommandRouter::volumioGetState Dec 20 17:00:39 volumio607 volumio[1318]: info: CorePlayQueue::getTrack 0 Dec 20 17:00:39 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 17:00:39 volumio607 ntpd[1173]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 20 17:00:39 volumio607 volumio[1318]: info: Initializing connection to go-librespot Websocket Dec 20 17:00:39 volumio607 volumio[1318]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 20 17:00:39 volumio607 volumio[1318]: info: Volumio Network Manager: Network status updated: 1 Dec 20 17:00:39 volumio607 ntpd[1173]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Dec 20 17:00:39 volumio607 ntpd[1173]: DNS: Pool skipping: 202.155.10.23 Dec 20 17:00:39 volumio607 ntpd[1173]: DNS: Pool skipping: 111.90.158.16 Dec 20 17:00:39 volumio607 ntpd[1173]: DNS: Pool skipping: 111.90.158.134 Dec 20 17:00:39 volumio607 ntpd[1173]: DNS: Pool taking: 47.254.196.78 Dec 20 17:00:39 volumio607 ntpd[1173]: DNS: Pool taking: 240b:400e:3b:7401:e706:8294:461f:7f12 Dec 20 17:00:39 volumio607 ntpd[1173]: DNS: Pool taking: 240b:400e:11b:f700::123 Dec 20 17:00:39 volumio607 ntpd[1173]: DNS: Pool taking: 240b:400e:5d:6d00:46e6:5a9e:75d6:461f Dec 20 17:00:39 volumio607 ntpd[1173]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 Dec 20 17:00:39 volumio607 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15. Dec 20 17:00:39 volumio607 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 20 17:00:39 volumio607 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 20 17:00:39 volumio607 go-librespot[1962]: go-librespot daemon starting... Dec 20 17:00:39 volumio607 go-librespot[1963]: time="2025-12-20T17:00:39+08:00" level=info msg="running go-librespot 0.4.0" Dec 20 17:00:39 volumio607 go-librespot[1963]: time="2025-12-20T17:00:39+08:00" level=debug msg="app state loaded" Dec 20 17:00:39 volumio607 go-librespot[1963]: time="2025-12-20T17:00:39+08:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 20 17:00:40 volumio607 ntpd[1173]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 20 17:00:40 volumio607 ntpd[1173]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Dec 20 17:00:40 volumio607 ntpd[1173]: DNS: Pool skipping: 202.155.10.23 Dec 20 17:00:40 volumio607 ntpd[1173]: DNS: Pool skipping: 111.90.158.16 Dec 20 17:00:40 volumio607 ntpd[1173]: DNS: Pool skipping: 103.16.182.23 Dec 20 17:00:40 volumio607 ntpd[1173]: DNS: Pool skipping: 111.90.158.134 Dec 20 17:00:40 volumio607 ntpd[1173]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 Dec 20 17:00:40 volumio607 go-librespot[1963]: time="2025-12-20T17:00:40+08:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 20 17:00:40 volumio607 go-librespot[1963]: time="2025-12-20T17:00:40+08:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 20 17:00:40 volumio607 go-librespot[1963]: time="2025-12-20T17:00:40+08:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 20 17:00:40 volumio607 go-librespot[1963]: time="2025-12-20T17:00:40+08:00" level=info msg="zeroconf server listening on port 36079" Dec 20 17:00:40 volumio607 go-librespot[1963]: time="2025-12-20T17:00:40+08:00" level=debug msg="obtained new client token: AACVgL1130iB9VaQHikyqaAE6vpN4s5+eJ1A9c+/+C4rC+zP+Q+oyt8fV00CRUGvRWceCnF41HIfPzixit5VEjRvyNxpMSANMu2OvrqJ7P1BeoXuaIYzqsBCNIT3/itugrfCIf0rVK218zL+T9sx3v7q55hxYt65O2ZzoRT3OXUhUL34oN5A3KyJ+Kt6/fNHmBa26jwt0/FbLMmuOJ8Hhx2xZJIwOPgcw/yHvnyUSWbalg2XgFzBwg==" Dec 20 17:00:41 volumio607 go-librespot[1963]: time="2025-12-20T17:00:41+08:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Dec 20 17:00:41 volumio607 go-librespot[1963]: time="2025-12-20T17:00:41+08:00" level=debug msg="completed keyexchange" Dec 20 17:00:41 volumio607 go-librespot[1963]: time="2025-12-20T17:00:41+08:00" level=debug msg="completed challenge" Dec 20 17:00:41 volumio607 ntpd[1173]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 20 17:00:41 volumio607 ntpd[1173]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Dec 20 17:00:41 volumio607 ntpd[1173]: DNS: Pool skipping: 202.155.10.23 Dec 20 17:00:41 volumio607 ntpd[1173]: DNS: Pool skipping: 111.90.158.134 Dec 20 17:00:41 volumio607 ntpd[1173]: DNS: Pool skipping: 103.16.182.23 Dec 20 17:00:41 volumio607 ntpd[1173]: DNS: Pool skipping: 111.90.158.16 Dec 20 17:00:41 volumio607 ntpd[1173]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 Dec 20 17:00:41 volumio607 go-librespot[1963]: time="2025-12-20T17:00:41+08:00" level=info msg="authenticated AP" username="31************************2a" Dec 20 17:00:41 volumio607 volumio[1318]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Dec 20 17:00:41 volumio607 volumio[1318]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Dec 20 17:00:41 volumio607 volumio[1318]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Dec 20 17:00:41 volumio607 volumio[1318]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Dec 20 17:00:41 volumio607 volumio[1318]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Dec 20 17:00:41 volumio607 volumio[1318]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Dec 20 17:00:41 volumio607 volumio[1318]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Dec 20 17:00:41 volumio607 volumio[1318]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Dec 20 17:00:41 volumio607 volumio[1318]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Dec 20 17:00:41 volumio607 volumio[1318]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Dec 20 17:00:41 volumio607 volumio[1318]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Dec 20 17:00:41 volumio607 volumio[1318]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Dec 20 17:00:41 volumio607 volumio[1318]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Dec 20 17:00:41 volumio607 volumio[1318]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Dec 20 17:00:41 volumio607 volumio[1318]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Dec 20 17:00:41 volumio607 volumio[1318]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Dec 20 17:00:41 volumio607 volumio[1318]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Dec 20 17:00:41 volumio607 volumio[1318]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Dec 20 17:00:41 volumio607 volumio[1318]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Dec 20 17:00:41 volumio607 volumio[1318]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Dec 20 17:00:41 volumio607 volumio[1318]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Dec 20 17:00:41 volumio607 volumio[1318]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Dec 20 17:00:41 volumio607 volumio[1318]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Dec 20 17:00:41 volumio607 volumio[1318]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Dec 20 17:00:41 volumio607 volumio[1318]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Dec 20 17:00:41 volumio607 volumio[1318]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Dec 20 17:00:41 volumio607 volumio[1318]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Dec 20 17:00:41 volumio607 volumio[1318]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Dec 20 17:00:41 volumio607 volumio[1318]: info: Adding plugin bluetooth to MyMusic Plugins Dec 20 17:00:41 volumio607 volumio[1318]: info: Adding plugin multiroom to MyMusic Plugins Dec 20 17:00:41 volumio607 volumio[1318]: info: Adding plugin metavolumio to MyMusic Plugins Dec 20 17:00:41 volumio607 volumio[1318]: info: Adding plugin cd_controller to MyMusic Plugins Dec 20 17:00:41 volumio607 volumio[1318]: info: Adding plugin qobuzconnect to MyMusic Plugins Dec 20 17:00:41 volumio607 volumio[1318]: info: Adding plugin smart_inputs to MyMusic Plugins Dec 20 17:00:41 volumio607 volumio[1318]: info: Adding plugin tidalconnect to MyMusic Plugins Dec 20 17:00:41 volumio607 volumio[1318]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Dec 20 17:00:41 volumio607 go-librespot[1963]: time="2025-12-20T17:00:41+08:00" level=info msg="authenticated Login5" username="31************************2a" Dec 20 17:00:41 volumio607 go-librespot[1963]: time="2025-12-20T17:00:41+08:00" level=debug msg="initializing zeroconf session" username="31************************2a" Dec 20 17:00:41 volumio607 go-librespot[1963]: time="2025-12-20T17:00:41+08:00" level=debug msg="dealer connection opened" Dec 20 17:00:41 volumio607 go-librespot[1963]: time="2025-12-20T17:00:41+08:00" level=trace msg="starting accesspoint recv loop" Dec 20 17:00:41 volumio607 go-librespot[1963]: time="2025-12-20T17:00:41+08:00" level=trace msg="starting dealer recv loop" Dec 20 17:00:41 volumio607 go-librespot[1963]: time="2025-12-20T17:00:41+08:00" level=trace msg="received accesspoint ping" Dec 20 17:00:41 volumio607 go-librespot[1963]: time="2025-12-20T17:00:41+08:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Dec 20 17:00:41 volumio607 go-librespot[1963]: time="2025-12-20T17:00:41+08:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Dec 20 17:00:41 volumio607 go-librespot[1963]: time="2025-12-20T17:00:41+08:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Dec 20 17:00:41 volumio607 go-librespot[1963]: time="2025-12-20T17:00:41+08:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Dec 20 17:00:41 volumio607 go-librespot[1963]: time="2025-12-20T17:00:41+08:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Dec 20 17:00:41 volumio607 go-librespot[1963]: time="2025-12-20T17:00:41+08:00" level=debug msg="received connection id: OWI0MzljYTMtOTYw...Q0Y3Mjc2NTI0Nw==" Dec 20 17:00:41 volumio607 go-librespot[1963]: time="2025-12-20T17:00:41+08:00" level=trace msg="received accesspoint pong ack" Dec 20 17:00:41 volumio607 go-librespot[1963]: time="2025-12-20T17:00:41+08:00" level=debug msg="put connect state because NEW_DEVICE" Dec 20 17:00:41 volumio607 volumio[1318]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Dec 20 17:00:41 volumio607 volumio[1318]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Dec 20 17:00:41 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 17:00:41 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 17:00:41 volumio607 volumio[1318]: info: Starting MyVolumio Remote Streaming Endpoints Dec 20 17:00:41 volumio607 volumio[1318]: info: MyVolumio login type: Token Dec 20 17:00:41 volumio607 volumio[1318]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Dec 20 17:00:41 volumio607 volumio[1318]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Dec 20 17:00:42 volumio607 volumio[1318]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Dec 20 17:00:42 volumio607 volumio[1318]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Dec 20 17:00:42 volumio607 volumio[1318]: info: Streaming services startup Dec 20 17:00:42 volumio607 volumio[1318]: info: Starting Streaming Daemon Dec 20 17:00:42 volumio607 sudo[1982]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 20 17:00:42 volumio607 sudo[1982]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 17:00:42 volumio607 volumio[1318]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Dec 20 17:00:42 volumio607 sudo[1982]: pam_unix(sudo:session): session closed for user root Dec 20 17:00:42 volumio607 volumio[1318]: error: Cannot start Volumio Streaming Daemon Dec 20 17:00:42 volumio607 volumio[1318]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 20 17:00:42 volumio607 volumio[1318]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 20 17:00:42 volumio607 volumio[1318]: info: Initializing connection to go-librespot Websocket Dec 20 17:00:42 volumio607 go-librespot[1963]: time="2025-12-20T17:00:42+08:00" level=debug msg="new websocket client" Dec 20 17:00:42 volumio607 volumio[1318]: info: Connection to go-librespot Websocket established Dec 20 17:00:42 volumio607 volumio[1318]: error: MyVolumio Custom Token format not valid, refreshing it Dec 20 17:00:43 volumio607 volumio[1318]: info: MyVolumio login type: Token Dec 20 17:00:43 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 20 17:00:43 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 17:00:43 volumio607 volumio[1318]: info: Discovery: Getting this device information Dec 20 17:00:43 volumio607 volumio[1318]: info: CoreCommandRouter::volumioGetState Dec 20 17:00:43 volumio607 volumio[1318]: info: CorePlayQueue::getTrack 0 Dec 20 17:00:43 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 17:00:43 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 20 17:00:43 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Dec 20 17:00:43 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Dec 20 17:00:43 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Dec 20 17:00:43 volumio607 sudo[1989]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Dec 20 17:00:43 volumio607 sudo[1989]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 17:00:43 volumio607 sudo[1995]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 20 17:00:43 volumio607 sudo[1995]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 17:00:43 volumio607 sudo[1997]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 20 17:00:43 volumio607 sudo[1997]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 17:00:43 volumio607 sudo[1995]: pam_unix(sudo:session): session closed for user root Dec 20 17:00:43 volumio607 sudo[1997]: pam_unix(sudo:session): session closed for user root Dec 20 17:00:43 volumio607 sudo[1989]: pam_unix(sudo:session): session closed for user root Dec 20 17:00:43 volumio607 sudo[2006]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 20 17:00:43 volumio607 sudo[2013]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 20 17:00:43 volumio607 sudo[2006]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 17:00:43 volumio607 sudo[2013]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 17:00:43 volumio607 sudo[2006]: pam_unix(sudo:session): session closed for user root Dec 20 17:00:43 volumio607 sudo[2014]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 20 17:00:43 volumio607 sudo[2013]: pam_unix(sudo:session): session closed for user root Dec 20 17:00:43 volumio607 sudo[2014]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 17:00:43 volumio607 sudo[2014]: pam_unix(sudo:session): session closed for user root Dec 20 17:00:43 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 20 17:00:43 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 17:00:43 volumio607 volumio[1318]: info: Discovery: Getting this device information Dec 20 17:00:43 volumio607 volumio[1318]: info: CoreCommandRouter::volumioGetState Dec 20 17:00:43 volumio607 volumio[1318]: info: CorePlayQueue::getTrack 0 Dec 20 17:00:43 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 17:00:43 volumio607 volumio[1318]: info: CoreCommandRouter::volumioGetState Dec 20 17:00:43 volumio607 volumio[1318]: info: CorePlayQueue::getTrack 0 Dec 20 17:00:43 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 20 17:00:43 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 20 17:00:43 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 20 17:00:43 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 17:00:43 volumio607 volumio[1318]: info: Discovery: Getting this device information Dec 20 17:00:43 volumio607 volumio[1318]: info: CoreCommandRouter::volumioGetState Dec 20 17:00:43 volumio607 volumio[1318]: info: CorePlayQueue::getTrack 0 Dec 20 17:00:43 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 17:00:43 volumio607 volumio[1318]: verbose: New Socket.io Connection to 192.168.0.16:3000 from 192.168.0.17 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Dec 20 17:00:43 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 20 17:00:43 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 20 17:00:43 volumio607 volumio[1318]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Dec 20 17:00:44 volumio607 sudo[2022]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 20 17:00:44 volumio607 sudo[2022]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 17:00:44 volumio607 sudo[2022]: pam_unix(sudo:session): session closed for user root Dec 20 17:00:44 volumio607 sudo[2024]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 20 17:00:44 volumio607 sudo[2024]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 17:00:44 volumio607 sudo[2024]: pam_unix(sudo:session): session closed for user root Dec 20 17:00:44 volumio607 volumio[1318]: verbose: New Socket.io Connection to 192.168.0.16 from 192.168.0.17 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 7 Dec 20 17:00:44 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 20 17:00:44 volumio607 sudo[2028]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 20 17:00:44 volumio607 sudo[2028]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 17:00:44 volumio607 sudo[2028]: pam_unix(sudo:session): session closed for user root Dec 20 17:00:44 volumio607 sudo[2030]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 20 17:00:44 volumio607 sudo[2030]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 17:00:44 volumio607 sudo[2030]: pam_unix(sudo:session): session closed for user root Dec 20 17:00:44 volumio607 volumio[1318]: verbose: New Socket.io Connection to 192.168.0.16 from 192.168.0.17 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 7 Dec 20 17:00:44 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 20 17:00:44 volumio607 volumio[1318]: info: MyVolumio token set successfully Dec 20 17:00:44 volumio607 volumio[1318]: info: MYVOLUMIO: Adding device Dec 20 17:00:44 volumio607 volumio[1318]: info: MYVOLUMIO: Evaluating Server Dec 20 17:00:44 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 20 17:00:44 volumio607 volumio[1318]: info: CoreCommandRouter::volumioGetVisibleSources Dec 20 17:00:44 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 17:00:44 volumio607 volumio[1318]: info: CoreCommandRouter::volumioGetState Dec 20 17:00:44 volumio607 volumio[1318]: info: CorePlayQueue::getTrack 0 Dec 20 17:00:44 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 20 17:00:44 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 20 17:00:44 volumio607 volumio[1318]: info: Received Get System Info Dec 20 17:00:44 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 20 17:00:44 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 17:00:44 volumio607 volumio[1318]: info: Discovery: Getting this device information Dec 20 17:00:44 volumio607 volumio[1318]: info: CoreCommandRouter::volumioGetState Dec 20 17:00:44 volumio607 volumio[1318]: info: CorePlayQueue::getTrack 0 Dec 20 17:00:44 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 17:00:44 volumio607 volumio[1318]: info: CoreCommandRouter::volumioGetState Dec 20 17:00:44 volumio607 volumio[1318]: info: CorePlayQueue::getTrack 0 Dec 20 17:00:44 volumio607 volumio[1318]: info: Listing playlists Dec 20 17:00:44 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 20 17:00:44 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 20 17:00:44 volumio607 volumio[1318]: info: MyVolumio status changed Dec 20 17:00:44 volumio607 volumio[1318]: info: Streaming services startup Dec 20 17:00:44 volumio607 volumio[1318]: info: Starting Streaming Daemon Dec 20 17:00:44 volumio607 volumio[1318]: info: Removing browser output: myVolumio user plan is not superstar Dec 20 17:00:44 volumio607 volumio[1318]: info: Removing audio output: Dec 20 17:00:44 volumio607 volumio[1318]: info: Stoppping Tunnel 1 Dec 20 17:00:44 volumio607 sudo[2054]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 20 17:00:44 volumio607 sudo[2054]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 17:00:44 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 20 17:00:44 volumio607 sudo[2056]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Dec 20 17:00:44 volumio607 sudo[2056]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 17:00:44 volumio607 sudo[2054]: pam_unix(sudo:session): session closed for user root Dec 20 17:00:44 volumio607 volumio[1318]: error: Cannot start Volumio Streaming Daemon Dec 20 17:00:44 volumio607 volumio[1318]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 20 17:00:44 volumio607 volumio[1318]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 20 17:00:44 volumio607 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. Dec 20 17:00:44 volumio607 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. Dec 20 17:00:44 volumio607 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. Dec 20 17:00:44 volumio607 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. Dec 20 17:00:44 volumio607 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. Dec 20 17:00:44 volumio607 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. Dec 20 17:00:44 volumio607 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. Dec 20 17:00:44 volumio607 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. Dec 20 17:00:44 volumio607 sudo[2056]: pam_unix(sudo:session): session closed for user root Dec 20 17:00:44 volumio607 volumio[1318]: info: Remote SSH Stopped Dec 20 17:00:45 volumio607 ntpd[1173]: CLOCK: time stepped by 0.138586 Dec 20 17:00:45 volumio607 ntpd[1173]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Dec 20 17:00:45 volumio607 volumio[1318]: info: Getting Spotify volume Dec 20 17:00:45 volumio607 volumio[1318]: info: Spotify volume: 100 Dec 20 17:00:45 volumio607 volumio[1318]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Dec 20 17:00:45 volumio607 volumio[1318]: info: CoreCommandRouter::volumioGetState Dec 20 17:00:45 volumio607 volumio[1318]: info: CorePlayQueue::getTrack 0 Dec 20 17:00:45 volumio607 volumio[1318]: info: CoreCommandRouter::volumioGetState Dec 20 17:00:45 volumio607 volumio[1318]: info: CorePlayQueue::getTrack 0 Dec 20 17:00:46 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 20 17:00:46 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 20 17:00:46 volumio607 volumio[1318]: info: Received Get System Info Dec 20 17:00:46 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 20 17:00:46 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 17:00:46 volumio607 volumio[1318]: info: Discovery: Getting this device information Dec 20 17:00:46 volumio607 volumio[1318]: info: CoreCommandRouter::volumioGetState Dec 20 17:00:46 volumio607 volumio[1318]: info: CorePlayQueue::getTrack 0 Dec 20 17:00:46 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 17:00:46 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 20 17:00:48 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 20 17:00:48 volumio607 volumio[1318]: info: Received Get System Info Dec 20 17:00:48 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 20 17:00:48 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 17:00:48 volumio607 volumio[1318]: info: Discovery: Getting this device information Dec 20 17:00:48 volumio607 volumio[1318]: info: CoreCommandRouter::volumioGetState Dec 20 17:00:48 volumio607 volumio[1318]: info: CorePlayQueue::getTrack 0 Dec 20 17:00:48 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 17:00:48 volumio607 volumio[1318]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 20 17:00:48 volumio607 volumio[1318]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 20 17:00:48 volumio607 volumio[1318]: info: Retrieving Cloud Streaming UI Dec 20 17:00:48 volumio607 volumio[1318]: info: Getting Tidal Cloud Configuration Dec 20 17:00:48 volumio607 volumio[1318]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 20 17:00:48 volumio607 volumio[1318]: info: Getting Qobuz Cloud Configuration Dec 20 17:00:48 volumio607 volumio[1318]: info: Asking plugin for UI Config Dec 20 17:00:48 volumio607 volumio[1318]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 20 17:00:48 volumio607 volumio[1318]: info: Getting Spotify Cloud Configuration Dec 20 17:00:48 volumio607 volumio[1318]: info: Asking plugin for UI Config Dec 20 17:00:48 volumio607 volumio[1318]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 20 17:00:48 volumio607 volumio[1318]: info: Saving Spotify Acccount Dec 20 17:00:48 volumio607 volumio[1318]: info: Got Tidal Cloud Configuration Dec 20 17:00:48 volumio607 volumio[1318]: info: Got it Dec 20 17:00:48 volumio607 volumio[1318]: info: Got it Dec 20 17:00:48 volumio607 volumio[1318]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 20 17:00:48 volumio607 volumio[1318]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 20 17:00:48 volumio607 volumio[1318]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Dec 20 17:00:48 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 20 17:00:48 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 20 17:00:48 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 20 17:00:48 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 20 17:00:48 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 20 17:00:48 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 20 17:00:48 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 20 17:00:48 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 20 17:00:48 volumio607 volumio[1318]: info: CoreCommandRouter::volumioGetBrowseSources Dec 20 17:00:48 volumio607 volumio[1318]: info: CoreCommandRouter::volumioGetBrowseSources Dec 20 17:00:48 volumio607 volumio[1318]: info: CoreCommandRouter::volumioGetBrowseSources Dec 20 17:00:48 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 20 17:00:48 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Dec 20 17:00:49 volumio607 upmpdcli[2059]: writing RSA key Dec 20 17:00:49 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 20 17:00:49 volumio607 volumio[1318]: info: Received Get System Info Dec 20 17:00:49 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 20 17:00:49 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 17:00:49 volumio607 volumio[1318]: info: Discovery: Getting this device information Dec 20 17:00:49 volumio607 volumio[1318]: info: CoreCommandRouter::volumioGetState Dec 20 17:00:49 volumio607 volumio[1318]: info: CorePlayQueue::getTrack 0 Dec 20 17:00:49 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 17:00:50 volumio607 volumio[1318]: info: Setting Geolocation for MyVolumio to as1 Dec 20 17:00:50 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 17:00:50 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 17:00:50 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 17:00:50 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Dec 20 17:00:50 volumio607 volumio[1318]: verbose: ControllerMpd::sendMpdCommand update Dec 20 17:00:50 volumio607 volumio[1318]: verbose: ControllerMpd::sendMpdCommand status Dec 20 17:00:50 volumio607 volumio[1318]: info: Dec 20 17:00:50 volumio607 volumio[1318]: ---------------------------- MPD announces state update: update Dec 20 17:00:50 volumio607 volumio[1318]: info: sendMpdCommand update took 2 milliseconds Dec 20 17:00:50 volumio607 volumio[1318]: info: ControllerMpd::getState Dec 20 17:00:50 volumio607 volumio[1318]: verbose: ControllerMpd::sendMpdCommand status Dec 20 17:00:50 volumio607 volumio[1318]: info: sendMpdCommand status took 1 milliseconds Dec 20 17:00:50 volumio607 volumio[1318]: info: sendMpdCommand status took 0 milliseconds Dec 20 17:00:50 volumio607 volumio[1318]: verbose: ControllerMpd::parseState Dec 20 17:00:50 volumio607 volumio[1318]: info: Command Router : Notfying DB Updatetrue Dec 20 17:00:50 volumio607 volumio[1318]: verbose: ControllerMpd::parseState Dec 20 17:00:50 volumio607 volumio[1318]: info: ControllerMpd::pushState Dec 20 17:00:50 volumio607 volumio[1318]: info: CoreCommandRouter::servicePushState Dec 20 17:00:50 volumio607 volumio[1318]: info: CoreStateMachine::pushState Dec 20 17:00:50 volumio607 volumio[1318]: info: CorePlayQueue::getTrack 0 Dec 20 17:00:50 volumio607 volumio[1318]: info: CoreCommandRouter::volumioPushState Dec 20 17:00:51 volumio607 volumio[1318]: info: CorePlayQueue::getTrack 0 Dec 20 17:00:51 volumio607 volumio[1318]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 20 17:00:51 volumio607 volumio[1318]: verbose: CURRENT POSITION 0 Dec 20 17:00:51 volumio607 volumio[1318]: info: CoreStateMachine::syncState stateService stop Dec 20 17:00:51 volumio607 volumio[1318]: info: CoreStateMachine::syncState currentStatus stop Dec 20 17:00:51 volumio607 volumio[1318]: info: CoreStateMachine::pushState Dec 20 17:00:51 volumio607 volumio[1318]: info: CorePlayQueue::getTrack 0 Dec 20 17:00:51 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 17:00:51 volumio607 volumio[1318]: info: CoreCommandRouter::volumioPushState Dec 20 17:00:51 volumio607 volumio[1318]: info: No code Dec 20 17:00:51 volumio607 volumio[1318]: info: CoreStateMachine::pushState Dec 20 17:00:51 volumio607 volumio[1318]: info: CorePlayQueue::getTrack 0 Dec 20 17:00:51 volumio607 volumio[1318]: info: CoreCommandRouter::volumioPushState Dec 20 17:00:51 volumio607 volumio[1318]: info: ------------------------------ 29ms Dec 20 17:00:51 volumio607 volumio[1318]: verbose: ControllerMpd::sendMpdCommand status Dec 20 17:00:51 volumio607 volumio[1318]: info: Dec 20 17:00:51 volumio607 volumio[1318]: ---------------------------- MPD announces state update: update Dec 20 17:00:51 volumio607 volumio[1318]: info: ControllerMpd::getState Dec 20 17:00:51 volumio607 volumio[1318]: verbose: ControllerMpd::sendMpdCommand status Dec 20 17:00:51 volumio607 volumio[1318]: verbose: ControllerMpd::sendMpdCommand status Dec 20 17:00:51 volumio607 volumio[1318]: info: Dec 20 17:00:51 volumio607 volumio[1318]: ---------------------------- MPD announces state update: update Dec 20 17:00:51 volumio607 volumio[1318]: info: ControllerMpd::getState Dec 20 17:00:51 volumio607 volumio[1318]: verbose: ControllerMpd::sendMpdCommand status Dec 20 17:00:51 volumio607 volumio[1318]: info: sendMpdCommand status took 2 milliseconds Dec 20 17:00:51 volumio607 volumio[1318]: info: sendMpdCommand status took 1 milliseconds Dec 20 17:00:51 volumio607 volumio[1318]: info: sendMpdCommand status took 1 milliseconds Dec 20 17:00:51 volumio607 volumio[1318]: info: sendMpdCommand status took 1 milliseconds Dec 20 17:00:51 volumio607 volumio[1318]: verbose: ControllerMpd::parseState Dec 20 17:00:51 volumio607 volumio[1318]: info: Command Router : Notfying DB Updatetrue Dec 20 17:00:51 volumio607 volumio[1318]: info: CoreCommandRouter::Close All Modals sent Dec 20 17:00:51 volumio607 volumio[1318]: verbose: ControllerMpd::parseState Dec 20 17:00:51 volumio607 volumio[1318]: verbose: ControllerMpd::parseState Dec 20 17:00:51 volumio607 volumio[1318]: info: Command Router : Notfying DB Updatetrue Dec 20 17:00:51 volumio607 volumio[1318]: info: CoreCommandRouter::Close All Modals sent Dec 20 17:00:51 volumio607 volumio[1318]: verbose: ControllerMpd::parseState Dec 20 17:00:51 volumio607 volumio[1318]: info: ControllerMpd::pushState Dec 20 17:00:51 volumio607 volumio[1318]: info: CoreCommandRouter::servicePushState Dec 20 17:00:51 volumio607 volumio[1318]: info: CoreStateMachine::pushState Dec 20 17:00:51 volumio607 volumio[1318]: info: CorePlayQueue::getTrack 0 Dec 20 17:00:51 volumio607 volumio[1318]: info: CoreCommandRouter::volumioPushState Dec 20 17:00:51 volumio607 volumio[1318]: info: CorePlayQueue::getTrack 0 Dec 20 17:00:51 volumio607 volumio[1318]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 20 17:00:51 volumio607 volumio[1318]: verbose: CURRENT POSITION 0 Dec 20 17:00:51 volumio607 volumio[1318]: info: CoreStateMachine::syncState stateService stop Dec 20 17:00:51 volumio607 volumio[1318]: info: CoreStateMachine::syncState currentStatus stop Dec 20 17:00:51 volumio607 volumio[1318]: info: CoreStateMachine::pushState Dec 20 17:00:51 volumio607 volumio[1318]: info: CorePlayQueue::getTrack 0 Dec 20 17:00:51 volumio607 volumio[1318]: info: CoreCommandRouter::volumioPushState Dec 20 17:00:51 volumio607 volumio[1318]: info: No code Dec 20 17:00:51 volumio607 volumio[1318]: info: CoreStateMachine::pushState Dec 20 17:00:51 volumio607 volumio[1318]: info: CorePlayQueue::getTrack 0 Dec 20 17:00:51 volumio607 volumio[1318]: info: CoreCommandRouter::volumioPushState Dec 20 17:00:51 volumio607 volumio[1318]: info: ControllerMpd::pushState Dec 20 17:00:51 volumio607 volumio[1318]: info: CoreCommandRouter::servicePushState Dec 20 17:00:51 volumio607 volumio[1318]: info: CoreStateMachine::pushState Dec 20 17:00:51 volumio607 volumio[1318]: info: CorePlayQueue::getTrack 0 Dec 20 17:00:51 volumio607 volumio[1318]: info: CoreCommandRouter::volumioPushState Dec 20 17:00:51 volumio607 volumio[1318]: info: CorePlayQueue::getTrack 0 Dec 20 17:00:51 volumio607 volumio[1318]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 20 17:00:51 volumio607 volumio[1318]: verbose: CURRENT POSITION 0 Dec 20 17:00:51 volumio607 volumio[1318]: info: CoreStateMachine::syncState stateService stop Dec 20 17:00:51 volumio607 volumio[1318]: info: CoreStateMachine::syncState currentStatus stop Dec 20 17:00:51 volumio607 volumio[1318]: info: CoreStateMachine::pushState Dec 20 17:00:51 volumio607 volumio[1318]: info: CorePlayQueue::getTrack 0 Dec 20 17:00:51 volumio607 volumio[1318]: info: CoreCommandRouter::volumioPushState Dec 20 17:00:51 volumio607 volumio[1318]: info: No code Dec 20 17:00:51 volumio607 volumio[1318]: info: CoreStateMachine::pushState Dec 20 17:00:51 volumio607 volumio[1318]: info: CorePlayQueue::getTrack 0 Dec 20 17:00:51 volumio607 volumio[1318]: info: CoreCommandRouter::volumioPushState Dec 20 17:00:51 volumio607 volumio[1318]: info: ------------------------------ 39ms Dec 20 17:00:51 volumio607 volumio[1318]: info: ------------------------------ 39ms Dec 20 17:00:51 volumio607 volumio[1318]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Dec 20 17:00:51 volumio607 volumio[1318]: info: Updating MyVolumio device info Dec 20 17:00:51 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 17:00:51 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 17:00:51 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 17:00:51 volumio607 volumio[1318]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Dec 20 17:00:52 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Dec 20 17:00:52 volumio607 volumio[1318]: verbose: ControllerMpd::sendMpdCommand update Dec 20 17:00:52 volumio607 volumio[1318]: info: sendMpdCommand update took 1 milliseconds Dec 20 17:00:52 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 20 17:00:54 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 20 17:00:55 volumio607 volumio[1318]: info: MYVOLUMIO: Adding device Dec 20 17:00:55 volumio607 volumio[1318]: info: MYVOLUMIO: Evaluating Server Dec 20 17:00:55 volumio607 volumio[1318]: info: Setting Geolocation for MyVolumio to as1 Dec 20 17:00:55 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 17:00:55 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 17:00:55 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 17:00:56 volumio607 volumio[1318]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Dec 20 17:00:56 volumio607 volumio[1318]: info: Updating MyVolumio device info Dec 20 17:00:56 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 17:00:56 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 17:00:56 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 17:00:56 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 20 17:00:56 volumio607 volumio[1318]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Dec 20 17:00:56 volumio607 volumio[1318]: info: CoreCommandRouter::volumioGetState Dec 20 17:00:56 volumio607 volumio[1318]: info: CorePlayQueue::getTrack 0 Dec 20 17:00:57 volumio607 volumio[1318]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Dec 20 17:00:57 volumio607 volumio[1318]: info: CoreCommandRouter::volumioGetState Dec 20 17:00:57 volumio607 volumio[1318]: info: CorePlayQueue::getTrack 0 Dec 20 17:00:59 volumio607 volumio[1318]: info: CoreCommandRouter::volumioGetQueue Dec 20 17:00:59 volumio607 volumio[1318]: info: CoreStateMachine::getQueue Dec 20 17:00:59 volumio607 volumio[1318]: info: CorePlayQueue::getQueue Dec 20 17:01:01 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 17:01:01 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 17:01:01 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Dec 20 17:01:01 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 20 17:01:01 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 20 17:01:01 volumio607 volumio[1318]: info: CoreCommandRouter::volumioGetBrowseSources Dec 20 17:01:01 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 20 17:01:03 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 20 17:01:03 volumio607 volumio[1318]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 20 17:01:03 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 20 17:01:03 volumio607 volumio[1318]: info: Received Get System Version Dec 20 17:01:03 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 20 17:01:03 volumio607 volumio[1318]: info: Received Get System Info Dec 20 17:01:03 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 20 17:01:03 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 17:01:03 volumio607 volumio[1318]: info: Discovery: Getting this device information Dec 20 17:01:03 volumio607 volumio[1318]: info: CoreCommandRouter::volumioGetState Dec 20 17:01:03 volumio607 volumio[1318]: info: CorePlayQueue::getTrack 0 Dec 20 17:01:03 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 17:01:11 volumio607 go-librespot[1963]: time="2025-12-20T17:01:11+08:00" level=trace msg="sent dealer ping" Dec 20 17:01:11 volumio607 go-librespot[1963]: time="2025-12-20T17:01:11+08:00" level=trace msg="received dealer pong" Dec 20 17:01:22 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 20 17:01:22 volumio607 volumio[1318]: info: CURURI: music-library/USB Dec 20 17:01:22 volumio607 volumio[1318]: info: Preload queue cleared Dec 20 17:01:28 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 20 17:01:28 volumio607 volumio[1318]: info: CURURI: music-library/USB Dec 20 17:01:29 volumio607 volumio[1318]: info: Preload queue cleared Dec 20 17:01:34 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 20 17:01:34 volumio607 volumio[1318]: info: CURURI: music-library Dec 20 17:01:34 volumio607 volumio[1318]: info: Preload queue cleared Dec 20 17:01:35 volumio607 volumio[1318]: info: MPD Database updated - AlbumList cache refreshed Dec 20 17:01:35 volumio607 volumio[1318]: info: Dec 20 17:01:35 volumio607 volumio[1318]: ---------------------------- MPD announces state update: database Dec 20 17:01:35 volumio607 volumio[1318]: verbose: ControllerMpd::sendMpdCommand status Dec 20 17:01:35 volumio607 volumio[1318]: info: Dec 20 17:01:35 volumio607 volumio[1318]: ---------------------------- MPD announces state update: update Dec 20 17:01:35 volumio607 volumio[1318]: info: ControllerMpd::getState Dec 20 17:01:35 volumio607 volumio[1318]: verbose: ControllerMpd::sendMpdCommand status Dec 20 17:01:35 volumio607 volumio[1318]: info: ControllerMpd::getState Dec 20 17:01:35 volumio607 volumio[1318]: verbose: ControllerMpd::sendMpdCommand status Dec 20 17:01:35 volumio607 volumio[1318]: info: MPD Database updated - AlbumList cache refreshed Dec 20 17:01:35 volumio607 volumio[1318]: info: Dec 20 17:01:35 volumio607 volumio[1318]: ---------------------------- MPD announces state update: database Dec 20 17:01:35 volumio607 volumio[1318]: verbose: ControllerMpd::sendMpdCommand status Dec 20 17:01:35 volumio607 volumio[1318]: info: Dec 20 17:01:35 volumio607 volumio[1318]: ---------------------------- MPD announces state update: update Dec 20 17:01:35 volumio607 volumio[1318]: info: ControllerMpd::getState Dec 20 17:01:35 volumio607 volumio[1318]: verbose: ControllerMpd::sendMpdCommand status Dec 20 17:01:35 volumio607 volumio[1318]: info: ControllerMpd::getState Dec 20 17:01:35 volumio607 volumio[1318]: verbose: ControllerMpd::sendMpdCommand status Dec 20 17:01:35 volumio607 volumio[1318]: info: MPD Database updated - AlbumList cache refreshed Dec 20 17:01:35 volumio607 volumio[1318]: info: Dec 20 17:01:35 volumio607 volumio[1318]: ---------------------------- MPD announces state update: database Dec 20 17:01:35 volumio607 volumio[1318]: verbose: ControllerMpd::sendMpdCommand status Dec 20 17:01:35 volumio607 volumio[1318]: info: Dec 20 17:01:35 volumio607 volumio[1318]: ---------------------------- MPD announces state update: update Dec 20 17:01:35 volumio607 volumio[1318]: info: ControllerMpd::getState Dec 20 17:01:35 volumio607 volumio[1318]: verbose: ControllerMpd::sendMpdCommand status Dec 20 17:01:35 volumio607 volumio[1318]: info: ControllerMpd::getState Dec 20 17:01:35 volumio607 volumio[1318]: verbose: ControllerMpd::sendMpdCommand status Dec 20 17:01:35 volumio607 volumio[1318]: info: sendMpdCommand status took 28 milliseconds Dec 20 17:01:35 volumio607 volumio[1318]: info: sendMpdCommand status took 28 milliseconds Dec 20 17:01:35 volumio607 volumio[1318]: info: sendMpdCommand status took 27 milliseconds Dec 20 17:01:35 volumio607 volumio[1318]: verbose: ControllerMpd::parseState Dec 20 17:01:35 volumio607 volumio[1318]: info: Command Router : Notfying DB Updatetrue Dec 20 17:01:35 volumio607 volumio[1318]: info: CoreCommandRouter::Close All Modals sent Dec 20 17:01:35 volumio607 volumio[1318]: verbose: ControllerMpd::parseState Dec 20 17:01:35 volumio607 volumio[1318]: verbose: ControllerMpd::parseState Dec 20 17:01:35 volumio607 volumio[1318]: info: ControllerMpd::pushState Dec 20 17:01:35 volumio607 volumio[1318]: info: CoreCommandRouter::servicePushState Dec 20 17:01:35 volumio607 volumio[1318]: info: CoreStateMachine::pushState Dec 20 17:01:35 volumio607 volumio[1318]: info: CorePlayQueue::getTrack 0 Dec 20 17:01:35 volumio607 volumio[1318]: info: CoreCommandRouter::volumioPushState Dec 20 17:01:35 volumio607 volumio[1318]: info: CorePlayQueue::getTrack 0 Dec 20 17:01:35 volumio607 volumio[1318]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 20 17:01:35 volumio607 volumio[1318]: verbose: CURRENT POSITION 0 Dec 20 17:01:35 volumio607 volumio[1318]: info: CoreStateMachine::syncState stateService stop Dec 20 17:01:35 volumio607 volumio[1318]: info: CoreStateMachine::syncState currentStatus stop Dec 20 17:01:35 volumio607 volumio[1318]: info: CoreStateMachine::pushState Dec 20 17:01:35 volumio607 volumio[1318]: info: CorePlayQueue::getTrack 0 Dec 20 17:01:35 volumio607 volumio[1318]: info: CoreCommandRouter::volumioPushState Dec 20 17:01:35 volumio607 volumio[1318]: info: No code Dec 20 17:01:35 volumio607 volumio[1318]: info: CoreStateMachine::pushState Dec 20 17:01:35 volumio607 volumio[1318]: info: CorePlayQueue::getTrack 0 Dec 20 17:01:35 volumio607 volumio[1318]: info: CoreCommandRouter::volumioPushState Dec 20 17:01:35 volumio607 volumio[1318]: info: ControllerMpd::pushState Dec 20 17:01:35 volumio607 volumio[1318]: info: CoreCommandRouter::servicePushState Dec 20 17:01:35 volumio607 volumio[1318]: info: CoreStateMachine::pushState Dec 20 17:01:35 volumio607 volumio[1318]: info: CorePlayQueue::getTrack 0 Dec 20 17:01:35 volumio607 volumio[1318]: info: CoreCommandRouter::volumioPushState Dec 20 17:01:35 volumio607 volumio[1318]: info: CorePlayQueue::getTrack 0 Dec 20 17:01:35 volumio607 volumio[1318]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 20 17:01:35 volumio607 volumio[1318]: verbose: CURRENT POSITION 0 Dec 20 17:01:35 volumio607 volumio[1318]: info: CoreStateMachine::syncState stateService stop Dec 20 17:01:35 volumio607 volumio[1318]: info: CoreStateMachine::syncState currentStatus stop Dec 20 17:01:35 volumio607 volumio[1318]: info: CoreStateMachine::pushState Dec 20 17:01:35 volumio607 volumio[1318]: info: CorePlayQueue::getTrack 0 Dec 20 17:01:35 volumio607 volumio[1318]: info: CoreCommandRouter::volumioPushState Dec 20 17:01:35 volumio607 volumio[1318]: info: No code Dec 20 17:01:35 volumio607 volumio[1318]: info: CoreStateMachine::pushState Dec 20 17:01:35 volumio607 volumio[1318]: info: CorePlayQueue::getTrack 0 Dec 20 17:01:35 volumio607 volumio[1318]: info: CoreCommandRouter::volumioPushState Dec 20 17:01:35 volumio607 volumio[1318]: info: ------------------------------ 68ms Dec 20 17:01:35 volumio607 volumio[1318]: info: ------------------------------ 67ms Dec 20 17:01:35 volumio607 volumio[1318]: info: sendMpdCommand status took 83 milliseconds Dec 20 17:01:35 volumio607 volumio[1318]: info: sendMpdCommand status took 83 milliseconds Dec 20 17:01:35 volumio607 volumio[1318]: info: sendMpdCommand status took 82 milliseconds Dec 20 17:01:35 volumio607 volumio[1318]: verbose: ControllerMpd::parseState Dec 20 17:01:35 volumio607 volumio[1318]: info: Command Router : Notfying DB Updatetrue Dec 20 17:01:35 volumio607 volumio[1318]: info: CoreCommandRouter::Close All Modals sent Dec 20 17:01:35 volumio607 volumio[1318]: verbose: ControllerMpd::parseState Dec 20 17:01:35 volumio607 volumio[1318]: verbose: ControllerMpd::parseState Dec 20 17:01:35 volumio607 volumio[1318]: info: ControllerMpd::pushState Dec 20 17:01:35 volumio607 volumio[1318]: info: CoreCommandRouter::servicePushState Dec 20 17:01:35 volumio607 volumio[1318]: info: CoreStateMachine::pushState Dec 20 17:01:35 volumio607 volumio[1318]: info: CorePlayQueue::getTrack 0 Dec 20 17:01:35 volumio607 volumio[1318]: info: CoreCommandRouter::volumioPushState Dec 20 17:01:35 volumio607 volumio[1318]: info: CorePlayQueue::getTrack 0 Dec 20 17:01:35 volumio607 volumio[1318]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 20 17:01:35 volumio607 volumio[1318]: verbose: CURRENT POSITION 0 Dec 20 17:01:35 volumio607 volumio[1318]: info: CoreStateMachine::syncState stateService stop Dec 20 17:01:35 volumio607 volumio[1318]: info: CoreStateMachine::syncState currentStatus stop Dec 20 17:01:35 volumio607 volumio[1318]: info: CoreStateMachine::pushState Dec 20 17:01:35 volumio607 volumio[1318]: info: CorePlayQueue::getTrack 0 Dec 20 17:01:35 volumio607 volumio[1318]: info: CoreCommandRouter::volumioPushState Dec 20 17:01:35 volumio607 volumio[1318]: info: No code Dec 20 17:01:35 volumio607 volumio[1318]: info: CoreStateMachine::pushState Dec 20 17:01:35 volumio607 volumio[1318]: info: CorePlayQueue::getTrack 0 Dec 20 17:01:35 volumio607 volumio[1318]: info: CoreCommandRouter::volumioPushState Dec 20 17:01:35 volumio607 volumio[1318]: info: ControllerMpd::pushState Dec 20 17:01:35 volumio607 volumio[1318]: info: CoreCommandRouter::servicePushState Dec 20 17:01:35 volumio607 volumio[1318]: info: CoreStateMachine::pushState Dec 20 17:01:35 volumio607 volumio[1318]: info: CorePlayQueue::getTrack 0 Dec 20 17:01:35 volumio607 volumio[1318]: info: CoreCommandRouter::volumioPushState Dec 20 17:01:35 volumio607 volumio[1318]: info: CorePlayQueue::getTrack 0 Dec 20 17:01:35 volumio607 volumio[1318]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 20 17:01:35 volumio607 volumio[1318]: verbose: CURRENT POSITION 0 Dec 20 17:01:35 volumio607 volumio[1318]: info: CoreStateMachine::syncState stateService stop Dec 20 17:01:35 volumio607 volumio[1318]: info: CoreStateMachine::syncState currentStatus stop Dec 20 17:01:35 volumio607 volumio[1318]: info: CoreStateMachine::pushState Dec 20 17:01:35 volumio607 volumio[1318]: info: CorePlayQueue::getTrack 0 Dec 20 17:01:35 volumio607 volumio[1318]: info: CoreCommandRouter::volumioPushState Dec 20 17:01:35 volumio607 volumio[1318]: info: No code Dec 20 17:01:35 volumio607 volumio[1318]: info: CoreStateMachine::pushState Dec 20 17:01:35 volumio607 volumio[1318]: info: CorePlayQueue::getTrack 0 Dec 20 17:01:35 volumio607 volumio[1318]: info: CoreCommandRouter::volumioPushState Dec 20 17:01:35 volumio607 volumio[1318]: info: ------------------------------ 119ms Dec 20 17:01:35 volumio607 volumio[1318]: info: ------------------------------ 119ms Dec 20 17:01:36 volumio607 volumio[1318]: info: sendMpdCommand status took 134 milliseconds Dec 20 17:01:36 volumio607 volumio[1318]: info: sendMpdCommand status took 133 milliseconds Dec 20 17:01:36 volumio607 volumio[1318]: info: sendMpdCommand status took 133 milliseconds Dec 20 17:01:36 volumio607 volumio[1318]: verbose: ControllerMpd::parseState Dec 20 17:01:36 volumio607 volumio[1318]: info: Command Router : Notfying DB Updatetrue Dec 20 17:01:36 volumio607 volumio[1318]: info: CoreCommandRouter::Close All Modals sent Dec 20 17:01:36 volumio607 volumio[1318]: verbose: ControllerMpd::parseState Dec 20 17:01:36 volumio607 volumio[1318]: verbose: ControllerMpd::parseState Dec 20 17:01:36 volumio607 volumio[1318]: info: ControllerMpd::pushState Dec 20 17:01:36 volumio607 volumio[1318]: info: CoreCommandRouter::servicePushState Dec 20 17:01:36 volumio607 volumio[1318]: info: CoreStateMachine::pushState Dec 20 17:01:36 volumio607 volumio[1318]: info: CorePlayQueue::getTrack 0 Dec 20 17:01:36 volumio607 volumio[1318]: info: CoreCommandRouter::volumioPushState Dec 20 17:01:36 volumio607 volumio[1318]: info: CorePlayQueue::getTrack 0 Dec 20 17:01:36 volumio607 volumio[1318]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 20 17:01:36 volumio607 volumio[1318]: verbose: CURRENT POSITION 0 Dec 20 17:01:36 volumio607 volumio[1318]: info: CoreStateMachine::syncState stateService stop Dec 20 17:01:36 volumio607 volumio[1318]: info: CoreStateMachine::syncState currentStatus stop Dec 20 17:01:36 volumio607 volumio[1318]: info: CoreStateMachine::pushState Dec 20 17:01:36 volumio607 volumio[1318]: info: CorePlayQueue::getTrack 0 Dec 20 17:01:36 volumio607 volumio[1318]: info: CoreCommandRouter::volumioPushState Dec 20 17:01:36 volumio607 volumio[1318]: info: No code Dec 20 17:01:36 volumio607 volumio[1318]: info: CoreStateMachine::pushState Dec 20 17:01:36 volumio607 volumio[1318]: info: CorePlayQueue::getTrack 0 Dec 20 17:01:36 volumio607 volumio[1318]: info: CoreCommandRouter::volumioPushState Dec 20 17:01:36 volumio607 volumio[1318]: info: ControllerMpd::pushState Dec 20 17:01:36 volumio607 volumio[1318]: info: CoreCommandRouter::servicePushState Dec 20 17:01:36 volumio607 volumio[1318]: info: CoreStateMachine::pushState Dec 20 17:01:36 volumio607 volumio[1318]: info: CorePlayQueue::getTrack 0 Dec 20 17:01:36 volumio607 volumio[1318]: info: CoreCommandRouter::volumioPushState Dec 20 17:01:36 volumio607 volumio[1318]: info: CorePlayQueue::getTrack 0 Dec 20 17:01:36 volumio607 volumio[1318]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 20 17:01:36 volumio607 volumio[1318]: verbose: CURRENT POSITION 0 Dec 20 17:01:36 volumio607 volumio[1318]: info: CoreStateMachine::syncState stateService stop Dec 20 17:01:36 volumio607 volumio[1318]: info: CoreStateMachine::syncState currentStatus stop Dec 20 17:01:36 volumio607 volumio[1318]: info: CoreStateMachine::pushState Dec 20 17:01:36 volumio607 volumio[1318]: info: CorePlayQueue::getTrack 0 Dec 20 17:01:36 volumio607 volumio[1318]: info: CoreCommandRouter::volumioPushState Dec 20 17:01:36 volumio607 volumio[1318]: info: No code Dec 20 17:01:36 volumio607 volumio[1318]: info: CoreStateMachine::pushState Dec 20 17:01:36 volumio607 volumio[1318]: info: CorePlayQueue::getTrack 0 Dec 20 17:01:36 volumio607 volumio[1318]: info: CoreCommandRouter::volumioPushState Dec 20 17:01:36 volumio607 volumio[1318]: info: ------------------------------ 167ms Dec 20 17:01:36 volumio607 volumio[1318]: info: ------------------------------ 167ms Dec 20 17:01:38 volumio607 volumio[1318]: info: MPD Database updated - AlbumList cache refreshed Dec 20 17:01:38 volumio607 volumio[1318]: info: Dec 20 17:01:38 volumio607 volumio[1318]: ---------------------------- MPD announces state update: database Dec 20 17:01:38 volumio607 volumio[1318]: verbose: ControllerMpd::sendMpdCommand status Dec 20 17:01:38 volumio607 volumio[1318]: info: Dec 20 17:01:38 volumio607 volumio[1318]: ---------------------------- MPD announces state update: update Dec 20 17:01:38 volumio607 volumio[1318]: info: ControllerMpd::getState Dec 20 17:01:38 volumio607 volumio[1318]: verbose: ControllerMpd::sendMpdCommand status Dec 20 17:01:38 volumio607 volumio[1318]: info: ControllerMpd::getState Dec 20 17:01:38 volumio607 volumio[1318]: verbose: ControllerMpd::sendMpdCommand status Dec 20 17:01:38 volumio607 volumio[1318]: info: MPD Database updated - AlbumList cache refreshed Dec 20 17:01:38 volumio607 volumio[1318]: info: Dec 20 17:01:38 volumio607 volumio[1318]: ---------------------------- MPD announces state update: database Dec 20 17:01:38 volumio607 volumio[1318]: verbose: ControllerMpd::sendMpdCommand status Dec 20 17:01:38 volumio607 volumio[1318]: info: Dec 20 17:01:38 volumio607 volumio[1318]: ---------------------------- MPD announces state update: update Dec 20 17:01:38 volumio607 volumio[1318]: info: ControllerMpd::getState Dec 20 17:01:38 volumio607 volumio[1318]: verbose: ControllerMpd::sendMpdCommand status Dec 20 17:01:38 volumio607 volumio[1318]: info: ControllerMpd::getState Dec 20 17:01:38 volumio607 volumio[1318]: verbose: ControllerMpd::sendMpdCommand status Dec 20 17:01:38 volumio607 volumio[1318]: info: MPD Database updated - AlbumList cache refreshed Dec 20 17:01:38 volumio607 volumio[1318]: info: Dec 20 17:01:38 volumio607 volumio[1318]: ---------------------------- MPD announces state update: database Dec 20 17:01:38 volumio607 volumio[1318]: verbose: ControllerMpd::sendMpdCommand status Dec 20 17:01:38 volumio607 volumio[1318]: info: Dec 20 17:01:38 volumio607 volumio[1318]: ---------------------------- MPD announces state update: update Dec 20 17:01:38 volumio607 volumio[1318]: info: ControllerMpd::getState Dec 20 17:01:38 volumio607 volumio[1318]: verbose: ControllerMpd::sendMpdCommand status Dec 20 17:01:38 volumio607 volumio[1318]: info: ControllerMpd::getState Dec 20 17:01:38 volumio607 volumio[1318]: verbose: ControllerMpd::sendMpdCommand status Dec 20 17:01:38 volumio607 volumio[1318]: info: sendMpdCommand status took 23 milliseconds Dec 20 17:01:38 volumio607 volumio[1318]: info: sendMpdCommand status took 23 milliseconds Dec 20 17:01:38 volumio607 volumio[1318]: info: sendMpdCommand status took 22 milliseconds Dec 20 17:01:38 volumio607 volumio[1318]: verbose: ControllerMpd::parseState Dec 20 17:01:38 volumio607 volumio[1318]: info: Command Router : Notfying DB Updatefalse Dec 20 17:01:38 volumio607 volumio[1318]: info: CoreCommandRouter::Close All Modals sent Dec 20 17:01:38 volumio607 volumio[1318]: verbose: ControllerMpd::parseState Dec 20 17:01:38 volumio607 volumio[1318]: verbose: ControllerMpd::parseState Dec 20 17:01:38 volumio607 volumio[1318]: info: ControllerMpd::pushState Dec 20 17:01:38 volumio607 volumio[1318]: info: CoreCommandRouter::servicePushState Dec 20 17:01:38 volumio607 volumio[1318]: info: CoreStateMachine::pushState Dec 20 17:01:38 volumio607 volumio[1318]: info: CorePlayQueue::getTrack 0 Dec 20 17:01:38 volumio607 volumio[1318]: info: CoreCommandRouter::volumioPushState Dec 20 17:01:38 volumio607 volumio[1318]: info: CorePlayQueue::getTrack 0 Dec 20 17:01:38 volumio607 volumio[1318]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 20 17:01:38 volumio607 volumio[1318]: verbose: CURRENT POSITION 0 Dec 20 17:01:38 volumio607 volumio[1318]: info: CoreStateMachine::syncState stateService stop Dec 20 17:01:38 volumio607 volumio[1318]: info: CoreStateMachine::syncState currentStatus stop Dec 20 17:01:38 volumio607 volumio[1318]: info: CoreStateMachine::pushState Dec 20 17:01:38 volumio607 volumio[1318]: info: CorePlayQueue::getTrack 0 Dec 20 17:01:38 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 17:01:38 volumio607 volumio[1318]: info: CoreCommandRouter::volumioPushState Dec 20 17:01:38 volumio607 volumio[1318]: info: No code Dec 20 17:01:38 volumio607 volumio[1318]: info: CoreStateMachine::pushState Dec 20 17:01:38 volumio607 volumio[1318]: info: CorePlayQueue::getTrack 0 Dec 20 17:01:38 volumio607 volumio[1318]: info: CoreCommandRouter::volumioPushState Dec 20 17:01:38 volumio607 volumio[1318]: info: ControllerMpd::pushState Dec 20 17:01:38 volumio607 volumio[1318]: info: CoreCommandRouter::servicePushState Dec 20 17:01:38 volumio607 volumio[1318]: info: CoreStateMachine::pushState Dec 20 17:01:38 volumio607 volumio[1318]: info: CorePlayQueue::getTrack 0 Dec 20 17:01:38 volumio607 volumio[1318]: info: CoreCommandRouter::volumioPushState Dec 20 17:01:38 volumio607 volumio[1318]: info: CorePlayQueue::getTrack 0 Dec 20 17:01:38 volumio607 volumio[1318]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 20 17:01:38 volumio607 volumio[1318]: verbose: CURRENT POSITION 0 Dec 20 17:01:38 volumio607 volumio[1318]: info: CoreStateMachine::syncState stateService stop Dec 20 17:01:38 volumio607 volumio[1318]: info: CoreStateMachine::syncState currentStatus stop Dec 20 17:01:38 volumio607 volumio[1318]: info: CoreStateMachine::pushState Dec 20 17:01:38 volumio607 volumio[1318]: info: CorePlayQueue::getTrack 0 Dec 20 17:01:38 volumio607 volumio[1318]: info: CoreCommandRouter::volumioPushState Dec 20 17:01:38 volumio607 volumio[1318]: info: No code Dec 20 17:01:38 volumio607 volumio[1318]: info: CoreStateMachine::pushState Dec 20 17:01:38 volumio607 volumio[1318]: info: CorePlayQueue::getTrack 0 Dec 20 17:01:38 volumio607 volumio[1318]: info: CoreCommandRouter::volumioPushState Dec 20 17:01:38 volumio607 volumio[1318]: info: ------------------------------ 57ms Dec 20 17:01:38 volumio607 volumio[1318]: info: ------------------------------ 58ms Dec 20 17:01:38 volumio607 volumio[1318]: info: sendMpdCommand status took 71 milliseconds Dec 20 17:01:38 volumio607 volumio[1318]: info: sendMpdCommand status took 71 milliseconds Dec 20 17:01:38 volumio607 volumio[1318]: info: sendMpdCommand status took 70 milliseconds Dec 20 17:01:38 volumio607 volumio[1318]: verbose: ControllerMpd::parseState Dec 20 17:01:38 volumio607 volumio[1318]: info: Command Router : Notfying DB Updatefalse Dec 20 17:01:38 volumio607 volumio[1318]: info: CoreCommandRouter::Close All Modals sent Dec 20 17:01:38 volumio607 volumio[1318]: verbose: ControllerMpd::parseState Dec 20 17:01:38 volumio607 volumio[1318]: verbose: ControllerMpd::parseState Dec 20 17:01:38 volumio607 volumio[1318]: info: ControllerMpd::pushState Dec 20 17:01:38 volumio607 volumio[1318]: info: CoreCommandRouter::servicePushState Dec 20 17:01:38 volumio607 volumio[1318]: info: CoreStateMachine::pushState Dec 20 17:01:38 volumio607 volumio[1318]: info: CorePlayQueue::getTrack 0 Dec 20 17:01:38 volumio607 volumio[1318]: info: CoreCommandRouter::volumioPushState Dec 20 17:01:38 volumio607 volumio[1318]: info: CorePlayQueue::getTrack 0 Dec 20 17:01:38 volumio607 volumio[1318]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 20 17:01:38 volumio607 volumio[1318]: verbose: CURRENT POSITION 0 Dec 20 17:01:38 volumio607 volumio[1318]: info: CoreStateMachine::syncState stateService stop Dec 20 17:01:38 volumio607 volumio[1318]: info: CoreStateMachine::syncState currentStatus stop Dec 20 17:01:38 volumio607 volumio[1318]: info: CoreStateMachine::pushState Dec 20 17:01:38 volumio607 volumio[1318]: info: CorePlayQueue::getTrack 0 Dec 20 17:01:38 volumio607 volumio[1318]: info: CoreCommandRouter::volumioPushState Dec 20 17:01:38 volumio607 volumio[1318]: info: No code Dec 20 17:01:38 volumio607 volumio[1318]: info: CoreStateMachine::pushState Dec 20 17:01:38 volumio607 volumio[1318]: info: CorePlayQueue::getTrack 0 Dec 20 17:01:38 volumio607 volumio[1318]: info: CoreCommandRouter::volumioPushState Dec 20 17:01:38 volumio607 volumio[1318]: info: ControllerMpd::pushState Dec 20 17:01:38 volumio607 volumio[1318]: info: CoreCommandRouter::servicePushState Dec 20 17:01:38 volumio607 volumio[1318]: info: CoreStateMachine::pushState Dec 20 17:01:38 volumio607 volumio[1318]: info: CorePlayQueue::getTrack 0 Dec 20 17:01:38 volumio607 volumio[1318]: info: CoreCommandRouter::volumioPushState Dec 20 17:01:38 volumio607 volumio[1318]: info: CorePlayQueue::getTrack 0 Dec 20 17:01:38 volumio607 volumio[1318]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 20 17:01:38 volumio607 volumio[1318]: verbose: CURRENT POSITION 0 Dec 20 17:01:38 volumio607 volumio[1318]: info: CoreStateMachine::syncState stateService stop Dec 20 17:01:38 volumio607 volumio[1318]: info: CoreStateMachine::syncState currentStatus stop Dec 20 17:01:38 volumio607 volumio[1318]: info: CoreStateMachine::pushState Dec 20 17:01:38 volumio607 volumio[1318]: info: CorePlayQueue::getTrack 0 Dec 20 17:01:38 volumio607 volumio[1318]: info: CoreCommandRouter::volumioPushState Dec 20 17:01:38 volumio607 volumio[1318]: info: No code Dec 20 17:01:38 volumio607 volumio[1318]: info: CoreStateMachine::pushState Dec 20 17:01:38 volumio607 volumio[1318]: info: CorePlayQueue::getTrack 0 Dec 20 17:01:38 volumio607 volumio[1318]: info: CoreCommandRouter::volumioPushState Dec 20 17:01:38 volumio607 volumio[1318]: info: ------------------------------ 102ms Dec 20 17:01:38 volumio607 volumio[1318]: info: ------------------------------ 102ms Dec 20 17:01:38 volumio607 volumio[1318]: info: sendMpdCommand status took 115 milliseconds Dec 20 17:01:38 volumio607 volumio[1318]: info: sendMpdCommand status took 116 milliseconds Dec 20 17:01:38 volumio607 volumio[1318]: info: sendMpdCommand status took 115 milliseconds Dec 20 17:01:38 volumio607 volumio[1318]: verbose: ControllerMpd::parseState Dec 20 17:01:38 volumio607 volumio[1318]: info: Command Router : Notfying DB Updatefalse Dec 20 17:01:38 volumio607 volumio[1318]: info: CoreCommandRouter::Close All Modals sent Dec 20 17:01:38 volumio607 volumio[1318]: verbose: ControllerMpd::parseState Dec 20 17:01:38 volumio607 volumio[1318]: verbose: ControllerMpd::parseState Dec 20 17:01:38 volumio607 volumio[1318]: info: ControllerMpd::pushState Dec 20 17:01:38 volumio607 volumio[1318]: info: CoreCommandRouter::servicePushState Dec 20 17:01:38 volumio607 volumio[1318]: info: CoreStateMachine::pushState Dec 20 17:01:38 volumio607 volumio[1318]: info: CorePlayQueue::getTrack 0 Dec 20 17:01:38 volumio607 volumio[1318]: info: CoreCommandRouter::volumioPushState Dec 20 17:01:38 volumio607 volumio[1318]: info: CorePlayQueue::getTrack 0 Dec 20 17:01:38 volumio607 volumio[1318]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 20 17:01:38 volumio607 volumio[1318]: verbose: CURRENT POSITION 0 Dec 20 17:01:38 volumio607 volumio[1318]: info: CoreStateMachine::syncState stateService stop Dec 20 17:01:38 volumio607 volumio[1318]: info: CoreStateMachine::syncState currentStatus stop Dec 20 17:01:38 volumio607 volumio[1318]: info: CoreStateMachine::pushState Dec 20 17:01:38 volumio607 volumio[1318]: info: CorePlayQueue::getTrack 0 Dec 20 17:01:38 volumio607 volumio[1318]: info: CoreCommandRouter::volumioPushState Dec 20 17:01:38 volumio607 volumio[1318]: info: No code Dec 20 17:01:38 volumio607 volumio[1318]: info: CoreStateMachine::pushState Dec 20 17:01:38 volumio607 volumio[1318]: info: CorePlayQueue::getTrack 0 Dec 20 17:01:38 volumio607 volumio[1318]: info: CoreCommandRouter::volumioPushState Dec 20 17:01:38 volumio607 volumio[1318]: info: ControllerMpd::pushState Dec 20 17:01:38 volumio607 volumio[1318]: info: CoreCommandRouter::servicePushState Dec 20 17:01:38 volumio607 volumio[1318]: info: CoreStateMachine::pushState Dec 20 17:01:38 volumio607 volumio[1318]: info: CorePlayQueue::getTrack 0 Dec 20 17:01:38 volumio607 volumio[1318]: info: CoreCommandRouter::volumioPushState Dec 20 17:01:38 volumio607 volumio[1318]: info: CorePlayQueue::getTrack 0 Dec 20 17:01:38 volumio607 volumio[1318]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 20 17:01:38 volumio607 volumio[1318]: verbose: CURRENT POSITION 0 Dec 20 17:01:38 volumio607 volumio[1318]: info: CoreStateMachine::syncState stateService stop Dec 20 17:01:38 volumio607 volumio[1318]: info: CoreStateMachine::syncState currentStatus stop Dec 20 17:01:38 volumio607 volumio[1318]: info: CoreStateMachine::pushState Dec 20 17:01:38 volumio607 volumio[1318]: info: CorePlayQueue::getTrack 0 Dec 20 17:01:38 volumio607 volumio[1318]: info: CoreCommandRouter::volumioPushState Dec 20 17:01:38 volumio607 volumio[1318]: info: No code Dec 20 17:01:38 volumio607 volumio[1318]: info: CoreStateMachine::pushState Dec 20 17:01:38 volumio607 volumio[1318]: info: CorePlayQueue::getTrack 0 Dec 20 17:01:38 volumio607 volumio[1318]: info: CoreCommandRouter::volumioPushState Dec 20 17:01:38 volumio607 volumio[1318]: info: ------------------------------ 147ms Dec 20 17:01:38 volumio607 volumio[1318]: info: ------------------------------ 147ms Dec 20 17:01:38 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 20 17:01:38 volumio607 volumio[1318]: info: CURURI: music-library/INTERNAL Dec 20 17:01:38 volumio607 volumio[1318]: info: Preload queue cleared Dec 20 17:01:38 volumio607 volumio[1318]: info: Preloading song: music-library/INTERNAL/[MP3DOWNLOAD.TO] Diviners, IZECOLD & Tim Beeren - This Time (ft. CRVN & Molly Ann) [NCS10 Release]-320k.mp3 Dec 20 17:01:38 volumio607 volumio[1318]: info: Preloading song: music-library/INTERNAL/Jesus, Jesus, Jesus.wav Dec 20 17:01:38 volumio607 volumio[1318]: info: Preloading song: music-library/INTERNAL/I Believe.wav Dec 20 17:01:38 volumio607 volumio[1318]: info: Preloading song: music-library/INTERNAL/If I Had A Hammer.wav Dec 20 17:01:38 volumio607 volumio[1318]: info: Preloading song: music-library/INTERNAL/Gotta Serve Somebody.wav Dec 20 17:01:38 volumio607 volumio[1318]: info: Preloading song: music-library/INTERNAL/Oh Happy Day.wav Dec 20 17:01:38 volumio607 volumio[1318]: info: Preloading song: music-library/INTERNAL/Ave Maria.wav Dec 20 17:01:38 volumio607 volumio[1318]: info: Preloading song: music-library/INTERNAL/Let Go.wav Dec 20 17:01:38 volumio607 volumio[1318]: info: Preloading song: music-library/INTERNAL/What A Friend We Have In Jesus.wav Dec 20 17:01:38 volumio607 volumio[1318]: info: Preloading song: music-library/INTERNAL/A Change Is Gonna Come.wav Dec 20 17:01:38 volumio607 volumio[1318]: info: Preloading song: music-library/INTERNAL/I Saw The Light (featuring The Dirty Dozen Brass Band).wav Dec 20 17:01:38 volumio607 volumio[1318]: info: Preloading song: music-library/INTERNAL/Going Home.wav Dec 20 17:01:38 volumio607 volumio[1318]: info: Preloading song: music-library/INTERNAL/Amazing Grace.wav Dec 20 17:01:38 volumio607 volumio[1318]: info: Preloading song: music-library/INTERNAL/With God On Our Side (Bonus Track).wav Dec 20 17:01:38 volumio607 volumio[1318]: info: Preloading song: music-library/INTERNAL/Steer Me Right.wav Dec 20 17:01:38 volumio607 volumio[1318]: info: Exploding uri music-library/INTERNAL/[MP3DOWNLOAD.TO] Diviners, IZECOLD & Tim Beeren - This Time (ft. CRVN & Molly Ann) [NCS10 Release]-320k.mp3 in service mpd Dec 20 17:01:38 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=//extralarge&path=%2Fmnt%2FINTERNAL%2F%5BMP3DOWNLOAD.TO%5D%20Diviners%2C%20IZECOLD%20%26%20Tim%20Beeren%20-%20This%20Time%20(ft.%20CRVN%20%26%20Molly%20Ann)%20%5BNCS10%20Release%5D-320k.mp3&metadata=false Dec 20 17:01:38 volumio607 volumio[1318]: info: URI /mnt/INTERNAL/[MP3DOWNLOAD.TO] Diviners, IZECOLD & Tim Beeren - This Time (ft. CRVN & Molly Ann) [NCS10 Release]-320k.mp3 Dec 20 17:01:38 volumio607 volumio[1318]: info: Exploding uri music-library/INTERNAL/Jesus, Jesus, Jesus.wav in service mpd Dec 20 17:01:38 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FJesus%2C%20Jesus%2C%20Jesus.wav&metadata=false Dec 20 17:01:38 volumio607 volumio[1318]: info: URI /mnt/INTERNAL/Jesus, Jesus, Jesus.wav Dec 20 17:01:38 volumio607 volumio[1318]: info: Exploding uri music-library/INTERNAL/I Believe.wav in service mpd Dec 20 17:01:38 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FI%20Believe.wav&metadata=false Dec 20 17:01:38 volumio607 volumio[1318]: info: URI /mnt/INTERNAL/I Believe.wav Dec 20 17:01:38 volumio607 volumio[1318]: info: Exploding uri music-library/INTERNAL/If I Had A Hammer.wav in service mpd Dec 20 17:01:38 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FIf%20I%20Had%20A%20Hammer.wav&metadata=false Dec 20 17:01:38 volumio607 volumio[1318]: info: URI /mnt/INTERNAL/If I Had A Hammer.wav Dec 20 17:01:38 volumio607 volumio[1318]: info: Exploding uri music-library/INTERNAL/Gotta Serve Somebody.wav in service mpd Dec 20 17:01:38 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FGotta%20Serve%20Somebody.wav&metadata=false Dec 20 17:01:38 volumio607 volumio[1318]: info: URI /mnt/INTERNAL/Gotta Serve Somebody.wav Dec 20 17:01:38 volumio607 volumio[1318]: info: Exploding uri music-library/INTERNAL/Oh Happy Day.wav in service mpd Dec 20 17:01:38 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FOh%20Happy%20Day.wav&metadata=false Dec 20 17:01:38 volumio607 volumio[1318]: info: URI /mnt/INTERNAL/Oh Happy Day.wav Dec 20 17:01:38 volumio607 volumio[1318]: info: Exploding uri music-library/INTERNAL/Ave Maria.wav in service mpd Dec 20 17:01:38 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FAve%20Maria.wav&metadata=false Dec 20 17:01:38 volumio607 volumio[1318]: info: URI /mnt/INTERNAL/Ave Maria.wav Dec 20 17:01:38 volumio607 volumio[1318]: info: Exploding uri music-library/INTERNAL/Let Go.wav in service mpd Dec 20 17:01:38 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FLet%20Go.wav&metadata=false Dec 20 17:01:38 volumio607 volumio[1318]: info: URI /mnt/INTERNAL/Let Go.wav Dec 20 17:01:39 volumio607 volumio[1318]: info: Exploding uri music-library/INTERNAL/What A Friend We Have In Jesus.wav in service mpd Dec 20 17:01:39 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FWhat%20A%20Friend%20We%20Have%20In%20Jesus.wav&metadata=false Dec 20 17:01:39 volumio607 volumio[1318]: info: URI /mnt/INTERNAL/What A Friend We Have In Jesus.wav Dec 20 17:01:39 volumio607 volumio[1318]: info: Exploding uri music-library/INTERNAL/A Change Is Gonna Come.wav in service mpd Dec 20 17:01:39 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FA%20Change%20Is%20Gonna%20Come.wav&metadata=false Dec 20 17:01:39 volumio607 volumio[1318]: info: URI /mnt/INTERNAL/A Change Is Gonna Come.wav Dec 20 17:01:39 volumio607 volumio[1318]: info: Exploding uri music-library/INTERNAL/I Saw The Light (featuring The Dirty Dozen Brass Band).wav in service mpd Dec 20 17:01:39 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FI%20Saw%20The%20Light%20(featuring%20The%20Dirty%20Dozen%20Brass%20Band).wav&metadata=false Dec 20 17:01:39 volumio607 volumio[1318]: info: URI /mnt/INTERNAL/I Saw The Light (featuring The Dirty Dozen Brass Band).wav Dec 20 17:01:39 volumio607 volumio[1318]: info: Exploding uri music-library/INTERNAL/Going Home.wav in service mpd Dec 20 17:01:39 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FGoing%20Home.wav&metadata=false Dec 20 17:01:39 volumio607 volumio[1318]: info: URI /mnt/INTERNAL/Going Home.wav Dec 20 17:01:39 volumio607 volumio[1318]: info: Exploding uri music-library/INTERNAL/Amazing Grace.wav in service mpd Dec 20 17:01:39 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FAmazing%20Grace.wav&metadata=false Dec 20 17:01:39 volumio607 volumio[1318]: info: URI /mnt/INTERNAL/Amazing Grace.wav Dec 20 17:01:39 volumio607 volumio[1318]: info: Exploding uri music-library/INTERNAL/With God On Our Side (Bonus Track).wav in service mpd Dec 20 17:01:39 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FWith%20God%20On%20Our%20Side%20(Bonus%20Track).wav&metadata=false Dec 20 17:01:39 volumio607 volumio[1318]: info: URI /mnt/INTERNAL/With God On Our Side (Bonus Track).wav Dec 20 17:01:39 volumio607 volumio[1318]: info: Exploding uri music-library/INTERNAL/Steer Me Right.wav in service mpd Dec 20 17:01:39 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=Aaron%20Neville/I%20Believe/extralarge&path=%2Fmnt%2FINTERNAL%2FSteer%20Me%20Right.wav&metadata=false Dec 20 17:01:39 volumio607 volumio[1318]: info: URI /mnt/INTERNAL/Steer Me Right.wav Dec 20 17:01:41 volumio607 go-librespot[1963]: time="2025-12-20T17:01:41+08:00" level=trace msg="sent dealer ping" Dec 20 17:01:41 volumio607 go-librespot[1963]: time="2025-12-20T17:01:41+08:00" level=trace msg="received dealer pong" Dec 20 17:01:43 volumio607 volumio[1318]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 20 17:01:43 volumio607 volumio[1318]: info: CURURI: music-library/USB Dec 20 17:01:43 volumio607 volumio[1318]: info: Preload queue cleared Dec 20 17:01:46 volumio607 volumio[1318]: info: Preload queue cleared Dec 20 17:01:46 volumio607 volumio[1318]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 20 17:01:46 volumio607 volumio[1318]: info: CoreStateMachine::ClearQueue Dec 20 17:01:46 volumio607 volumio[1318]: info: CoreStateMachine::stop Dec 20 17:01:46 volumio607 volumio[1318]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 20 17:01:46 volumio607 volumio[1318]: info: CorePlayQueue::clearPlayQueue Dec 20 17:01:46 volumio607 volumio[1318]: info: CorePlayQueue::saveQueue Dec 20 17:01:46 volumio607 volumio[1318]: info: CoreCommandRouter::volumioPushQueue Dec 20 17:01:46 volumio607 volumio[1318]: info: CoreStateMachine::addQueueItems Dec 20 17:01:46 volumio607 volumio[1318]: info: CorePlayQueue::addQueueItems Dec 20 17:01:46 volumio607 volumio[1318]: info: Preload queue cleared Dec 20 17:01:46 volumio607 volumio[1318]: info: Adding Item to queue: music-library/USB/USB_64G Dec 20 17:01:46 volumio607 volumio[1318]: info: Exploding uri music-library/USB/USB_64G in service mpd Dec 20 17:01:46 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=Anette%20Askvik/Liberty/extralarge&path=%2Fmnt%2FUSB%2FUSB_64G%2FAnette%20Askvik%20-%20Liberty%20(2011)%20%5B24-48%5D%2F01.%20Liberty.flac&metadata=false Dec 20 17:01:46 volumio607 volumio[1318]: info: URI /mnt/USB/USB_64G/Anette Askvik - Liberty (2011) [24-48]/01. Liberty.flac Dec 20 17:01:46 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=Anette%20Askvik/Liberty/extralarge&path=%2Fmnt%2FUSB%2FUSB_64G%2FAnette%20Askvik%20-%20Liberty%20(2011)%20%5B24-48%5D%2F02.%20The%20Bird%20Song.flac&metadata=false Dec 20 17:01:46 volumio607 volumio[1318]: info: URI /mnt/USB/USB_64G/Anette Askvik - Liberty (2011) [24-48]/02. The Bird Song.flac Dec 20 17:01:46 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=Anette%20Askvik/Liberty/extralarge&path=%2Fmnt%2FUSB%2FUSB_64G%2FAnette%20Askvik%20-%20Liberty%20(2011)%20%5B24-48%5D%2F03.%20April.flac&metadata=false Dec 20 17:01:46 volumio607 volumio[1318]: info: URI /mnt/USB/USB_64G/Anette Askvik - Liberty (2011) [24-48]/03. April.flac Dec 20 17:01:46 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=Anette%20Askvik/Liberty/extralarge&path=%2Fmnt%2FUSB%2FUSB_64G%2FAnette%20Askvik%20-%20Liberty%20(2011)%20%5B24-48%5D%2F04.%20Under%20the%20Tallest%20Tree.flac&metadata=false Dec 20 17:01:46 volumio607 volumio[1318]: info: URI /mnt/USB/USB_64G/Anette Askvik - Liberty (2011) [24-48]/04. Under the Tallest Tree.flac Dec 20 17:01:46 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=Anette%20Askvik/Liberty/extralarge&path=%2Fmnt%2FUSB%2FUSB_64G%2FAnette%20Askvik%20-%20Liberty%20(2011)%20%5B24-48%5D%2F05.%20Blue%20Lights.flac&metadata=false Dec 20 17:01:46 volumio607 volumio[1318]: info: URI /mnt/USB/USB_64G/Anette Askvik - Liberty (2011) [24-48]/05. Blue Lights.flac Dec 20 17:01:46 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=Anette%20Askvik/Liberty/extralarge&path=%2Fmnt%2FUSB%2FUSB_64G%2FAnette%20Askvik%20-%20Liberty%20(2011)%20%5B24-48%5D%2F06.%20Time%20to%20Move%20On.flac&metadata=false Dec 20 17:01:46 volumio607 volumio[1318]: info: URI /mnt/USB/USB_64G/Anette Askvik - Liberty (2011) [24-48]/06. Time to Move On.flac Dec 20 17:01:46 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=Anette%20Askvik/Liberty/extralarge&path=%2Fmnt%2FUSB%2FUSB_64G%2FAnette%20Askvik%20-%20Liberty%20(2011)%20%5B24-48%5D%2F07.%20Bring%20Her%20Home.flac&metadata=false Dec 20 17:01:46 volumio607 volumio[1318]: info: URI /mnt/USB/USB_64G/Anette Askvik - Liberty (2011) [24-48]/07. Bring Her Home.flac Dec 20 17:01:46 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=Anette%20Askvik/Liberty/extralarge&path=%2Fmnt%2FUSB%2FUSB_64G%2FAnette%20Askvik%20-%20Liberty%20(2011)%20%5B24-48%5D%2F08.%20Without%20Permission.flac&metadata=false Dec 20 17:01:46 volumio607 volumio[1318]: info: URI /mnt/USB/USB_64G/Anette Askvik - Liberty (2011) [24-48]/08. Without Permission.flac Dec 20 17:01:46 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=Anette%20Askvik/Liberty/extralarge&path=%2Fmnt%2FUSB%2FUSB_64G%2FAnette%20Askvik%20-%20Liberty%20(2011)%20%5B24-48%5D%2F09.%20You%20Shoot%20Me.flac&metadata=false Dec 20 17:01:46 volumio607 volumio[1318]: info: URI /mnt/USB/USB_64G/Anette Askvik - Liberty (2011) [24-48]/09. You Shoot Me.flac Dec 20 17:01:46 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=Anette%20Askvik/Liberty/extralarge&path=%2Fmnt%2FUSB%2FUSB_64G%2FAnette%20Askvik%20-%20Liberty%20(2011)%20%5B24-48%5D%2F10.%20Days%20Like%20This.flac&metadata=false Dec 20 17:01:46 volumio607 volumio[1318]: info: URI /mnt/USB/USB_64G/Anette Askvik - Liberty (2011) [24-48]/10. Days Like This.flac Dec 20 17:01:46 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=Anette%20Askvik/Liberty/extralarge&path=%2Fmnt%2FUSB%2FUSB_64G%2FAnette%20Askvik%20-%20Liberty%20(2011)%20%5B24-48%5D%2FAnette%20Askvik-Liberty_WAV%2F01.%20Liberty.wav&metadata=false Dec 20 17:01:46 volumio607 volumio[1318]: info: URI /mnt/USB/USB_64G/Anette Askvik - Liberty (2011) [24-48]/Anette Askvik-Liberty_WAV/01. Liberty.wav Dec 20 17:01:46 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=Anette%20Askvik/Liberty/extralarge&path=%2Fmnt%2FUSB%2FUSB_64G%2FAnette%20Askvik%20-%20Liberty%20(2011)%20%5B24-48%5D%2FAnette%20Askvik-Liberty_WAV%2F02.%20The%20Bird%20Song.wav&metadata=false Dec 20 17:01:46 volumio607 volumio[1318]: info: URI /mnt/USB/USB_64G/Anette Askvik - Liberty (2011) [24-48]/Anette Askvik-Liberty_WAV/02. The Bird Song.wav Dec 20 17:01:46 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=Anette%20Askvik/Liberty/extralarge&path=%2Fmnt%2FUSB%2FUSB_64G%2FAnette%20Askvik%20-%20Liberty%20(2011)%20%5B24-48%5D%2FAnette%20Askvik-Liberty_WAV%2F03.%20April.wav&metadata=false Dec 20 17:01:46 volumio607 volumio[1318]: info: URI /mnt/USB/USB_64G/Anette Askvik - Liberty (2011) [24-48]/Anette Askvik-Liberty_WAV/03. April.wav Dec 20 17:01:46 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=Anette%20Askvik/Liberty/extralarge&path=%2Fmnt%2FUSB%2FUSB_64G%2FAnette%20Askvik%20-%20Liberty%20(2011)%20%5B24-48%5D%2FAnette%20Askvik-Liberty_WAV%2F04.%20Under%20the%20Tallest%20Tree.wav&metadata=false Dec 20 17:01:46 volumio607 volumio[1318]: info: URI /mnt/USB/USB_64G/Anette Askvik - Liberty (2011) [24-48]/Anette Askvik-Liberty_WAV/04. Under the Tallest Tree.wav Dec 20 17:01:46 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=Anette%20Askvik/Liberty/extralarge&path=%2Fmnt%2FUSB%2FUSB_64G%2FAnette%20Askvik%20-%20Liberty%20(2011)%20%5B24-48%5D%2FAnette%20Askvik-Liberty_WAV%2F05.%20Blue%20Lights.wav&metadata=false Dec 20 17:01:46 volumio607 volumio[1318]: info: URI /mnt/USB/USB_64G/Anette Askvik - Liberty (2011) [24-48]/Anette Askvik-Liberty_WAV/05. Blue Lights.wav Dec 20 17:01:46 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=Anette%20Askvik/Liberty/extralarge&path=%2Fmnt%2FUSB%2FUSB_64G%2FAnette%20Askvik%20-%20Liberty%20(2011)%20%5B24-48%5D%2FAnette%20Askvik-Liberty_WAV%2F06.%20Time%20to%20Move%20On.wav&metadata=false Dec 20 17:01:46 volumio607 volumio[1318]: info: URI /mnt/USB/USB_64G/Anette Askvik - Liberty (2011) [24-48]/Anette Askvik-Liberty_WAV/06. Time to Move On.wav Dec 20 17:01:46 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=Anette%20Askvik/Liberty/extralarge&path=%2Fmnt%2FUSB%2FUSB_64G%2FAnette%20Askvik%20-%20Liberty%20(2011)%20%5B24-48%5D%2FAnette%20Askvik-Liberty_WAV%2F07.%20Bring%20Her%20Home.wav&metadata=false Dec 20 17:01:46 volumio607 volumio[1318]: info: URI /mnt/USB/USB_64G/Anette Askvik - Liberty (2011) [24-48]/Anette Askvik-Liberty_WAV/07. Bring Her Home.wav Dec 20 17:01:46 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=Anette%20Askvik/Liberty/extralarge&path=%2Fmnt%2FUSB%2FUSB_64G%2FAnette%20Askvik%20-%20Liberty%20(2011)%20%5B24-48%5D%2FAnette%20Askvik-Liberty_WAV%2F08.%20Without%20Permission.wav&metadata=false Dec 20 17:01:46 volumio607 volumio[1318]: info: URI /mnt/USB/USB_64G/Anette Askvik - Liberty (2011) [24-48]/Anette Askvik-Liberty_WAV/08. Without Permission.wav Dec 20 17:01:46 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=Anette%20Askvik/Liberty/extralarge&path=%2Fmnt%2FUSB%2FUSB_64G%2FAnette%20Askvik%20-%20Liberty%20(2011)%20%5B24-48%5D%2FAnette%20Askvik-Liberty_WAV%2F09.%20You%20Shoot%20Me.wav&metadata=false Dec 20 17:01:46 volumio607 volumio[1318]: info: URI /mnt/USB/USB_64G/Anette Askvik - Liberty (2011) [24-48]/Anette Askvik-Liberty_WAV/09. You Shoot Me.wav Dec 20 17:01:46 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=Anette%20Askvik/Liberty/extralarge&path=%2Fmnt%2FUSB%2FUSB_64G%2FAnette%20Askvik%20-%20Liberty%20(2011)%20%5B24-48%5D%2FAnette%20Askvik-Liberty_WAV%2F10.%20Days%20Like%20This.wav&metadata=false Dec 20 17:01:46 volumio607 volumio[1318]: info: URI /mnt/USB/USB_64G/Anette Askvik - Liberty (2011) [24-48]/Anette Askvik-Liberty_WAV/10. Days Like This.wav Dec 20 17:01:46 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=Anne%20Bisson/Be%20My%20Lover/extralarge&path=%2Fmnt%2FUSB%2FUSB_64G%2FAnne%20Bisson%20-%20Be%20My%20Lover%20(2023)%20%5B24-192%5D%2F01.%20Moondance.flac&metadata=false Dec 20 17:01:46 volumio607 volumio[1318]: info: URI /mnt/USB/USB_64G/Anne Bisson - Be My Lover (2023) [24-192]/01. Moondance.flac Dec 20 17:01:46 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=Anne%20Bisson/Be%20My%20Lover/extralarge&path=%2Fmnt%2FUSB%2FUSB_64G%2FAnne%20Bisson%20-%20Be%20My%20Lover%20(2023)%20%5B24-192%5D%2F02.%20Blue%20Bayou.flac&metadata=false Dec 20 17:01:46 volumio607 volumio[1318]: info: URI /mnt/USB/USB_64G/Anne Bisson - Be My Lover (2023) [24-192]/02. Blue Bayou.flac Dec 20 17:01:46 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=Anne%20Bisson/Be%20My%20Lover/extralarge&path=%2Fmnt%2FUSB%2FUSB_64G%2FAnne%20Bisson%20-%20Be%20My%20Lover%20(2023)%20%5B24-192%5D%2F03.%20I%20Wake%20up%20(The%20Nightmare).flac&metadata=false Dec 20 17:01:46 volumio607 volumio[1318]: info: URI /mnt/USB/USB_64G/Anne Bisson - Be My Lover (2023) [24-192]/03. I Wake up (The Nightmare).flac Dec 20 17:01:46 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=Anne%20Bisson/Be%20My%20Lover/extralarge&path=%2Fmnt%2FUSB%2FUSB_64G%2FAnne%20Bisson%20-%20Be%20My%20Lover%20(2023)%20%5B24-192%5D%2F04.%20Are%20You%20Sure%20You%20Want%20Me.flac&metadata=false Dec 20 17:01:46 volumio607 volumio[1318]: info: URI /mnt/USB/USB_64G/Anne Bisson - Be My Lover (2023) [24-192]/04. Are You Sure You Want Me.flac Dec 20 17:01:46 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=Anne%20Bisson/Be%20My%20Lover/extralarge&path=%2Fmnt%2FUSB%2FUSB_64G%2FAnne%20Bisson%20-%20Be%20My%20Lover%20(2023)%20%5B24-192%5D%2F05.%20From%20the%20Beginning.flac&metadata=false Dec 20 17:01:46 volumio607 volumio[1318]: info: URI /mnt/USB/USB_64G/Anne Bisson - Be My Lover (2023) [24-192]/05. From the Beginning.flac Dec 20 17:01:46 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=Anne%20Bisson/Be%20My%20Lover/extralarge&path=%2Fmnt%2FUSB%2FUSB_64G%2FAnne%20Bisson%20-%20Be%20My%20Lover%20(2023)%20%5B24-192%5D%2F06.%20I%20Need%20Protection.flac&metadata=false Dec 20 17:01:46 volumio607 volumio[1318]: info: URI /mnt/USB/USB_64G/Anne Bisson - Be My Lover (2023) [24-192]/06. I Need Protection.flac Dec 20 17:01:46 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=Anne%20Bisson/Be%20My%20Lover/extralarge&path=%2Fmnt%2FUSB%2FUSB_64G%2FAnne%20Bisson%20-%20Be%20My%20Lover%20(2023)%20%5B24-192%5D%2F07.%20The%20Four%20Horsemen.flac&metadata=false Dec 20 17:01:46 volumio607 volumio[1318]: info: URI /mnt/USB/USB_64G/Anne Bisson - Be My Lover (2023) [24-192]/07. The Four Horsemen.flac Dec 20 17:01:46 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=Anne%20Bisson/Be%20My%20Lover/extralarge&path=%2Fmnt%2FUSB%2FUSB_64G%2FAnne%20Bisson%20-%20Be%20My%20Lover%20(2023)%20%5B24-192%5D%2F08.%20Bridge%20over%20Troubled%20Water.flac&metadata=false Dec 20 17:01:46 volumio607 volumio[1318]: info: URI /mnt/USB/USB_64G/Anne Bisson - Be My Lover (2023) [24-192]/08. Bridge over Troubled Water.flac Dec 20 17:01:46 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=Anne%20Bisson/Be%20My%20Lover/extralarge&path=%2Fmnt%2FUSB%2FUSB_64G%2FAnne%20Bisson%20-%20Be%20My%20Lover%20(2023)%20%5B24-192%5D%2F09.%20The%20Seabreeze.flac&metadata=false Dec 20 17:01:46 volumio607 volumio[1318]: info: URI /mnt/USB/USB_64G/Anne Bisson - Be My Lover (2023) [24-192]/09. The Seabreeze.flac Dec 20 17:01:46 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=Anne%20Bisson/Be%20My%20Lover/extralarge&path=%2Fmnt%2FUSB%2FUSB_64G%2FAnne%20Bisson%20-%20Be%20My%20Lover%20(2023)%20%5B24-192%5D%2F10.%20A%20Million%20Dreams.flac&metadata=false Dec 20 17:01:46 volumio607 volumio[1318]: info: URI /mnt/USB/USB_64G/Anne Bisson - Be My Lover (2023) [24-192]/10. A Million Dreams.flac Dec 20 17:01:46 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=Anne%20Bisson/Be%20My%20Lover/extralarge&path=%2Fmnt%2FUSB%2FUSB_64G%2FAnne%20Bisson%20-%20Be%20My%20Lover%20(2023)%20%5B24-192%5D%2F11.%20Nature%20Boy.flac&metadata=false Dec 20 17:01:46 volumio607 volumio[1318]: info: URI /mnt/USB/USB_64G/Anne Bisson - Be My Lover (2023) [24-192]/11. Nature Boy.flac Dec 20 17:01:46 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=Anne%20Bisson/Be%20My%20Lover/extralarge&path=%2Fmnt%2FUSB%2FUSB_64G%2FAnne%20Bisson%20-%20Be%20My%20Lover%20(2023)%20%5B24-192%5D%2F12.%20Be%20My%20Lover.flac&metadata=false Dec 20 17:01:46 volumio607 volumio[1318]: info: URI /mnt/USB/USB_64G/Anne Bisson - Be My Lover (2023) [24-192]/12. Be My Lover.flac Dec 20 17:01:46 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=Anne%20Bisson/Be%20My%20Lover/extralarge&path=%2Fmnt%2FUSB%2FUSB_64G%2FAnne%20Bisson%20-%20Be%20My%20Lover%20(2023)%20%5B24-192%5D%2FAnne%20Bisson-Be%20My%20Lover_WAV%2F01.%20Moondance.wav&metadata=false Dec 20 17:01:46 volumio607 volumio[1318]: info: URI /mnt/USB/USB_64G/Anne Bisson - Be My Lover (2023) [24-192]/Anne Bisson-Be My Lover_WAV/01. Moondance.wav Dec 20 17:01:46 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=Anne%20Bisson/Be%20My%20Lover/extralarge&path=%2Fmnt%2FUSB%2FUSB_64G%2FAnne%20Bisson%20-%20Be%20My%20Lover%20(2023)%20%5B24-192%5D%2FAnne%20Bisson-Be%20My%20Lover_WAV%2F02.%20Blue%20Bayou.wav&metadata=false Dec 20 17:01:46 volumio607 volumio[1318]: info: URI /mnt/USB/USB_64G/Anne Bisson - Be My Lover (2023) [24-192]/Anne Bisson-Be My Lover_WAV/02. Blue Bayou.wav Dec 20 17:01:46 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=Anne%20Bisson/Be%20My%20Lover/extralarge&path=%2Fmnt%2FUSB%2FUSB_64G%2FAnne%20Bisson%20-%20Be%20My%20Lover%20(2023)%20%5B24-192%5D%2FAnne%20Bisson-Be%20My%20Lover_WAV%2F03.%20I%20Wake%20up%20(The%20Nightmare).wav&metadata=false Dec 20 17:01:46 volumio607 volumio[1318]: info: URI /mnt/USB/USB_64G/Anne Bisson - Be My Lover (2023) [24-192]/Anne Bisson-Be My Lover_WAV/03. I Wake up (The Nightmare).wav Dec 20 17:01:46 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=Anne%20Bisson/Be%20My%20Lover/extralarge&path=%2Fmnt%2FUSB%2FUSB_64G%2FAnne%20Bisson%20-%20Be%20My%20Lover%20(2023)%20%5B24-192%5D%2FAnne%20Bisson-Be%20My%20Lover_WAV%2F04.%20Are%20You%20Sure%20You%20Want%20Me.wav&metadata=false Dec 20 17:01:46 volumio607 volumio[1318]: info: URI /mnt/USB/USB_64G/Anne Bisson - Be My Lover (2023) [24-192]/Anne Bisson-Be My Lover_WAV/04. Are You Sure You Want Me.wav Dec 20 17:01:46 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=Anne%20Bisson/Be%20My%20Lover/extralarge&path=%2Fmnt%2FUSB%2FUSB_64G%2FAnne%20Bisson%20-%20Be%20My%20Lover%20(2023)%20%5B24-192%5D%2FAnne%20Bisson-Be%20My%20Lover_WAV%2F05.%20From%20the%20Beginning.wav&metadata=false Dec 20 17:01:46 volumio607 volumio[1318]: info: URI /mnt/USB/USB_64G/Anne Bisson - Be My Lover (2023) [24-192]/Anne Bisson-Be My Lover_WAV/05. From the Beginning.wav Dec 20 17:01:46 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=Anne%20Bisson/Be%20My%20Lover/extralarge&path=%2Fmnt%2FUSB%2FUSB_64G%2FAnne%20Bisson%20-%20Be%20My%20Lover%20(2023)%20%5B24-192%5D%2FAnne%20Bisson-Be%20My%20Lover_WAV%2F06.%20I%20Need%20Protection.wav&metadata=false Dec 20 17:01:46 volumio607 volumio[1318]: info: URI /mnt/USB/USB_64G/Anne Bisson - Be My Lover (2023) [24-192]/Anne Bisson-Be My Lover_WAV/06. I Need Protection.wav Dec 20 17:01:46 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=Anne%20Bisson/Be%20My%20Lover/extralarge&path=%2Fmnt%2FUSB%2FUSB_64G%2FAnne%20Bisson%20-%20Be%20My%20Lover%20(2023)%20%5B24-192%5D%2FAnne%20Bisson-Be%20My%20Lover_WAV%2F07.%20The%20Four%20Horsemen.wav&metadata=false Dec 20 17:01:46 volumio607 volumio[1318]: info: URI /mnt/USB/USB_64G/Anne Bisson - Be My Lover (2023) [24-192]/Anne Bisson-Be My Lover_WAV/07. The Four Horsemen.wav Dec 20 17:01:46 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=Anne%20Bisson/Be%20My%20Lover/extralarge&path=%2Fmnt%2FUSB%2FUSB_64G%2FAnne%20Bisson%20-%20Be%20My%20Lover%20(2023)%20%5B24-192%5D%2FAnne%20Bisson-Be%20My%20Lover_WAV%2F08.%20Bridge%20over%20Troubled%20Water.wav&metadata=false Dec 20 17:01:46 volumio607 volumio[1318]: info: URI /mnt/USB/USB_64G/Anne Bisson - Be My Lover (2023) [24-192]/Anne Bisson-Be My Lover_WAV/08. Bridge over Troubled Water.wav Dec 20 17:01:46 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=Anne%20Bisson/Be%20My%20Lover/extralarge&path=%2Fmnt%2FUSB%2FUSB_64G%2FAnne%20Bisson%20-%20Be%20My%20Lover%20(2023)%20%5B24-192%5D%2FAnne%20Bisson-Be%20My%20Lover_WAV%2F09.%20The%20Seabreeze.wav&metadata=false Dec 20 17:01:46 volumio607 volumio[1318]: info: URI /mnt/USB/USB_64G/Anne Bisson - Be My Lover (2023) [24-192]/Anne Bisson-Be My Lover_WAV/09. The Seabreeze.wav Dec 20 17:01:46 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=Anne%20Bisson/Be%20My%20Lover/extralarge&path=%2Fmnt%2FUSB%2FUSB_64G%2FAnne%20Bisson%20-%20Be%20My%20Lover%20(2023)%20%5B24-192%5D%2FAnne%20Bisson-Be%20My%20Lover_WAV%2F10.%20A%20Million%20Dreams.wav&metadata=false Dec 20 17:01:46 volumio607 volumio[1318]: info: URI /mnt/USB/USB_64G/Anne Bisson - Be My Lover (2023) [24-192]/Anne Bisson-Be My Lover_WAV/10. A Million Dreams.wav Dec 20 17:01:46 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=Anne%20Bisson/Be%20My%20Lover/extralarge&path=%2Fmnt%2FUSB%2FUSB_64G%2FAnne%20Bisson%20-%20Be%20My%20Lover%20(2023)%20%5B24-192%5D%2FAnne%20Bisson-Be%20My%20Lover_WAV%2F11.%20Nature%20Boy.wav&metadata=false Dec 20 17:01:46 volumio607 volumio[1318]: info: URI /mnt/USB/USB_64G/Anne Bisson - Be My Lover (2023) [24-192]/Anne Bisson-Be My Lover_WAV/11. Nature Boy.wav Dec 20 17:01:46 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=Anne%20Bisson/Be%20My%20Lover/extralarge&path=%2Fmnt%2FUSB%2FUSB_64G%2FAnne%20Bisson%20-%20Be%20My%20Lover%20(2023)%20%5B24-192%5D%2FAnne%20Bisson-Be%20My%20Lover_WAV%2F12.%20Be%20My%20Lover.wav&metadata=false Dec 20 17:01:46 volumio607 volumio[1318]: info: URI /mnt/USB/USB_64G/Anne Bisson - Be My Lover (2023) [24-192]/Anne Bisson-Be My Lover_WAV/12. Be My Lover.wav Dec 20 17:01:46 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=CHIE%20AYADO/PRAYER/extralarge&path=%2Fmnt%2FUSB%2FUSB_64G%2FAyado_Prayer_WAV%2FCHIE%20AYADO%20-%20PRAYER%20%5B10%5D.wav&metadata=false Dec 20 17:01:46 volumio607 volumio[1318]: info: URI /mnt/USB/USB_64G/Ayado_Prayer_WAV/CHIE AYADO - PRAYER [10].wav Dec 20 17:01:46 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=CHIE%20AYADO/PRAYER/extralarge&path=%2Fmnt%2FUSB%2FUSB_64G%2FAyado_Prayer_WAV%2FCHIE%20AYADO%20-%20PRAYER%20%5B11%5D.wav&metadata=false Dec 20 17:01:46 volumio607 volumio[1318]: info: URI /mnt/USB/USB_64G/Ayado_Prayer_WAV/CHIE AYADO - PRAYER [11].wav Dec 20 17:01:46 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=CHIE%20AYADO/PRAYER/extralarge&path=%2Fmnt%2FUSB%2FUSB_64G%2FAyado_Prayer_WAV%2FCHIE%20AYADO%20-%20PRAYER%20%5B12%5D.wav&metadata=false Dec 20 17:01:46 volumio607 volumio[1318]: info: URI /mnt/USB/USB_64G/Ayado_Prayer_WAV/CHIE AYADO - PRAYER [12].wav Dec 20 17:01:46 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=CHIE%20AYADO/PRAYER/extralarge&path=%2Fmnt%2FUSB%2FUSB_64G%2FAyado_Prayer_WAV%2FCHIE%20AYADO%20-%20PRAYER%20%5B13%5D.wav&metadata=false Dec 20 17:01:46 volumio607 volumio[1318]: info: URI /mnt/USB/USB_64G/Ayado_Prayer_WAV/CHIE AYADO - PRAYER [13].wav Dec 20 17:01:46 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=CHIE%20AYADO/PRAYER/extralarge&path=%2Fmnt%2FUSB%2FUSB_64G%2FAyado_Prayer_WAV%2FCHIE%20AYADO%20-%20PRAYER%20%5B14%5D.wav&metadata=false Dec 20 17:01:46 volumio607 volumio[1318]: info: URI /mnt/USB/USB_64G/Ayado_Prayer_WAV/CHIE AYADO - PRAYER [14].wav Dec 20 17:01:46 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=CHIE%20AYADO/PRAYER/extralarge&path=%2Fmnt%2FUSB%2FUSB_64G%2FAyado_Prayer_WAV%2FCHIE%20AYADO%20-%20PRAYER%20%5B1%5D.wav&metadata=false Dec 20 17:01:46 volumio607 volumio[1318]: info: URI /mnt/USB/USB_64G/Ayado_Prayer_WAV/CHIE AYADO - PRAYER [1].wav Dec 20 17:01:46 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=CHIE%20AYADO/PRAYER/extralarge&path=%2Fmnt%2FUSB%2FUSB_64G%2FAyado_Prayer_WAV%2FCHIE%20AYADO%20-%20PRAYER%20%5B2%5D.wav&metadata=false Dec 20 17:01:46 volumio607 volumio[1318]: info: URI /mnt/USB/USB_64G/Ayado_Prayer_WAV/CHIE AYADO - PRAYER [2].wav Dec 20 17:01:46 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=CHIE%20AYADO/PRAYER/extralarge&path=%2Fmnt%2FUSB%2FUSB_64G%2FAyado_Prayer_WAV%2FCHIE%20AYADO%20-%20PRAYER%20%5B3%5D.wav&metadata=false Dec 20 17:01:46 volumio607 volumio[1318]: info: URI /mnt/USB/USB_64G/Ayado_Prayer_WAV/CHIE AYADO - PRAYER [3].wav Dec 20 17:01:46 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=CHIE%20AYADO/PRAYER/extralarge&path=%2Fmnt%2FUSB%2FUSB_64G%2FAyado_Prayer_WAV%2FCHIE%20AYADO%20-%20PRAYER%20%5B4%5D.wav&metadata=false Dec 20 17:01:46 volumio607 volumio[1318]: info: URI /mnt/USB/USB_64G/Ayado_Prayer_WAV/CHIE AYADO - PRAYER [4].wav Dec 20 17:01:46 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=CHIE%20AYADO/PRAYER/extralarge&path=%2Fmnt%2FUSB%2FUSB_64G%2FAyado_Prayer_WAV%2FCHIE%20AYADO%20-%20PRAYER%20%5B5%5D.wav&metadata=false Dec 20 17:01:46 volumio607 volumio[1318]: info: URI /mnt/USB/USB_64G/Ayado_Prayer_WAV/CHIE AYADO - PRAYER [5].wav Dec 20 17:01:46 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=CHIE%20AYADO/PRAYER/extralarge&path=%2Fmnt%2FUSB%2FUSB_64G%2FAyado_Prayer_WAV%2FCHIE%20AYADO%20-%20PRAYER%20%5B6%5D.wav&metadata=false Dec 20 17:01:46 volumio607 volumio[1318]: info: URI /mnt/USB/USB_64G/Ayado_Prayer_WAV/CHIE AYADO - PRAYER [6].wav Dec 20 17:01:46 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=CHIE%20AYADO/PRAYER/extralarge&path=%2Fmnt%2FUSB%2FUSB_64G%2FAyado_Prayer_WAV%2FCHIE%20AYADO%20-%20PRAYER%20%5B7%5D.wav&metadata=false Dec 20 17:01:46 volumio607 volumio[1318]: info: URI /mnt/USB/USB_64G/Ayado_Prayer_WAV/CHIE AYADO - PRAYER [7].wav Dec 20 17:01:46 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=CHIE%20AYADO/PRAYER/extralarge&path=%2Fmnt%2FUSB%2FUSB_64G%2FAyado_Prayer_WAV%2FCHIE%20AYADO%20-%20PRAYER%20%5B8%5D.wav&metadata=false Dec 20 17:01:46 volumio607 volumio[1318]: info: URI /mnt/USB/USB_64G/Ayado_Prayer_WAV/CHIE AYADO - PRAYER [8].wav Dec 20 17:01:46 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=CHIE%20AYADO/PRAYER/extralarge&path=%2Fmnt%2FUSB%2FUSB_64G%2FAyado_Prayer_WAV%2FCHIE%20AYADO%20-%20PRAYER%20%5B9%5D.wav&metadata=false Dec 20 17:01:46 volumio607 volumio[1318]: info: URI /mnt/USB/USB_64G/Ayado_Prayer_WAV/CHIE AYADO - PRAYER [9].wav Dec 20 17:01:46 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=Ayako%20Hosokawa%20/A%20Whisper%20of%20Love/extralarge&path=%2Fmnt%2FUSB%2FUSB_64G%2FAyako%20Hosokawa%2F01.%20Ayako%20Hosokawa.wav&metadata=false Dec 20 17:01:46 volumio607 volumio[1318]: info: URI /mnt/USB/USB_64G/Ayako Hosokawa/01. Ayako Hosokawa.wav Dec 20 17:01:46 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=Ayako%20Hosokawa%20/A%20Whisper%20of%20Love/extralarge&path=%2Fmnt%2FUSB%2FUSB_64G%2FAyako%20Hosokawa%2F02.%20Vocal%20De-mud.wav&metadata=false Dec 20 17:01:46 volumio607 volumio[1318]: info: URI /mnt/USB/USB_64G/Ayako Hosokawa/02. Vocal De-mud.wav Dec 20 17:01:46 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=Ayako%20Hosokawa%20/A%20Whisper%20of%20Love/extralarge&path=%2Fmnt%2FUSB%2FUSB_64G%2FAyako%20Hosokawa%2F03.%20Vocal%20forward%20pop.wav&metadata=false Dec 20 17:01:46 volumio607 volumio[1318]: info: URI /mnt/USB/USB_64G/Ayako Hosokawa/03. Vocal forward pop.wav Dec 20 17:01:46 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=Ayako%20Hosokawa%20/A%20Whisper%20of%20Love/extralarge&path=%2Fmnt%2FUSB%2FUSB_64G%2FAyako%20Hosokawa%2F04.%20Vocal%20forward%20R1.wav&metadata=false Dec 20 17:01:46 volumio607 volumio[1318]: info: URI /mnt/USB/USB_64G/Ayako Hosokawa/04. Vocal forward R1.wav Dec 20 17:01:46 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=Ayako%20Hosokawa%20/A%20Whisper%20of%20Love/extralarge&path=%2Fmnt%2FUSB%2FUSB_64G%2FAyako%20Hosokawa%2F05.%20Vocal%20forward%20strong.wav&metadata=false Dec 20 17:01:46 volumio607 volumio[1318]: info: URI /mnt/USB/USB_64G/Ayako Hosokawa/05. Vocal forward strong.wav Dec 20 17:01:46 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=Ayako%20Hosokawa%20/A%20Whisper%20of%20Love/extralarge&path=%2Fmnt%2FUSB%2FUSB_64G%2FAyako%20Hosokawa%2F06.%20Vocal%20forward%20natural.wav&metadata=false Dec 20 17:01:46 volumio607 volumio[1318]: info: URI /mnt/USB/USB_64G/Ayako Hosokawa/06. Vocal forward natural.wav Dec 20 17:01:46 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=Ayako%20Hosokawa/A%20Whisper%20of%20Love/extralarge&path=%2Fmnt%2FUSB%2FUSB_64G%2FAyako%20Hosokawa_A%20Whisper%20of%20Love%2F01.%20Ayako%20Hosokawa.wav&metadata=false Dec 20 17:01:46 volumio607 volumio[1318]: info: URI /mnt/USB/USB_64G/Ayako Hosokawa_A Whisper of Love/01. Ayako Hosokawa.wav Dec 20 17:01:46 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=Ayako%20Hosokawa/A%20Whisper%20of%20Love/extralarge&path=%2Fmnt%2FUSB%2FUSB_64G%2FAyako%20Hosokawa_A%20Whisper%20of%20Love%2F02.%20Ayako%20Hosokawa.wav&metadata=false Dec 20 17:01:46 volumio607 volumio[1318]: info: URI /mnt/USB/USB_64G/Ayako Hosokawa_A Whisper of Love/02. Ayako Hosokawa.wav Dec 20 17:01:46 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=Ayako%20Hosokawa/A%20Whisper%20of%20Love/extralarge&path=%2Fmnt%2FUSB%2FUSB_64G%2FAyako%20Hosokawa_A%20Whisper%20of%20Love%2F03.%20Ayako%20Hosokawa.wav&metadata=false Dec 20 17:01:46 volumio607 volumio[1318]: info: URI /mnt/USB/USB_64G/Ayako Hosokawa_A Whisper of Love/03. Ayako Hosokawa.wav Dec 20 17:01:46 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=Ayako%20Hosokawa/A%20Whisper%20of%20Love/extralarge&path=%2Fmnt%2FUSB%2FUSB_64G%2FAyako%20Hosokawa_A%20Whisper%20of%20Love%2F04.%20Ayako%20Hosokawa.wav&metadata=false Dec 20 17:01:46 volumio607 volumio[1318]: info: URI /mnt/USB/USB_64G/Ayako Hosokawa_A Whisper of Love/04. Ayako Hosokawa.wav Dec 20 17:01:46 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=Ayako%20Hosokawa%20/A%20Whisper%20of%20Love/extralarge&path=%2Fmnt%2FUSB%2FUSB_64G%2FAyako%20Hosokawa_A%20Whisper%20of%20Love%2F05.%20Ayako%20Hosokawa.wav&metadata=false Dec 20 17:01:46 volumio607 volumio[1318]: info: URI /mnt/USB/USB_64G/Ayako Hosokawa_A Whisper of Love/05. Ayako Hosokawa.wav Dec 20 17:01:46 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=Ayako%20Hosokawa%20/A%20Whisper%20of%20Love/extralarge&path=%2Fmnt%2FUSB%2FUSB_64G%2FAyako%20Hosokawa_A%20Whisper%20of%20Love%2F06.%20Ayako%20Hosokawa.wav&metadata=false Dec 20 17:01:46 volumio607 volumio[1318]: info: URI /mnt/USB/USB_64G/Ayako Hosokawa_A Whisper of Love/06. Ayako Hosokawa.wav Dec 20 17:01:46 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=Ayako%20Hosokawa%20/A%20Whisper%20of%20Love/extralarge&path=%2Fmnt%2FUSB%2FUSB_64G%2FAyako%20Hosokawa_A%20Whisper%20of%20Love%2F07.%20Ayako%20Hosokawa.wav&metadata=false Dec 20 17:01:46 volumio607 volumio[1318]: info: URI /mnt/USB/USB_64G/Ayako Hosokawa_A Whisper of Love/07. Ayako Hosokawa.wav Dec 20 17:01:46 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=Ayako%20Hosokawa/A%20Whisper%20of%20Love/extralarge&path=%2Fmnt%2FUSB%2FUSB_64G%2FAyako%20Hosokawa_A%20Whisper%20of%20Love%2F08.%20Ayako%20Hosokawa.wav&metadata=false Dec 20 17:01:46 volumio607 volumio[1318]: info: URI /mnt/USB/USB_64G/Ayako Hosokawa_A Whisper of Love/08. Ayako Hosokawa.wav Dec 20 17:01:46 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=Ayako%20Hosokawa/A%20Whisper%20of%20Love/extralarge&path=%2Fmnt%2FUSB%2FUSB_64G%2FAyako%20Hosokawa_A%20Whisper%20of%20Love%2F09.%20Ayako%20Hosokawa.wav&metadata=false Dec 20 17:01:46 volumio607 volumio[1318]: info: URI /mnt/USB/USB_64G/Ayako Hosokawa_A Whisper of Love/09. Ayako Hosokawa.wav Dec 20 17:01:46 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=Ayako%20Hosokawa/A%20Whisper%20of%20Love/extralarge&path=%2Fmnt%2FUSB%2FUSB_64G%2FAyako%20Hosokawa_A%20Whisper%20of%20Love%2F10.%20Ayako%20Hosokawa.wav&metadata=false Dec 20 17:01:46 volumio607 volumio[1318]: info: URI /mnt/USB/USB_64G/Ayako Hosokawa_A Whisper of Love/10. Ayako Hosokawa.wav Dec 20 17:01:46 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=Ayako%20Hosokawa/A%20Whisper%20of%20Love/extralarge&path=%2Fmnt%2FUSB%2FUSB_64G%2FAyako%20Hosokawa_A%20Whisper%20of%20Love%2F11.%20Ayako%20Hosokawa.wav&metadata=false Dec 20 17:01:46 volumio607 volumio[1318]: info: URI /mnt/USB/USB_64G/Ayako Hosokawa_A Whisper of Love/11. Ayako Hosokawa.wav Dec 20 17:01:46 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=Ayako%20Hosokawa/A%20Whisper%20of%20Love/extralarge&path=%2Fmnt%2FUSB%2FUSB_64G%2FAyako%20Hosokawa_A%20Whisper%20of%20Love%2F12.%20Ayako%20Hosokawa.wav&metadata=false Dec 20 17:01:46 volumio607 volumio[1318]: info: URI /mnt/USB/USB_64G/Ayako Hosokawa_A Whisper of Love/12. Ayako Hosokawa.wav Dec 20 17:01:46 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=Ayako%20Hosokawa%20/A%20Whisper%20of%20Love/extralarge&path=%2Fmnt%2FUSB%2FUSB_64G%2FAyako%20Hosokawa_A%20Whisper%20of%20Love%2F13.%20Ayako%20Hosokawa.wav&metadata=false Dec 20 17:01:46 volumio607 volumio[1318]: info: URI /mnt/USB/USB_64G/Ayako Hosokawa_A Whisper of Love/13. Ayako Hosokawa.wav Dec 20 17:01:46 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=Ayako%20Hosokawa/A%20Whisper%20of%20Love/extralarge&path=%2Fmnt%2FUSB%2FUSB_64G%2FAyako%20Hosokawa_A%20Whisper%20of%20Love%2Falbum.cue&metadata=false Dec 20 17:01:46 volumio607 volumio[1318]: info: URI /mnt/USB/USB_64G/Ayako Hosokawa_A Whisper of Love/album.cue Dec 20 17:01:46 volumio607 volumio[1318]: info: ALBUMART /albumart?cacheid=527&web=Ayako%20Hosokawa/A%20Whisper%20of%20Love/extralarge&path=%2Fmnt%2FUSB%2FUSB_64G%2FAyako%20Hosokawa_A%20Whisper%20of%20Love%2Falbum.cue&metadata=false Dec 20 17:01:46 volumio607 volumio[1318]: info: URI /mnt/USB/USB_64G/Ayako Hosokawa_A Whisper of Love/album.cue Dec 20 17:01:46 volumio607 volumio[1318]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 20 17:01:46 volumio607 volumio[1318]: Error: Unable to resolve or reject the same promise twice Dec 20 17:01:46 volumio607 volumio[1318]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Dec 20 17:01:46 volumio607 volumio[1318]: at /volumio/app/plugins/music_service/mpd/index.js:2587:21 Dec 20 17:01:46 volumio607 volumio[1318]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Dec 20 17:01:46 volumio607 volumio[1318]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Dec 20 17:01:46 volumio607 volumio[1318]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Dec 20 17:01:46 volumio607 volumio[1318]: at Socket.emit (node:events:514:28) Dec 20 17:01:46 volumio607 volumio[1318]: at addChunk (node:internal/streams/readable:343:12) Dec 20 17:01:46 volumio607 volumio[1318]: at readableAddChunk (node:internal/streams/readable:312:11) Dec 20 17:01:46 volumio607 volumio[1318]: at Readable.push (node:internal/streams/readable:253:10) Dec 20 17:01:46 volumio607 volumio[1318]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Dec 20 17:01:46 volumio607 volumio[1318]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 20 17:01:46 volumio607 sudo[2239]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-20 17:00' Dec 20 17:01:46 volumio607 sudo[2239]: 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"