Dec 09 18:09:54 den-volumio volumio[2861]: verbose: New Socket.io Connection to 192.168.1.112:3000 from 192.168.1.203 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Dec 09 18:09:54 den-volumio volumio[2861]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 09 18:09:54 den-volumio volumio[2861]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 09 18:09:54 den-volumio volumio[2861]: info: Discovery: Getting this device information Dec 09 18:09:54 den-volumio volumio[2861]: info: CoreCommandRouter::volumioGetState Dec 09 18:09:54 den-volumio volumio[2861]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 09 18:09:54 den-volumio volumio[2861]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 09 18:09:54 den-volumio volumio[2861]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 09 18:09:54 den-volumio volumio[2861]: info: Discovery: Getting this device information Dec 09 18:09:54 den-volumio volumio[2861]: info: CoreCommandRouter::volumioGetState Dec 09 18:09:54 den-volumio volumio[2861]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 09 18:09:54 den-volumio volumio[2861]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 09 18:09:54 den-volumio volumio[2861]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Dec 09 18:09:54 den-volumio volumio[2861]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Dec 09 18:09:54 den-volumio volumio[2861]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Dec 09 18:09:54 den-volumio sudo[6895]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Dec 09 18:09:54 den-volumio sudo[6895]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 09 18:09:54 den-volumio sudo[6900]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 09 18:09:54 den-volumio sudo[6900]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 09 18:09:55 den-volumio sudo[6900]: pam_unix(sudo:session): session closed for user root Dec 09 18:09:55 den-volumio sudo[6895]: pam_unix(sudo:session): session closed for user root Dec 09 18:09:55 den-volumio sudo[6907]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 09 18:09:55 den-volumio sudo[6907]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 09 18:09:55 den-volumio sudo[6907]: pam_unix(sudo:session): session closed for user root Dec 09 18:09:55 den-volumio sudo[6917]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 09 18:09:55 den-volumio sudo[6917]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 09 18:09:55 den-volumio sudo[6913]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 09 18:09:55 den-volumio sudo[6913]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 09 18:09:55 den-volumio sudo[6917]: pam_unix(sudo:session): session closed for user root Dec 09 18:09:55 den-volumio sudo[6921]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 09 18:09:55 den-volumio sudo[6921]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 09 18:09:55 den-volumio sudo[6921]: pam_unix(sudo:session): session closed for user root Dec 09 18:09:55 den-volumio sudo[6913]: pam_unix(sudo:session): session closed for user root Dec 09 18:09:55 den-volumio volumio[2861]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 09 18:09:55 den-volumio volumio[2861]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 09 18:09:55 den-volumio volumio[2861]: info: Discovery: Getting this device information Dec 09 18:09:55 den-volumio volumio[2861]: info: CoreCommandRouter::volumioGetState Dec 09 18:09:55 den-volumio volumio[2861]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 09 18:09:55 den-volumio volumio[2861]: info: CoreCommandRouter::volumioGetState Dec 09 18:09:55 den-volumio volumio[2861]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 09 18:09:55 den-volumio volumio[2861]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 09 18:09:55 den-volumio volumio[2861]: info: Discovery: Getting this device information Dec 09 18:09:55 den-volumio volumio[2861]: info: CoreCommandRouter::volumioGetState Dec 09 18:09:55 den-volumio volumio[2861]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 09 18:09:55 den-volumio volumio[2861]: verbose: New Socket.io Connection to 192.168.1.112:3000 from 192.168.1.203 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Dec 09 18:09:55 den-volumio volumio[2861]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 09 18:09:55 den-volumio volumio[2861]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 09 18:09:55 den-volumio volumio[2861]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 09 18:09:55 den-volumio volumio[2861]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 09 18:09:55 den-volumio volumio[2861]: info: Discovery: Getting this device information Dec 09 18:09:55 den-volumio volumio[2861]: info: CoreCommandRouter::volumioGetState Dec 09 18:09:55 den-volumio volumio[2861]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 09 18:09:55 den-volumio volumio[2861]: verbose: New Socket.io Connection to 192.168.1.112:3000 from 192.168.1.203 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Dec 09 18:09:55 den-volumio volumio[2861]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 09 18:09:55 den-volumio volumio[2861]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 09 18:09:56 den-volumio volumio[2861]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 09 18:09:56 den-volumio volumio[2861]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 09 18:09:57 den-volumio sudo[6927]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 09 18:09:57 den-volumio sudo[6927]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 09 18:09:57 den-volumio sudo[6927]: pam_unix(sudo:session): session closed for user root Dec 09 18:09:57 den-volumio sudo[6929]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 09 18:09:57 den-volumio sudo[6929]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 09 18:09:57 den-volumio sudo[6929]: pam_unix(sudo:session): session closed for user root Dec 09 18:09:57 den-volumio volumio[2861]: verbose: New Socket.io Connection to 192.168.1.112 from 192.168.1.203 UA: Mozilla/5.0 (Linux; Android 16; Pixel 7 Build/BP3A.251005.004.B2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/142.0.7444.171 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Dec 09 18:09:57 den-volumio sudo[6933]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 09 18:09:57 den-volumio sudo[6933]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 09 18:09:57 den-volumio sudo[6933]: pam_unix(sudo:session): session closed for user root Dec 09 18:09:57 den-volumio sudo[6935]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 09 18:09:57 den-volumio sudo[6935]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 09 18:09:57 den-volumio sudo[6935]: pam_unix(sudo:session): session closed for user root Dec 09 18:09:57 den-volumio volumio[2861]: verbose: New Socket.io Connection to 192.168.1.112 from 192.168.1.203 UA: Mozilla/5.0 (Linux; Android 16; Pixel 7 Build/BP3A.251005.004.B2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/142.0.7444.171 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Dec 09 18:09:57 den-volumio volumio[2861]: info: Executing endpoint metavolumio Dec 09 18:09:57 den-volumio volumio[2861]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 09 18:09:57 den-volumio volumio[2861]: info: Executing endpoint metavolumio Dec 09 18:09:57 den-volumio volumio[2861]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 09 18:09:57 den-volumio volumio[2861]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 09 18:09:57 den-volumio volumio[2861]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 09 18:09:57 den-volumio volumio[2861]: info: CoreCommandRouter::volumioGetVisibleSources Dec 09 18:09:57 den-volumio volumio[2861]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 09 18:09:57 den-volumio volumio[2861]: info: CoreCommandRouter::volumioGetState Dec 09 18:09:57 den-volumio volumio[2861]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 09 18:09:57 den-volumio volumio[2861]: info: CoreCommandRouter::volumioGetQueue Dec 09 18:09:57 den-volumio volumio[2861]: info: CoreStateMachine::getQueue Dec 09 18:09:57 den-volumio volumio[2861]: info: CorePlayQueue::getQueue Dec 09 18:09:57 den-volumio volumio[2861]: info: Listing playlists Dec 09 18:09:57 den-volumio volumio[2861]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 09 18:09:57 den-volumio volumio[2861]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Dec 09 18:09:57 den-volumio volumio[2861]: info: Received Get System Info Dec 09 18:09:57 den-volumio volumio[2861]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 09 18:09:57 den-volumio volumio[2861]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 09 18:09:57 den-volumio volumio[2861]: info: Discovery: Getting this device information Dec 09 18:09:57 den-volumio volumio[2861]: info: CoreCommandRouter::volumioGetState Dec 09 18:09:57 den-volumio volumio[2861]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 09 18:09:57 den-volumio volumio[2861]: info: CoreCommandRouter::volumioGetState Dec 09 18:09:57 den-volumio volumio[2861]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 09 18:09:57 den-volumio volumio[2861]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 09 18:09:59 den-volumio volumio[2861]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 09 18:09:59 den-volumio volumio[2861]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 09 18:09:59 den-volumio volumio[2861]: info: Received Get System Info Dec 09 18:09:59 den-volumio volumio[2861]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 09 18:09:59 den-volumio volumio[2861]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 09 18:09:59 den-volumio volumio[2861]: info: Discovery: Getting this device information Dec 09 18:09:59 den-volumio volumio[2861]: info: CoreCommandRouter::volumioGetState Dec 09 18:09:59 den-volumio volumio[2861]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 09 18:09:59 den-volumio volumio[2861]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 09 18:09:59 den-volumio volumio[2861]: info: Received Get System Info Dec 09 18:09:59 den-volumio volumio[2861]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 09 18:09:59 den-volumio volumio[2861]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 09 18:09:59 den-volumio volumio[2861]: info: Discovery: Getting this device information Dec 09 18:09:59 den-volumio volumio[2861]: info: CoreCommandRouter::volumioGetState Dec 09 18:09:59 den-volumio volumio[2861]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 09 18:10:01 den-volumio volumio[2861]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Dec 09 18:10:01 den-volumio volumio[2861]: info: handleBrowseUri took 129 milliseconds Dec 09 18:10:01 den-volumio volumio[2861]: info: Preload queue cleared Dec 09 18:10:01 den-volumio volumio[2861]: info: Preload queue cleared Dec 09 18:10:01 den-volumio volumio[2861]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Dec 09 18:10:02 den-volumio volumio[2861]: info: handleBrowseUri took 1067 milliseconds Dec 09 18:10:03 den-volumio volumio[2861]: info: Preload queue cleared Dec 09 18:10:05 den-volumio volumio[2861]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 09 18:10:05 den-volumio volumio[2861]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 09 18:10:05 den-volumio volumio[2861]: info: Discovery: Getting this device information Dec 09 18:10:05 den-volumio volumio[2861]: info: CoreCommandRouter::volumioGetState Dec 09 18:10:05 den-volumio volumio[2861]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 09 18:10:05 den-volumio volumio[2861]: verbose: New Socket.io Connection to 192.168.1.112:3000 from 192.168.1.203 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Dec 09 18:10:05 den-volumio volumio[2861]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 09 18:10:05 den-volumio volumio[2861]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 09 18:10:07 den-volumio volumio[2861]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 09 18:10:13 den-volumio volumio[2861]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Dec 09 18:10:13 den-volumio volumio[2861]: info: handleBrowseUri took 359 milliseconds Dec 09 18:10:13 den-volumio volumio[2861]: info: Preload queue cleared Dec 09 18:10:13 den-volumio volumio[2861]: info: Preloading song: qobuz://song/355738883 Dec 09 18:10:13 den-volumio volumio[2861]: info: Preloading song: qobuz://song/355738884 Dec 09 18:10:13 den-volumio volumio[2861]: info: Preloading song: qobuz://song/355738885 Dec 09 18:10:13 den-volumio volumio[2861]: info: Preloading song: qobuz://song/355738886 Dec 09 18:10:13 den-volumio volumio[2861]: info: Preloading song: qobuz://song/355738887 Dec 09 18:10:13 den-volumio volumio[2861]: info: Preloading song: qobuz://song/355738888 Dec 09 18:10:13 den-volumio volumio[2861]: info: Preloading song: qobuz://song/355738889 Dec 09 18:10:13 den-volumio volumio[2861]: info: Preloading song: qobuz://song/355738890 Dec 09 18:10:13 den-volumio volumio[2861]: info: Preloading song: qobuz://song/355738891 Dec 09 18:10:13 den-volumio volumio[2861]: info: Preloading song: qobuz://song/355738892 Dec 09 18:10:13 den-volumio volumio[2861]: info: Preloading song: qobuz://song/355738893 Dec 09 18:10:13 den-volumio volumio[2861]: info: Preloading song: qobuz://song/355738894 Dec 09 18:10:13 den-volumio volumio[2861]: info: Preloading song: qobuz://song/355738895 Dec 09 18:10:13 den-volumio volumio[2861]: info: Preloading song: qobuz://song/355738896 Dec 09 18:10:13 den-volumio volumio[2861]: info: Preloading song: qobuz://song/355738897 Dec 09 18:10:13 den-volumio volumio[2861]: info: Preloading song: qobuz://song/355738898 Dec 09 18:10:13 den-volumio volumio[2861]: info: Preloading song: qobuz://song/355738899 Dec 09 18:10:13 den-volumio volumio[2861]: info: Preloading song: qobuz://song/355738900 Dec 09 18:10:13 den-volumio volumio[2861]: info: Preloading song: qobuz://song/355738901 Dec 09 18:10:13 den-volumio volumio[2861]: info: Preloading song: qobuz://song/355738902 Dec 09 18:10:13 den-volumio volumio[2861]: info: Preloading song: qobuz://song/355738903 Dec 09 18:10:13 den-volumio volumio[2861]: info: Preloading song: qobuz://song/355738904 Dec 09 18:10:13 den-volumio volumio[2861]: info: Exploding uri qobuz://song/355738883 in service qobuz Dec 09 18:10:13 den-volumio volumio[2861]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 09 18:10:13 den-volumio volumio[2861]: info: Exploding uri qobuz://song/355738884 in service qobuz Dec 09 18:10:13 den-volumio volumio[2861]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 09 18:10:13 den-volumio volumio[2861]: info: Exploding uri qobuz://song/355738885 in service qobuz Dec 09 18:10:13 den-volumio volumio[2861]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 09 18:10:14 den-volumio volumio[2861]: info: Executing endpoint getSimilarAlbums Dec 09 18:10:14 den-volumio volumio[2861]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Dec 09 18:10:14 den-volumio volumio[2861]: info: Exploding uri qobuz://song/355738886 in service qobuz Dec 09 18:10:14 den-volumio volumio[2861]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 09 18:10:14 den-volumio volumio[2861]: info: Exploding uri qobuz://song/355738887 in service qobuz Dec 09 18:10:14 den-volumio volumio[2861]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 09 18:10:14 den-volumio volumio[2861]: info: Executing endpoint metavolumio Dec 09 18:10:14 den-volumio volumio[2861]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 09 18:10:14 den-volumio volumio[2861]: info: Executing endpoint metavolumio Dec 09 18:10:14 den-volumio volumio[2861]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 09 18:10:14 den-volumio volumio[2861]: info: Exploding uri qobuz://song/355738888 in service qobuz Dec 09 18:10:14 den-volumio volumio[2861]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 09 18:10:14 den-volumio volumio[2861]: info: explodeUri took 282 milliseconds Dec 09 18:10:14 den-volumio volumio[2861]: info: Exploding uri qobuz://song/355738889 in service qobuz Dec 09 18:10:14 den-volumio volumio[2861]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 09 18:10:14 den-volumio volumio[2861]: info: Exploding uri qobuz://song/355738890 in service qobuz Dec 09 18:10:14 den-volumio volumio[2861]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 09 18:10:14 den-volumio volumio[2861]: info: explodeUri took 322 milliseconds Dec 09 18:10:14 den-volumio volumio[2861]: info: Exploding uri qobuz://song/355738891 in service qobuz Dec 09 18:10:14 den-volumio volumio[2861]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 09 18:10:14 den-volumio volumio[2861]: info: explodeUri took 304 milliseconds Dec 09 18:10:14 den-volumio volumio[2861]: info: Exploding uri qobuz://song/355738892 in service qobuz Dec 09 18:10:14 den-volumio volumio[2861]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 09 18:10:14 den-volumio volumio[2861]: info: explodeUri took 340 milliseconds Dec 09 18:10:14 den-volumio volumio[2861]: info: Exploding uri qobuz://song/355738893 in service qobuz Dec 09 18:10:14 den-volumio volumio[2861]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 09 18:10:14 den-volumio volumio[2861]: info: explodeUri took 219 milliseconds Dec 09 18:10:14 den-volumio volumio[2861]: info: explodeUri took 324 milliseconds Dec 09 18:10:14 den-volumio volumio[2861]: info: Exploding uri qobuz://song/355738894 in service qobuz Dec 09 18:10:14 den-volumio volumio[2861]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 09 18:10:14 den-volumio volumio[2861]: info: explodeUri took 307 milliseconds Dec 09 18:10:14 den-volumio volumio[2861]: info: Exploding uri qobuz://song/355738895 in service qobuz Dec 09 18:10:14 den-volumio volumio[2861]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 09 18:10:14 den-volumio volumio[2861]: info: explodeUri took 288 milliseconds Dec 09 18:10:14 den-volumio volumio[2861]: info: Exploding uri qobuz://song/355738896 in service qobuz Dec 09 18:10:14 den-volumio volumio[2861]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 09 18:10:14 den-volumio volumio[2861]: info: explodeUri took 259 milliseconds Dec 09 18:10:14 den-volumio volumio[2861]: info: explodeUri took 224 milliseconds Dec 09 18:10:14 den-volumio volumio[2861]: info: Exploding uri qobuz://song/355738897 in service qobuz Dec 09 18:10:14 den-volumio volumio[2861]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 09 18:10:14 den-volumio volumio[2861]: info: Exploding uri qobuz://song/355738898 in service qobuz Dec 09 18:10:14 den-volumio volumio[2861]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 09 18:10:14 den-volumio volumio[2861]: info: explodeUri took 210 milliseconds Dec 09 18:10:14 den-volumio volumio[2861]: info: Exploding uri qobuz://song/355738899 in service qobuz Dec 09 18:10:14 den-volumio volumio[2861]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 09 18:10:14 den-volumio volumio[2861]: info: explodeUri took 220 milliseconds Dec 09 18:10:14 den-volumio volumio[2861]: info: explodeUri took 321 milliseconds Dec 09 18:10:14 den-volumio volumio[2861]: info: Exploding uri qobuz://song/355738900 in service qobuz Dec 09 18:10:14 den-volumio volumio[2861]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 09 18:10:14 den-volumio volumio[2861]: info: explodeUri took 214 milliseconds Dec 09 18:10:14 den-volumio volumio[2861]: info: Exploding uri qobuz://song/355738901 in service qobuz Dec 09 18:10:14 den-volumio volumio[2861]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 09 18:10:14 den-volumio volumio[2861]: info: explodeUri took 207 milliseconds Dec 09 18:10:14 den-volumio volumio[2861]: info: Exploding uri qobuz://song/355738902 in service qobuz Dec 09 18:10:14 den-volumio volumio[2861]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 09 18:10:14 den-volumio volumio[2861]: info: explodeUri took 222 milliseconds Dec 09 18:10:14 den-volumio volumio[2861]: info: Exploding uri qobuz://song/355738903 in service qobuz Dec 09 18:10:14 den-volumio volumio[2861]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 09 18:10:14 den-volumio volumio[2861]: info: explodeUri took 214 milliseconds Dec 09 18:10:14 den-volumio volumio[2861]: info: Exploding uri qobuz://song/355738904 in service qobuz Dec 09 18:10:14 den-volumio volumio[2861]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 09 18:10:14 den-volumio volumio[2861]: info: explodeUri took 214 milliseconds Dec 09 18:10:14 den-volumio volumio[2861]: info: explodeUri took 203 milliseconds Dec 09 18:10:15 den-volumio volumio[2861]: info: explodeUri took 232 milliseconds Dec 09 18:10:15 den-volumio volumio[2861]: info: explodeUri took 232 milliseconds Dec 09 18:10:15 den-volumio volumio[2861]: info: explodeUri took 204 milliseconds Dec 09 18:10:15 den-volumio volumio[2861]: info: Preload queue cleared Dec 09 18:10:15 den-volumio volumio[2861]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 09 18:10:15 den-volumio volumio[2861]: info: CoreStateMachine::ClearQueue Dec 09 18:10:15 den-volumio volumio[2861]: info: CoreStateMachine::stop Dec 09 18:10:15 den-volumio volumio[2861]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 09 18:10:15 den-volumio volumio[2861]: info: CoreStateMachine::updateTrackBlock Dec 09 18:10:15 den-volumio volumio[2861]: info: CorePlayQueue::getTrackBlock Dec 09 18:10:15 den-volumio volumio[2861]: info: CoreStateMachine::stPlaybackTimer Dec 09 18:10:15 den-volumio volumio[2861]: info: CoreStateMachine::pushState Dec 09 18:10:15 den-volumio volumio[2861]: info: CorePlayQueue::getTrack 0 Dec 09 18:10:15 den-volumio volumio[2861]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 09 18:10:15 den-volumio volumio[2861]: info: CoreCommandRouter::volumioPushState Dec 09 18:10:15 den-volumio volumio[2861]: info: MRS: Pushing multiroomSync output update for this device Dec 09 18:10:15 den-volumio volumio[2861]: info: MRS: Pushing multiroomSync output Dec 09 18:10:15 den-volumio volumio[2861]: info: CoreCommandRouter::volumioGetState Dec 09 18:10:15 den-volumio volumio[2861]: info: CorePlayQueue::getTrack 0 Dec 09 18:10:15 den-volumio volumio[2861]: info: CoreStateMachine::serviceStop Dec 09 18:10:15 den-volumio volumio[2861]: info: CorePlayQueue::getTrack 0 Dec 09 18:10:15 den-volumio volumio[2861]: info: CoreCommandRouter::serviceStop Dec 09 18:10:15 den-volumio volumio[2861]: info: [1765325415752] ControllerQobuz::stop Dec 09 18:10:15 den-volumio volumio[2861]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 09 18:10:15 den-volumio volumio[2861]: info: ControllerMpd::stop Dec 09 18:10:15 den-volumio volumio[2861]: verbose: ControllerMpd::sendMpdCommand stop Dec 09 18:10:15 den-volumio volumio[2861]: info: CorePlayQueue::clearPlayQueue Dec 09 18:10:15 den-volumio volumio[2861]: info: CorePlayQueue::saveQueue Dec 09 18:10:15 den-volumio volumio[2861]: info: CoreCommandRouter::volumioPushQueue Dec 09 18:10:15 den-volumio volumio[2861]: info: CoreStateMachine::addQueueItems Dec 09 18:10:15 den-volumio volumio[2861]: info: CorePlayQueue::addQueueItems Dec 09 18:10:15 den-volumio volumio[2861]: info: Preload queue cleared Dec 09 18:10:15 den-volumio volumio[2861]: info: Adding Item to queue: qobuz://album/y1af8moi1jvwc Dec 09 18:10:15 den-volumio volumio[2861]: info: Exploding uri qobuz://album/y1af8moi1jvwc in service qobuz Dec 09 18:10:15 den-volumio volumio[2861]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 09 18:10:15 den-volumio volumio[2861]: info: Dec 09 18:10:15 den-volumio volumio[2861]: ---------------------------- MPD announces state update: player Dec 09 18:10:15 den-volumio volumio[2861]: info: sendMpdCommand stop took 22 milliseconds Dec 09 18:10:15 den-volumio volumio[2861]: info: ControllerMpd::getState Dec 09 18:10:15 den-volumio volumio[2861]: verbose: ControllerMpd::sendMpdCommand status Dec 09 18:10:15 den-volumio volumio[2861]: info: sendMpdCommand status took 1 milliseconds Dec 09 18:10:15 den-volumio volumio[2861]: verbose: ControllerMpd::parseState Dec 09 18:10:15 den-volumio volumio[2861]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 09 18:10:15 den-volumio volumio[2861]: info: Dec 09 18:10:15 den-volumio volumio[2861]: ---------------------------- MPD announces state update: player Dec 09 18:10:15 den-volumio volumio[2861]: info: ControllerMpd::getState Dec 09 18:10:15 den-volumio volumio[2861]: verbose: ControllerMpd::sendMpdCommand status Dec 09 18:10:15 den-volumio volumio[2861]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 09 18:10:15 den-volumio volumio[2861]: info: sendMpdCommand status took 2 milliseconds Dec 09 18:10:15 den-volumio volumio[2861]: verbose: ControllerMpd::parseTrackInfo Dec 09 18:10:15 den-volumio volumio[2861]: verbose: ControllerMpd::parseState Dec 09 18:10:15 den-volumio volumio[2861]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 09 18:10:15 den-volumio volumio[2861]: info: ControllerMpd::pushState Dec 09 18:10:15 den-volumio volumio[2861]: info: CoreCommandRouter::servicePushState Dec 09 18:10:15 den-volumio volumio[2861]: info: CoreStateMachine::pushState Dec 09 18:10:15 den-volumio volumio[2861]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 09 18:10:15 den-volumio volumio[2861]: info: CoreCommandRouter::volumioPushState Dec 09 18:10:15 den-volumio volumio[2861]: info: MRS: Pushing multiroomSync output update for this device Dec 09 18:10:15 den-volumio volumio[2861]: info: MRS: Pushing multiroomSync output Dec 09 18:10:15 den-volumio volumio[2861]: info: CoreCommandRouter::volumioGetState Dec 09 18:10:15 den-volumio volumio[2861]: info: CorePlayQueue::getTrack 0 Dec 09 18:10:15 den-volumio volumio[2861]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"file?uid=1019108&eid=372384288&fmt=6&profile=raw&app_id=539451548&cid=1274695&etsp=1765327654&hmac=OtYIJGoMMzorF81vwmNuGOQhd8M","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=1019108&eid=372384288&fmt=6&profile=raw&app_id=539451548&cid=1274695&etsp=1765327654&hmac=OtYIJGoMMzorF81vwmNuGOQhd8M","trackType":"qobuz"} Dec 09 18:10:15 den-volumio volumio[2861]: verbose: CURRENT POSITION 0 Dec 09 18:10:15 den-volumio volumio[2861]: info: CoreStateMachine::syncState stateService stop Dec 09 18:10:15 den-volumio volumio[2861]: info: CoreStateMachine::syncState currentStatus stop Dec 09 18:10:15 den-volumio volumio[2861]: info: CoreStateMachine::pushState Dec 09 18:10:15 den-volumio volumio[2861]: info: CoreCommandRouter::volumioPushState Dec 09 18:10:15 den-volumio volumio[2861]: info: MRS: Pushing multiroomSync output update for this device Dec 09 18:10:15 den-volumio volumio[2861]: info: MRS: Pushing multiroomSync output Dec 09 18:10:15 den-volumio volumio[2861]: info: CoreCommandRouter::volumioGetState Dec 09 18:10:15 den-volumio volumio[2861]: info: No code Dec 09 18:10:15 den-volumio volumio[2861]: info: CoreStateMachine::pushState Dec 09 18:10:15 den-volumio volumio[2861]: info: CoreCommandRouter::volumioPushState Dec 09 18:10:15 den-volumio volumio[2861]: info: MRS: Pushing multiroomSync output update for this device Dec 09 18:10:15 den-volumio volumio[2861]: info: MRS: Pushing multiroomSync output Dec 09 18:10:15 den-volumio volumio[2861]: info: CoreCommandRouter::volumioGetState Dec 09 18:10:15 den-volumio volumio[2861]: info: ------------------------------ 44ms Dec 09 18:10:15 den-volumio volumio[2861]: info: sendMpdCommand playlistinfo took 47 milliseconds Dec 09 18:10:15 den-volumio volumio[2861]: verbose: ControllerMpd::parseTrackInfo Dec 09 18:10:15 den-volumio volumio[2861]: info: ControllerMpd::pushState Dec 09 18:10:15 den-volumio volumio[2861]: info: CoreCommandRouter::servicePushState Dec 09 18:10:15 den-volumio volumio[2861]: info: CoreStateMachine::pushState Dec 09 18:10:15 den-volumio volumio[2861]: info: CoreCommandRouter::volumioPushState Dec 09 18:10:15 den-volumio volumio[2861]: info: MRS: Pushing multiroomSync output update for this device Dec 09 18:10:15 den-volumio volumio[2861]: info: MRS: Pushing multiroomSync output Dec 09 18:10:15 den-volumio volumio[2861]: info: CoreCommandRouter::volumioGetState Dec 09 18:10:15 den-volumio volumio[2861]: info: CorePlayQueue::getTrack 0 Dec 09 18:10:15 den-volumio volumio[2861]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"file?uid=1019108&eid=372384288&fmt=6&profile=raw&app_id=539451548&cid=1274695&etsp=1765327654&hmac=OtYIJGoMMzorF81vwmNuGOQhd8M","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=1019108&eid=372384288&fmt=6&profile=raw&app_id=539451548&cid=1274695&etsp=1765327654&hmac=OtYIJGoMMzorF81vwmNuGOQhd8M","trackType":"qobuz"} Dec 09 18:10:15 den-volumio volumio[2861]: verbose: CURRENT POSITION 0 Dec 09 18:10:15 den-volumio volumio[2861]: info: CoreStateMachine::syncState stateService stop Dec 09 18:10:15 den-volumio volumio[2861]: info: CoreStateMachine::syncState currentStatus stop Dec 09 18:10:15 den-volumio volumio[2861]: info: CoreStateMachine::pushState Dec 09 18:10:15 den-volumio volumio[2861]: info: CoreCommandRouter::volumioPushState Dec 09 18:10:15 den-volumio volumio[2861]: info: MRS: Pushing multiroomSync output update for this device Dec 09 18:10:15 den-volumio volumio[2861]: info: MRS: Pushing multiroomSync output Dec 09 18:10:15 den-volumio volumio[2861]: info: CoreCommandRouter::volumioGetState Dec 09 18:10:15 den-volumio volumio[2861]: info: No code Dec 09 18:10:15 den-volumio volumio[2861]: info: CoreStateMachine::pushState Dec 09 18:10:15 den-volumio volumio[2861]: info: CoreCommandRouter::volumioPushState Dec 09 18:10:15 den-volumio volumio[2861]: info: MRS: Pushing multiroomSync output update for this device Dec 09 18:10:15 den-volumio volumio[2861]: info: MRS: Pushing multiroomSync output Dec 09 18:10:15 den-volumio volumio[2861]: info: CoreCommandRouter::volumioGetState Dec 09 18:10:15 den-volumio volumio[2861]: info: ------------------------------ 83ms Dec 09 18:10:15 den-volumio volumio[2861]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 09 18:10:15 den-volumio volumio[2861]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 09 18:10:15 den-volumio volumio[2861]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 09 18:10:15 den-volumio volumio[2861]: info: Received Get System Version Dec 09 18:10:15 den-volumio volumio[2861]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 09 18:10:15 den-volumio volumio[2861]: info: Received Get System Info Dec 09 18:10:15 den-volumio volumio[2861]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 09 18:10:15 den-volumio volumio[2861]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 09 18:10:15 den-volumio volumio[2861]: info: Discovery: Getting this device information Dec 09 18:10:15 den-volumio volumio[2861]: info: CoreCommandRouter::volumioGetState Dec 09 18:10:15 den-volumio volumio[2861]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 09 18:10:16 den-volumio volumio[2861]: info: explodeUri took 280 milliseconds Dec 09 18:10:16 den-volumio volumio[2861]: info: CoreCommandRouter::volumioPushQueue Dec 09 18:10:16 den-volumio volumio[2861]: info: CorePlayQueue::saveQueue Dec 09 18:10:16 den-volumio volumio[2861]: info: CoreStateMachine::updateTrackBlock Dec 09 18:10:16 den-volumio volumio[2861]: info: CorePlayQueue::getTrackBlock Dec 09 18:10:16 den-volumio volumio[2861]: info: CoreCommandRouter::volumioGetState Dec 09 18:10:16 den-volumio volumio[2861]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Dec 09 18:10:16 den-volumio volumio[2861]: info: CoreCommandRouter::volumioPlay Dec 09 18:10:16 den-volumio volumio[2861]: info: CoreStateMachine::play index 0 Dec 09 18:10:16 den-volumio volumio[2861]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 09 18:10:16 den-volumio volumio[2861]: info: CoreStateMachine::stop Dec 09 18:10:16 den-volumio volumio[2861]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 09 18:10:16 den-volumio volumio[2861]: info: CoreStateMachine::play index undefined Dec 09 18:10:16 den-volumio volumio[2861]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 09 18:10:16 den-volumio volumio[2861]: info: CorePlayQueue::getTrack 0 Dec 09 18:10:16 den-volumio volumio[2861]: info: CoreStateMachine::startPlaybackTimer Dec 09 18:10:16 den-volumio volumio[2861]: info: CorePlayQueue::getTrack 0 Dec 09 18:10:16 den-volumio volumio[2861]: info: [1765325416044] ControllerQobuz::clearAddPlayTrack Dec 09 18:10:16 den-volumio volumio[2861]: info: getStreamUrl took 183 milliseconds Dec 09 18:10:16 den-volumio volumio[2861]: verbose: ControllerMpd::sendMpdCommand stop Dec 09 18:10:16 den-volumio volumio[2861]: info: sendMpdCommand stop took 0 milliseconds Dec 09 18:10:16 den-volumio volumio[2861]: verbose: ControllerMpd::sendMpdCommand clear Dec 09 18:10:16 den-volumio volumio[2861]: info: Dec 09 18:10:16 den-volumio volumio[2861]: ---------------------------- MPD announces system playlist update Dec 09 18:10:16 den-volumio volumio[2861]: info: Ignoring MPD Status Update Dec 09 18:10:16 den-volumio volumio[2861]: info: sendMpdCommand clear took 1 milliseconds Dec 09 18:10:16 den-volumio volumio[2861]: verbose: ControllerMpd::sendMpdCommand load "https://streaming-qobuz-std.akamaized.net/file?uid=1019108&eid=355738883&fmt=7&profile=raw&app_id=539451548&cid=1274695&etsp=1765329016&hmac=DVsXWBvTPblkNYxJkeCYr_Z4-PI" Dec 09 18:10:16 den-volumio volumio[2861]: info: Dec 09 18:10:16 den-volumio volumio[2861]: ---------------------------- MPD announces system playlist update Dec 09 18:10:16 den-volumio volumio[2861]: info: Ignoring MPD Status Update Dec 09 18:10:16 den-volumio volumio[2861]: error: updateQueue error: null Dec 09 18:10:16 den-volumio volumio[2861]: info: ------------------------------ 5ms Dec 09 18:10:17 den-volumio volumio[2861]: verbose: ControllerMpd::sendMpdCommand add "https://streaming-qobuz-std.akamaized.net/file?uid=1019108&eid=355738883&fmt=7&profile=raw&app_id=539451548&cid=1274695&etsp=1765329016&hmac=DVsXWBvTPblkNYxJkeCYr_Z4-PI" Dec 09 18:10:17 den-volumio volumio[2861]: error: updateQueue error: null Dec 09 18:10:17 den-volumio volumio[2861]: info: Dec 09 18:10:17 den-volumio volumio[2861]: ---------------------------- MPD announces system playlist update Dec 09 18:10:17 den-volumio volumio[2861]: info: Ignoring MPD Status Update Dec 09 18:10:17 den-volumio volumio[2861]: info: ------------------------------ 1014ms Dec 09 18:10:17 den-volumio volumio[2861]: info: sendMpdCommand add "https://streaming-qobuz-std.akamaized.net/file?uid=1019108&eid=355738883&fmt=7&profile=raw&app_id=539451548&cid=1274695&etsp=1765329016&hmac=DVsXWBvTPblkNYxJkeCYr_Z4-PI" took 2 milliseconds Dec 09 18:10:17 den-volumio volumio[2861]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 09 18:10:17 den-volumio volumio[2861]: verbose: ControllerMpd::sendMpdCommand play Dec 09 18:10:17 den-volumio volumio[2861]: info: Dec 09 18:10:17 den-volumio volumio[2861]: ---------------------------- MPD announces system playlist update Dec 09 18:10:17 den-volumio volumio[2861]: info: Ignoring MPD Status Update Dec 09 18:10:17 den-volumio volumio[2861]: info: ------------------------------ 4ms Dec 09 18:10:17 den-volumio volumio[2861]: info: sendMpdCommand play took 2 milliseconds Dec 09 18:10:17 den-volumio volumio[2861]: info: ------------------------------ 3ms Dec 09 18:10:17 den-volumio volumio[2861]: info: Dec 09 18:10:17 den-volumio volumio[2861]: ---------------------------- MPD announces state update: player Dec 09 18:10:17 den-volumio volumio[2861]: info: ControllerMpd::getState Dec 09 18:10:17 den-volumio volumio[2861]: verbose: ControllerMpd::sendMpdCommand status Dec 09 18:10:17 den-volumio volumio[2861]: info: Dec 09 18:10:17 den-volumio volumio[2861]: ---------------------------- MPD announces state update: player Dec 09 18:10:17 den-volumio volumio[2861]: info: ControllerMpd::getState Dec 09 18:10:17 den-volumio volumio[2861]: verbose: ControllerMpd::sendMpdCommand status Dec 09 18:10:17 den-volumio volumio[2861]: info: Dec 09 18:10:17 den-volumio volumio[2861]: ---------------------------- MPD announces state update: player Dec 09 18:10:17 den-volumio volumio[2861]: info: sendMpdCommand status took 4 milliseconds Dec 09 18:10:17 den-volumio volumio[2861]: info: ControllerMpd::getState Dec 09 18:10:17 den-volumio volumio[2861]: verbose: ControllerMpd::sendMpdCommand status Dec 09 18:10:17 den-volumio volumio[2861]: verbose: ControllerMpd::parseState Dec 09 18:10:17 den-volumio volumio[2861]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 09 18:10:17 den-volumio volumio[2861]: info: sendMpdCommand status took 6 milliseconds Dec 09 18:10:17 den-volumio volumio[2861]: info: sendMpdCommand status took 3 milliseconds Dec 09 18:10:17 den-volumio volumio[2861]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 09 18:10:17 den-volumio volumio[2861]: verbose: ControllerMpd::parseState Dec 09 18:10:17 den-volumio kernel: bcm2835-i2s fe203000.i2s: I2S SYNC error! Dec 09 18:10:17 den-volumio volumio[2861]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 09 18:10:17 den-volumio volumio[2861]: verbose: ControllerMpd::parseState Dec 09 18:10:17 den-volumio volumio[2861]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 09 18:10:17 den-volumio volumio[2861]: verbose: ControllerMpd::parseTrackInfo Dec 09 18:10:17 den-volumio volumio[2861]: info: ControllerMpd::pushState Dec 09 18:10:17 den-volumio volumio[2861]: info: CoreCommandRouter::servicePushState Dec 09 18:10:17 den-volumio volumio[2861]: info: CorePlayQueue::getTrack 0 Dec 09 18:10:17 den-volumio volumio[2861]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":208,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"file?uid=1019108&eid=355738883&fmt=7&profile=raw&app_id=539451548&cid=1274695&etsp=1765329016&hmac=DVsXWBvTPblkNYxJkeCYr_Z4-PI","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=1019108&eid=355738883&fmt=7&profile=raw&app_id=539451548&cid=1274695&etsp=1765329016&hmac=DVsXWBvTPblkNYxJkeCYr_Z4-PI","trackType":"qobuz"} Dec 09 18:10:17 den-volumio volumio[2861]: verbose: CURRENT POSITION 0 Dec 09 18:10:17 den-volumio volumio[2861]: info: CoreStateMachine::syncState stateService play Dec 09 18:10:17 den-volumio volumio[2861]: info: CoreStateMachine::syncState currentStatus stop Dec 09 18:10:17 den-volumio volumio[2861]: info: ------------------------------ 12ms Dec 09 18:10:17 den-volumio volumio[2861]: info: Dec 09 18:10:17 den-volumio volumio[2861]: ---------------------------- MPD announces state update: player Dec 09 18:10:17 den-volumio volumio[2861]: info: ControllerMpd::getState Dec 09 18:10:17 den-volumio volumio[2861]: verbose: ControllerMpd::sendMpdCommand status Dec 09 18:10:17 den-volumio volumio[2861]: info: sendMpdCommand playlistinfo took 6 milliseconds Dec 09 18:10:17 den-volumio volumio[2861]: info: sendMpdCommand playlistinfo took 6 milliseconds Dec 09 18:10:17 den-volumio volumio[2861]: info: sendMpdCommand status took 1 milliseconds Dec 09 18:10:17 den-volumio volumio[2861]: verbose: ControllerMpd::parseTrackInfo Dec 09 18:10:17 den-volumio volumio[2861]: verbose: ControllerMpd::parseTrackInfo Dec 09 18:10:17 den-volumio volumio[2861]: verbose: ControllerMpd::parseState Dec 09 18:10:17 den-volumio volumio[2861]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 09 18:10:17 den-volumio volumio[2861]: info: ControllerMpd::pushState Dec 09 18:10:17 den-volumio volumio[2861]: info: CoreCommandRouter::servicePushState Dec 09 18:10:17 den-volumio volumio[2861]: info: CorePlayQueue::getTrack 0 Dec 09 18:10:17 den-volumio volumio[2861]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":208,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"file?uid=1019108&eid=355738883&fmt=7&profile=raw&app_id=539451548&cid=1274695&etsp=1765329016&hmac=DVsXWBvTPblkNYxJkeCYr_Z4-PI","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=1019108&eid=355738883&fmt=7&profile=raw&app_id=539451548&cid=1274695&etsp=1765329016&hmac=DVsXWBvTPblkNYxJkeCYr_Z4-PI","trackType":"qobuz"} Dec 09 18:10:17 den-volumio volumio[2861]: verbose: CURRENT POSITION 0 Dec 09 18:10:17 den-volumio volumio[2861]: info: CoreStateMachine::syncState stateService play Dec 09 18:10:17 den-volumio volumio[2861]: info: CoreStateMachine::syncState currentStatus play Dec 09 18:10:17 den-volumio volumio[2861]: info: Received an update from plugin. extracting info from payload Dec 09 18:10:17 den-volumio volumio[2861]: info: CoreStateMachine::pushState Dec 09 18:10:17 den-volumio volumio[2861]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 09 18:10:17 den-volumio volumio[2861]: info: CoreCommandRouter::volumioPushState Dec 09 18:10:17 den-volumio volumio[2861]: info: MRS: Pushing multiroomSync output update for this device Dec 09 18:10:17 den-volumio volumio[2861]: info: MRS: Pushing multiroomSync output Dec 09 18:10:17 den-volumio volumio[2861]: info: CoreCommandRouter::volumioGetState Dec 09 18:10:17 den-volumio volumio[2861]: info: CoreStateMachine::pushState Dec 09 18:10:17 den-volumio volumio[2861]: info: CoreCommandRouter::volumioPushState Dec 09 18:10:17 den-volumio volumio[2861]: info: MRS: Pushing multiroomSync output update for this device Dec 09 18:10:17 den-volumio volumio[2861]: info: MRS: Pushing multiroomSync output Dec 09 18:10:17 den-volumio volumio[2861]: info: CoreCommandRouter::volumioGetState Dec 09 18:10:17 den-volumio volumio[2861]: info: ControllerMpd::pushState Dec 09 18:10:17 den-volumio volumio[2861]: info: CoreCommandRouter::servicePushState Dec 09 18:10:17 den-volumio volumio[2861]: info: CorePlayQueue::getTrack 0 Dec 09 18:10:17 den-volumio volumio[2861]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":208,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"file?uid=1019108&eid=355738883&fmt=7&profile=raw&app_id=539451548&cid=1274695&etsp=1765329016&hmac=DVsXWBvTPblkNYxJkeCYr_Z4-PI","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=1019108&eid=355738883&fmt=7&profile=raw&app_id=539451548&cid=1274695&etsp=1765329016&hmac=DVsXWBvTPblkNYxJkeCYr_Z4-PI","trackType":"qobuz"} Dec 09 18:10:17 den-volumio volumio[2861]: verbose: CURRENT POSITION 0 Dec 09 18:10:17 den-volumio volumio[2861]: info: CoreStateMachine::syncState stateService play Dec 09 18:10:17 den-volumio volumio[2861]: info: CoreStateMachine::syncState currentStatus play Dec 09 18:10:17 den-volumio volumio[2861]: info: Received an update from plugin. extracting info from payload Dec 09 18:10:17 den-volumio volumio[2861]: info: CoreStateMachine::pushState Dec 09 18:10:17 den-volumio volumio[2861]: info: CoreCommandRouter::volumioPushState Dec 09 18:10:17 den-volumio volumio[2861]: info: MRS: Pushing multiroomSync output update for this device Dec 09 18:10:17 den-volumio volumio[2861]: info: MRS: Pushing multiroomSync output Dec 09 18:10:17 den-volumio volumio[2861]: info: CoreCommandRouter::volumioGetState Dec 09 18:10:17 den-volumio volumio[2861]: info: CoreStateMachine::pushState Dec 09 18:10:17 den-volumio volumio[2861]: info: CoreCommandRouter::volumioPushState Dec 09 18:10:17 den-volumio volumio[2861]: info: MRS: Pushing multiroomSync output update for this device Dec 09 18:10:17 den-volumio volumio[2861]: info: MRS: Pushing multiroomSync output Dec 09 18:10:17 den-volumio volumio[2861]: info: CoreCommandRouter::volumioGetState Dec 09 18:10:17 den-volumio volumio[2861]: info: ------------------------------ 67ms Dec 09 18:10:17 den-volumio volumio[2861]: info: ------------------------------ 66ms Dec 09 18:10:17 den-volumio volumio[2861]: info: sendMpdCommand playlistinfo took 53 milliseconds Dec 09 18:10:17 den-volumio volumio[2861]: verbose: ControllerMpd::parseTrackInfo Dec 09 18:10:17 den-volumio volumio[2861]: info: ControllerMpd::pushState Dec 09 18:10:17 den-volumio volumio[2861]: info: CoreCommandRouter::servicePushState Dec 09 18:10:17 den-volumio volumio[2861]: info: CorePlayQueue::getTrack 0 Dec 09 18:10:17 den-volumio volumio[2861]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":208,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"file?uid=1019108&eid=355738883&fmt=7&profile=raw&app_id=539451548&cid=1274695&etsp=1765329016&hmac=DVsXWBvTPblkNYxJkeCYr_Z4-PI","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=1019108&eid=355738883&fmt=7&profile=raw&app_id=539451548&cid=1274695&etsp=1765329016&hmac=DVsXWBvTPblkNYxJkeCYr_Z4-PI","trackType":"qobuz"} Dec 09 18:10:17 den-volumio volumio[2861]: verbose: CURRENT POSITION 0 Dec 09 18:10:17 den-volumio volumio[2861]: info: CoreStateMachine::syncState stateService play Dec 09 18:10:17 den-volumio volumio[2861]: info: CoreStateMachine::syncState currentStatus play Dec 09 18:10:17 den-volumio volumio[2861]: info: Received an update from plugin. extracting info from payload Dec 09 18:10:17 den-volumio volumio[2861]: info: CoreStateMachine::pushState Dec 09 18:10:17 den-volumio volumio[2861]: info: CoreCommandRouter::volumioPushState Dec 09 18:10:17 den-volumio volumio[2861]: info: MRS: Pushing multiroomSync output update for this device Dec 09 18:10:17 den-volumio volumio[2861]: info: MRS: Pushing multiroomSync output Dec 09 18:10:17 den-volumio volumio[2861]: info: CoreCommandRouter::volumioGetState Dec 09 18:10:17 den-volumio volumio[2861]: info: CoreStateMachine::pushState Dec 09 18:10:17 den-volumio volumio[2861]: info: CoreCommandRouter::volumioPushState Dec 09 18:10:17 den-volumio volumio[2861]: info: MRS: Pushing multiroomSync output update for this device Dec 09 18:10:17 den-volumio volumio[2861]: info: MRS: Pushing multiroomSync output Dec 09 18:10:17 den-volumio volumio[2861]: info: CoreCommandRouter::volumioGetState Dec 09 18:10:17 den-volumio volumio[2861]: info: ------------------------------ 88ms Dec 09 18:10:24 den-volumio wpa_supplicant[2721]: wlan0: WNM: Disassociation Imminent - Disassociation Timer 60 Dec 09 18:10:24 den-volumio wpa_supplicant[2721]: wlan0: WNM: Preferred List Available Dec 09 18:10:24 den-volumio wpa_supplicant[2721]: wlan0: Trying to associate with 08:9b:b9:30:c1:4c (SSID='Bumpercars2' freq=5745 MHz) Dec 09 18:10:24 den-volumio dhcpcd[810]: wlan0: carrier lost - roaming Dec 09 18:10:24 den-volumio kernel: ieee80211 phy0: brcmf_p2p_send_action_frame: Unknown Frame: category 0xa, action 0x8 Dec 09 18:10:24 den-volumio dhcpcd[810]: wlan0: changing route to 192.168.1.0/24 Dec 09 18:10:24 den-volumio dhcpcd[810]: wlan0: changing default route via 192.168.1.254 Dec 09 18:10:24 den-volumio wpa_supplicant[2721]: wlan0: Associated with 08:9b:b9:30:c1:4c Dec 09 18:10:24 den-volumio wpa_supplicant[2721]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Dec 09 18:10:24 den-volumio wpa_supplicant[2721]: wlan0: WPA: Key negotiation completed with 08:9b:b9:30:c1:4c [PTK=CCMP GTK=CCMP] Dec 09 18:10:24 den-volumio wpa_supplicant[2721]: wlan0: CTRL-EVENT-CONNECTED - Connection to 08:9b:b9:30:c1:4c completed [id=0 id_str=] Dec 09 18:10:24 den-volumio dhcpcd[810]: wlan0: carrier acquired Dec 09 18:10:24 den-volumio dhcpcd[810]: wlan0: IAID 32:2a:a7:bb Dec 09 18:10:25 den-volumio dhcpcd[810]: wlan0: soliciting a DHCP lease Dec 09 18:10:25 den-volumio dhcpcd[810]: wlan0: soliciting an IPv6 router Dec 09 18:10:29 den-volumio dhcpcd[810]: wlan0: offered 192.168.1.91 from 192.168.1.254 Dec 09 18:10:30 den-volumio dhcpcd[810]: wlan0: NAK: requested address not available from 192.168.1.254 Dec 09 18:10:30 den-volumio dhcpcd[810]: wlan0: message: requested address not available Dec 09 18:10:30 den-volumio avahi-daemon[2860]: Withdrawing address record for 192.168.1.112 on wlan0. Dec 09 18:10:30 den-volumio avahi-daemon[2860]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.112. Dec 09 18:10:30 den-volumio dhcpcd[810]: wlan0: deleting route to 192.168.1.0/24 Dec 09 18:10:30 den-volumio avahi-daemon[2860]: Interface wlan0.IPv4 no longer relevant for mDNS. Dec 09 18:10:30 den-volumio dhcpcd[810]: wlan0: deleting default route via 192.168.1.254 Dec 09 18:10:30 den-volumio volumio[2861]: info: Discovery: A device disappeared from network Dec 09 18:10:30 den-volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Dec 09 18:10:30 den-volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Dec 09 18:10:30 den-volumio systemd[1]: welcome.service: Deactivated successfully. Dec 09 18:10:30 den-volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 09 18:10:30 den-volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 09 18:10:30 den-volumio dhcpcd[810]: wlan0: soliciting a DHCP lease Dec 09 18:10:30 den-volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 09 18:10:30 den-volumio welcome[7021]: Resolved ip:[0] Dec 09 18:10:30 den-volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 09 18:10:30 den-volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Dec 09 18:10:30 den-volumio dhcpcd[810]: wlan0: offered 192.168.1.91 from 192.168.1.254 Dec 09 18:10:30 den-volumio dhcpcd[810]: wlan0: probing address 192.168.1.91/24 Dec 09 18:10:30 den-volumio volumio[2861]: info: Volumio Network Manager: Network status updated: 0 Dec 09 18:10:31 den-volumio ntpd[986]: IO: Deleting interface #5 wlan0, 192.168.1.112#123, interface stats: received=422, sent=429, dropped=0, active_time=1837 secs Dec 09 18:10:31 den-volumio ntpd[986]: PROTO: 23.95.35.34 unlink local addr 192.168.1.112 -> Dec 09 18:10:31 den-volumio ntpd[986]: PROTO: 216.229.0.49 unlink local addr 192.168.1.112 -> Dec 09 18:10:31 den-volumio ntpd[986]: PROTO: 45.33.53.84 unlink local addr 192.168.1.112 -> Dec 09 18:10:31 den-volumio ntpd[986]: PROTO: 83.147.242.172 unlink local addr 192.168.1.112 -> Dec 09 18:10:31 den-volumio ntpd[986]: PROTO: 23.131.160.7 unlink local addr 192.168.1.112 -> Dec 09 18:10:31 den-volumio ntpd[986]: PROTO: 23.186.168.131 unlink local addr 192.168.1.112 -> Dec 09 18:10:31 den-volumio ntpd[986]: PROTO: 172.235.60.8 unlink local addr 192.168.1.112 -> Dec 09 18:10:35 den-volumio dhcpcd[810]: wlan0: leased 192.168.1.91 for 86400 seconds Dec 09 18:10:35 den-volumio avahi-daemon[2860]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.91. Dec 09 18:10:35 den-volumio avahi-daemon[2860]: New relevant interface wlan0.IPv4 for mDNS. Dec 09 18:10:35 den-volumio avahi-daemon[2860]: Registering new address record for 192.168.1.91 on wlan0.IPv4. Dec 09 18:10:35 den-volumio dhcpcd[810]: wlan0: adding route to 192.168.1.0/24 Dec 09 18:10:35 den-volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Dec 09 18:10:35 den-volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Dec 09 18:10:35 den-volumio systemd[1]: welcome.service: Deactivated successfully. Dec 09 18:10:35 den-volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 09 18:10:35 den-volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 09 18:10:35 den-volumio dhcpcd[810]: wlan0: adding default route via 192.168.1.254 Dec 09 18:10:35 den-volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 09 18:10:35 den-volumio welcome[7055]: Resolved ip:[1] 192.168.1.91 Dec 09 18:10:35 den-volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 09 18:10:35 den-volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Dec 09 18:10:35 den-volumio volumio[2861]: info: MRS: Found cast device: SHIELD-Android-TV-1be6f52f6464c65a31e25c2bbf9be2c9 Dec 09 18:10:35 den-volumio volumio[2861]: compat.c: read() failed: Resource temporarily unavailable Dec 09 18:10:35 den-volumio volumio[2861]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 09 18:10:35 den-volumio volumio[2861]: Error: dns service error: unknown Dec 09 18:10:35 den-volumio volumio[2861]: at MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Dec 09 18:10:35 den-volumio volumio[2861]: errorCode: -65537 Dec 09 18:10:35 den-volumio volumio[2861]: } Dec 09 18:10:35 den-volumio volumio[2861]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 09 18:10:36 den-volumio sudo[7086]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-09 18:09' Dec 09 18:10:36 den-volumio sudo[7086]: 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"