-- Logs begin at Thu 2019-02-14 10:11:58 GMT, end at Tue 2025-12-30 23:42:32 GMT. -- Dec 30 23:41:00 volumio ntpd[819]: Soliciting pool server 89.234.64.77 Dec 30 23:41:00 volumio ntpd[819]: Soliciting pool server 162.159.200.123 Dec 30 23:41:01 volumio ntpd[819]: Soliciting pool server 2607:5dc0:1:c::7bfd:487f Dec 30 23:41:03 volumio ntpd[819]: receive: Unexpected origin timestamp 0xecfee18e.deee89a1 does not match aorg 0000000000.00000000 from server@52.17.231.73 xmt 0xecfee18f.f6a51828 Dec 30 23:41:03 volumio ntpd[819]: receive: Unexpected origin timestamp 0xecfee18e.def00e75 does not match aorg 0000000000.00000000 from server@88.81.100.130 xmt 0xecfee18f.f72f7f7d Dec 30 23:41:03 volumio ntpd[819]: receive: Unexpected origin timestamp 0xecfee18e.deeb6567 does not match aorg 0000000000.00000000 from server@162.159.200.123 xmt 0xecfee18f.f79c3a02 Dec 30 23:41:19 volumio volumio[1034]: verbose: New Socket.io Connection to 192.168.1.9 from 192.168.1.13 UA: Mozilla/5.0 (Linux; Android 12; SM-N975F Build/SP1A.210812.016; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.34 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Dec 30 23:41:19 volumio volumio[1034]: info: CoreCommandRouter::volumioGetState Dec 30 23:41:19 volumio volumio[1034]: info: CorePlayQueue::getTrack 0 Dec 30 23:41:19 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 30 23:41:19 volumio volumio[1034]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Dec 30 23:41:19 volumio volumio[1034]: info: Listing playlists Dec 30 23:41:24 volumio go-librespot[1226]: time="2025-12-30T23:41:24Z" level=trace msg="sent dealer ping" Dec 30 23:41:24 volumio go-librespot[1226]: time="2025-12-30T23:41:24Z" level=trace msg="received dealer pong" Dec 30 23:41:29 volumio volumio[1034]: info: CoreCommandRouter::volumioPlay Dec 30 23:41:29 volumio volumio[1034]: info: CoreStateMachine::play index undefined Dec 30 23:41:29 volumio volumio[1034]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 30 23:41:29 volumio volumio[1034]: info: CorePlayQueue::getTrack 0 Dec 30 23:41:41 volumio volumio[1034]: verbose: New Socket.io Connection to 192.168.1.9:3000 from 192.168.1.13 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Dec 30 23:41:41 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 30 23:41:41 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 30 23:41:41 volumio volumio[1034]: info: Discovery: Getting this device information Dec 30 23:41:41 volumio volumio[1034]: info: CoreCommandRouter::volumioGetState Dec 30 23:41:41 volumio volumio[1034]: info: CorePlayQueue::getTrack 0 Dec 30 23:41:41 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 30 23:41:41 volumio volumio[1034]: verbose: New Socket.io Connection to 192.168.1.9:3000 from 192.168.1.13 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Dec 30 23:41:41 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 30 23:41:41 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 30 23:41:41 volumio volumio[1034]: info: Discovery: Getting this device information Dec 30 23:41:41 volumio volumio[1034]: info: CoreCommandRouter::volumioGetState Dec 30 23:41:41 volumio volumio[1034]: info: CorePlayQueue::getTrack 0 Dec 30 23:41:41 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 30 23:41:41 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 30 23:41:41 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 30 23:41:41 volumio volumio[1034]: info: Discovery: Getting this device information Dec 30 23:41:41 volumio volumio[1034]: info: CoreCommandRouter::volumioGetState Dec 30 23:41:41 volumio volumio[1034]: info: CorePlayQueue::getTrack 0 Dec 30 23:41:41 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 30 23:41:41 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 30 23:41:41 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Dec 30 23:41:41 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Dec 30 23:41:41 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Dec 30 23:41:41 volumio sudo[1742]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Dec 30 23:41:41 volumio sudo[1742]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 23:41:41 volumio sudo[1748]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 30 23:41:41 volumio sudo[1742]: pam_unix(sudo:session): session closed for user root Dec 30 23:41:41 volumio sudo[1748]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 23:41:41 volumio sudo[1748]: pam_unix(sudo:session): session closed for user root Dec 30 23:41:41 volumio sudo[1756]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 30 23:41:41 volumio sudo[1756]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 23:41:41 volumio sudo[1756]: pam_unix(sudo:session): session closed for user root Dec 30 23:41:41 volumio sudo[1762]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 30 23:41:41 volumio sudo[1762]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 23:41:41 volumio sudo[1762]: pam_unix(sudo:session): session closed for user root Dec 30 23:41:41 volumio sudo[1768]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 30 23:41:41 volumio sudo[1768]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 23:41:41 volumio sudo[1768]: pam_unix(sudo:session): session closed for user root Dec 30 23:41:41 volumio sudo[1771]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 30 23:41:41 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 30 23:41:41 volumio sudo[1771]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 23:41:41 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 30 23:41:41 volumio volumio[1034]: info: Discovery: Getting this device information Dec 30 23:41:41 volumio volumio[1034]: info: CoreCommandRouter::volumioGetState Dec 30 23:41:41 volumio volumio[1034]: info: CorePlayQueue::getTrack 0 Dec 30 23:41:41 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 30 23:41:41 volumio sudo[1771]: pam_unix(sudo:session): session closed for user root Dec 30 23:41:41 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 30 23:41:41 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 30 23:41:41 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 30 23:41:41 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 30 23:41:41 volumio volumio[1034]: info: Discovery: Getting this device information Dec 30 23:41:41 volumio volumio[1034]: info: CoreCommandRouter::volumioGetState Dec 30 23:41:41 volumio volumio[1034]: info: CorePlayQueue::getTrack 0 Dec 30 23:41:41 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 30 23:41:41 volumio volumio[1034]: verbose: New Socket.io Connection to 192.168.1.9:3000 from 192.168.1.13 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Dec 30 23:41:41 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 30 23:41:41 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 30 23:41:41 volumio volumio[1034]: info: Discovery: Getting this device information Dec 30 23:41:41 volumio volumio[1034]: info: CoreCommandRouter::volumioGetState Dec 30 23:41:41 volumio volumio[1034]: info: CorePlayQueue::getTrack 0 Dec 30 23:41:41 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 30 23:41:41 volumio volumio[1034]: info: CoreCommandRouter::volumioGetState Dec 30 23:41:41 volumio volumio[1034]: info: CorePlayQueue::getTrack 0 Dec 30 23:41:41 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 30 23:41:41 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 30 23:41:41 volumio volumio[1034]: verbose: New Socket.io Connection to 192.168.1.9:3000 from 192.168.1.13 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Dec 30 23:41:41 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 30 23:41:41 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 30 23:41:43 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 30 23:41:43 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 30 23:41:44 volumio sudo[1777]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 30 23:41:44 volumio sudo[1777]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 23:41:44 volumio sudo[1777]: pam_unix(sudo:session): session closed for user root Dec 30 23:41:44 volumio sudo[1780]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 30 23:41:44 volumio sudo[1780]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 23:41:44 volumio sudo[1780]: pam_unix(sudo:session): session closed for user root Dec 30 23:41:44 volumio volumio[1034]: verbose: New Socket.io Connection to 192.168.1.9 from 192.168.1.13 UA: Mozilla/5.0 (Linux; Android 12; SM-N975F Build/SP1A.210812.016; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.34 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Dec 30 23:41:44 volumio volumio[1034]: info: CoreCommandRouter::volumioGetVisibleSources Dec 30 23:41:44 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 30 23:41:44 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 30 23:41:44 volumio volumio[1034]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Dec 30 23:41:44 volumio volumio[1034]: info: CoreCommandRouter::volumioGetState Dec 30 23:41:44 volumio volumio[1034]: info: CorePlayQueue::getTrack 0 Dec 30 23:41:44 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 30 23:41:44 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 30 23:41:44 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 30 23:41:44 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 30 23:41:44 volumio sudo[1783]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 30 23:41:44 volumio sudo[1783]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 23:41:44 volumio sudo[1783]: pam_unix(sudo:session): session closed for user root Dec 30 23:41:44 volumio sudo[1786]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 30 23:41:44 volumio sudo[1786]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 23:41:44 volumio sudo[1786]: pam_unix(sudo:session): session closed for user root Dec 30 23:41:44 volumio volumio[1034]: verbose: New Socket.io Connection to 192.168.1.9 from 192.168.1.13 UA: Mozilla/5.0 (Linux; Android 12; SM-N975F Build/SP1A.210812.016; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.34 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Dec 30 23:41:44 volumio volumio[1034]: info: CoreCommandRouter::volumioGetState Dec 30 23:41:44 volumio volumio[1034]: info: CorePlayQueue::getTrack 0 Dec 30 23:41:45 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 30 23:41:45 volumio volumio[1034]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Dec 30 23:41:45 volumio volumio[1034]: info: Listing playlists Dec 30 23:41:45 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 30 23:41:45 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 30 23:41:45 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 30 23:41:45 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 30 23:41:48 volumio volumio[1034]: info: CoreCommandRouter::volumioGetVisibleSources Dec 30 23:41:48 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 30 23:41:48 volumio volumio[1034]: info: CoreCommandRouter::volumioGetQueue Dec 30 23:41:48 volumio volumio[1034]: info: CoreStateMachine::getQueue Dec 30 23:41:48 volumio volumio[1034]: info: CorePlayQueue::getQueue Dec 30 23:41:49 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Dec 30 23:41:49 volumio volumio[1034]: info: Preload queue cleared Dec 30 23:41:50 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Dec 30 23:41:50 volumio volumio[1034]: info: Preload queue cleared Dec 30 23:41:52 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Dec 30 23:41:52 volumio volumio[1034]: info: Preload queue cleared Dec 30 23:41:53 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Dec 30 23:41:53 volumio volumio[1034]: info: Preload queue cleared Dec 30 23:41:53 volumio volumio[1034]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$C Dec 30 23:41:53 volumio volumio[1034]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$D Dec 30 23:41:53 volumio volumio[1034]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$E Dec 30 23:41:53 volumio volumio[1034]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$13 Dec 30 23:41:53 volumio volumio[1034]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$F Dec 30 23:41:53 volumio volumio[1034]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$10 Dec 30 23:41:53 volumio volumio[1034]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$11 Dec 30 23:41:53 volumio volumio[1034]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$12 Dec 30 23:41:53 volumio volumio[1034]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$0 Dec 30 23:41:53 volumio volumio[1034]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$1 Dec 30 23:41:53 volumio volumio[1034]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$2 Dec 30 23:41:53 volumio volumio[1034]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$3 Dec 30 23:41:53 volumio volumio[1034]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$4 Dec 30 23:41:53 volumio volumio[1034]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$5 Dec 30 23:41:53 volumio volumio[1034]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$6 Dec 30 23:41:53 volumio volumio[1034]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$7 Dec 30 23:41:53 volumio volumio[1034]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$8 Dec 30 23:41:53 volumio volumio[1034]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$9 Dec 30 23:41:53 volumio volumio[1034]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$A Dec 30 23:41:53 volumio volumio[1034]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$B Dec 30 23:41:53 volumio volumio[1034]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$C in service upnp_browser Dec 30 23:41:53 volumio volumio[1034]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$D in service upnp_browser Dec 30 23:41:54 volumio go-librespot[1226]: time="2025-12-30T23:41:54Z" level=trace msg="sent dealer ping" Dec 30 23:41:54 volumio volumio[1034]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$E in service upnp_browser Dec 30 23:41:54 volumio go-librespot[1226]: time="2025-12-30T23:41:54Z" level=trace msg="received dealer pong" Dec 30 23:41:54 volumio volumio[1034]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$13 in service upnp_browser Dec 30 23:41:54 volumio volumio[1034]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$F in service upnp_browser Dec 30 23:41:54 volumio volumio[1034]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$10 in service upnp_browser Dec 30 23:41:54 volumio volumio[1034]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$11 in service upnp_browser Dec 30 23:41:54 volumio volumio[1034]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$12 in service upnp_browser Dec 30 23:41:54 volumio volumio[1034]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$0 in service upnp_browser Dec 30 23:41:54 volumio volumio[1034]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$1 in service upnp_browser Dec 30 23:41:54 volumio volumio[1034]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$2 in service upnp_browser Dec 30 23:41:54 volumio volumio[1034]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$3 in service upnp_browser Dec 30 23:41:54 volumio volumio[1034]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$4 in service upnp_browser Dec 30 23:41:54 volumio volumio[1034]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$5 in service upnp_browser Dec 30 23:41:54 volumio volumio[1034]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$6 in service upnp_browser Dec 30 23:41:54 volumio volumio[1034]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$7 in service upnp_browser Dec 30 23:41:54 volumio volumio[1034]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$8 in service upnp_browser Dec 30 23:41:54 volumio volumio[1034]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$9 in service upnp_browser Dec 30 23:41:54 volumio volumio[1034]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$A in service upnp_browser Dec 30 23:41:54 volumio volumio[1034]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$B in service upnp_browser Dec 30 23:41:55 volumio volumio[1034]: info: Preload queue cleared Dec 30 23:41:55 volumio volumio[1034]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 30 23:41:55 volumio volumio[1034]: info: CoreStateMachine::ClearQueue Dec 30 23:41:55 volumio volumio[1034]: info: CoreStateMachine::stop Dec 30 23:41:55 volumio volumio[1034]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 30 23:41:55 volumio volumio[1034]: info: CorePlayQueue::clearPlayQueue Dec 30 23:41:55 volumio volumio[1034]: info: CorePlayQueue::saveQueue Dec 30 23:41:55 volumio volumio[1034]: info: CoreCommandRouter::volumioPushQueue Dec 30 23:41:55 volumio volumio[1034]: info: CoreStateMachine::addQueueItems Dec 30 23:41:55 volumio volumio[1034]: info: CorePlayQueue::addQueueItems Dec 30 23:41:55 volumio volumio[1034]: info: Preload queue cleared Dec 30 23:41:55 volumio volumio[1034]: info: Adding Item to queue: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$C Dec 30 23:41:55 volumio volumio[1034]: info: Using cached record of: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$C Dec 30 23:41:55 volumio volumio[1034]: info: Adding Item to queue: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$D Dec 30 23:41:55 volumio volumio[1034]: info: Using cached record of: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$D Dec 30 23:41:55 volumio volumio[1034]: info: CoreCommandRouter::volumioPushQueue Dec 30 23:41:55 volumio volumio[1034]: info: CorePlayQueue::saveQueue Dec 30 23:41:55 volumio volumio[1034]: info: CoreStateMachine::updateTrackBlock Dec 30 23:41:55 volumio volumio[1034]: info: CorePlayQueue::getTrackBlock Dec 30 23:41:55 volumio volumio[1034]: info: CoreCommandRouter::volumioPlay Dec 30 23:41:55 volumio volumio[1034]: info: CoreStateMachine::play index 1 Dec 30 23:41:55 volumio volumio[1034]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 30 23:41:55 volumio volumio[1034]: info: CoreStateMachine::addQueueItems Dec 30 23:41:55 volumio volumio[1034]: info: CorePlayQueue::addQueueItems Dec 30 23:41:55 volumio volumio[1034]: info: Preload queue cleared Dec 30 23:41:55 volumio volumio[1034]: info: Adding Item to queue: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$E Dec 30 23:41:55 volumio volumio[1034]: info: Using cached record of: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$E Dec 30 23:41:55 volumio volumio[1034]: info: Adding Item to queue: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$13 Dec 30 23:41:55 volumio volumio[1034]: info: Using cached record of: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$13 Dec 30 23:41:55 volumio volumio[1034]: info: Adding Item to queue: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$F Dec 30 23:41:55 volumio volumio[1034]: info: Using cached record of: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$F Dec 30 23:41:55 volumio volumio[1034]: info: Adding Item to queue: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$10 Dec 30 23:41:55 volumio volumio[1034]: info: Using cached record of: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$10 Dec 30 23:41:55 volumio volumio[1034]: info: Adding Item to queue: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$11 Dec 30 23:41:55 volumio volumio[1034]: info: Using cached record of: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$11 Dec 30 23:41:55 volumio volumio[1034]: info: Adding Item to queue: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$12 Dec 30 23:41:55 volumio volumio[1034]: info: Using cached record of: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$12 Dec 30 23:41:55 volumio volumio[1034]: info: Adding Item to queue: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$0 Dec 30 23:41:55 volumio volumio[1034]: info: Using cached record of: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$0 Dec 30 23:41:55 volumio volumio[1034]: info: Adding Item to queue: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$1 Dec 30 23:41:55 volumio volumio[1034]: info: Using cached record of: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$1 Dec 30 23:41:55 volumio volumio[1034]: info: Adding Item to queue: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$2 Dec 30 23:41:55 volumio volumio[1034]: info: Using cached record of: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$2 Dec 30 23:41:55 volumio volumio[1034]: info: Adding Item to queue: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$3 Dec 30 23:41:55 volumio volumio[1034]: info: Using cached record of: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$3 Dec 30 23:41:55 volumio volumio[1034]: info: Adding Item to queue: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$4 Dec 30 23:41:55 volumio volumio[1034]: info: Using cached record of: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$4 Dec 30 23:41:55 volumio volumio[1034]: info: Adding Item to queue: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$5 Dec 30 23:41:55 volumio volumio[1034]: info: Using cached record of: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$5 Dec 30 23:41:55 volumio volumio[1034]: info: Adding Item to queue: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$6 Dec 30 23:41:55 volumio volumio[1034]: info: Using cached record of: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$6 Dec 30 23:41:55 volumio volumio[1034]: info: Adding Item to queue: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$7 Dec 30 23:41:55 volumio volumio[1034]: info: Using cached record of: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$7 Dec 30 23:41:55 volumio volumio[1034]: info: Adding Item to queue: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$8 Dec 30 23:41:55 volumio volumio[1034]: info: Using cached record of: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$8 Dec 30 23:41:55 volumio volumio[1034]: info: Adding Item to queue: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$9 Dec 30 23:41:55 volumio volumio[1034]: info: Using cached record of: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$9 Dec 30 23:41:55 volumio volumio[1034]: info: Adding Item to queue: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$A Dec 30 23:41:55 volumio volumio[1034]: info: Using cached record of: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$A Dec 30 23:41:55 volumio volumio[1034]: info: Adding Item to queue: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$B Dec 30 23:41:55 volumio volumio[1034]: info: Using cached record of: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$B Dec 30 23:41:55 volumio volumio[1034]: info: CoreStateMachine::stop Dec 30 23:41:55 volumio volumio[1034]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 30 23:41:55 volumio volumio[1034]: info: CoreCommandRouter::volumioPushQueue Dec 30 23:41:55 volumio volumio[1034]: info: CorePlayQueue::saveQueue Dec 30 23:41:55 volumio volumio[1034]: info: CoreStateMachine::play index undefined Dec 30 23:41:55 volumio volumio[1034]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 30 23:41:55 volumio volumio[1034]: info: CoreStateMachine::updateTrackBlock Dec 30 23:41:55 volumio volumio[1034]: info: CorePlayQueue::getTrackBlock Dec 30 23:41:55 volumio volumio[1034]: info: CorePlayQueue::getTrack 1 Dec 30 23:41:55 volumio volumio[1034]: info: CoreStateMachine::startPlaybackTimer Dec 30 23:41:55 volumio volumio[1034]: info: CorePlayQueue::getTrack 1 Dec 30 23:41:55 volumio volumio[1034]: info: [1767138115170] ControllerUPNPBrowser::clearAddPlayTrack Dec 30 23:41:55 volumio volumio[1034]: verbose: ControllerMpd::sendMpdCommand stop Dec 30 23:41:55 volumio volumio[1034]: info: sendMpdCommand stop took 8 milliseconds Dec 30 23:41:55 volumio volumio[1034]: verbose: ControllerMpd::sendMpdCommand clear Dec 30 23:41:55 volumio volumio[1034]: info: Dec 30 23:41:55 volumio volumio[1034]: ---------------------------- MPD announces system playlist update Dec 30 23:41:55 volumio volumio[1034]: info: Ignoring MPD Status Update Dec 30 23:41:55 volumio volumio[1034]: info: sendMpdCommand clear took 2 milliseconds Dec 30 23:41:55 volumio volumio[1034]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.9:8200/MediaItems/50.wav" Dec 30 23:41:55 volumio volumio[1034]: error: updateQueue error: null Dec 30 23:41:55 volumio volumio[1034]: info: ------------------------------ 3ms Dec 30 23:41:55 volumio volumio[1034]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.9:8200/MediaItems/50.wav" Dec 30 23:41:55 volumio volumio[1034]: info: Dec 30 23:41:55 volumio volumio[1034]: ---------------------------- MPD announces system playlist update Dec 30 23:41:55 volumio volumio[1034]: info: Ignoring MPD Status Update Dec 30 23:41:55 volumio volumio[1034]: info: sendMpdCommand add "http://192.168.1.9:8200/MediaItems/50.wav" took 2 milliseconds Dec 30 23:41:55 volumio volumio[1034]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 30 23:41:55 volumio volumio[1034]: verbose: ControllerMpd::sendMpdCommand play Dec 30 23:41:55 volumio volumio[1034]: info: ------------------------------ 3ms Dec 30 23:41:55 volumio volumio[1034]: info: sendMpdCommand play took 1 milliseconds Dec 30 23:41:55 volumio volumio[1034]: info: Dec 30 23:41:55 volumio volumio[1034]: ---------------------------- MPD announces state update: player Dec 30 23:41:55 volumio volumio[1034]: info: ControllerMpd::getState Dec 30 23:41:55 volumio volumio[1034]: verbose: ControllerMpd::sendMpdCommand status Dec 30 23:41:55 volumio volumio[1034]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 30 23:41:55 volumio volumio[1034]: info: sendMpdCommand status took 6 milliseconds Dec 30 23:41:55 volumio volumio[1034]: verbose: ControllerMpd::parseState Dec 30 23:41:55 volumio volumio[1034]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 30 23:41:55 volumio volumio[1034]: info: sendMpdCommand clearerror took 2 milliseconds Dec 30 23:41:55 volumio volumio[1034]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 30 23:41:55 volumio volumio[1034]: verbose: ControllerMpd::parseTrackInfo Dec 30 23:41:55 volumio volumio[1034]: info: ControllerMpd::pushState Dec 30 23:41:55 volumio volumio[1034]: info: CoreCommandRouter::servicePushState Dec 30 23:41:55 volumio volumio[1034]: info: CoreStateMachine::pushState Dec 30 23:41:55 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 23:41:55 volumio volumio[1034]: info: CoreCommandRouter::volumioPushState Dec 30 23:41:55 volumio volumio[1034]: info: MRS: Pushing multiroomSync output update for this device Dec 30 23:41:55 volumio volumio[1034]: info: MRS: Pushing multiroomSync output Dec 30 23:41:55 volumio volumio[1034]: info: CoreCommandRouter::volumioGetState Dec 30 23:41:55 volumio volumio[1034]: info: CorePlayQueue::getTrack 1 Dec 30 23:41:55 volumio volumio[1034]: 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":"50.wav","artist":null,"album":null,"uri":"http://192.168.1.9:8200/MediaItems/50.wav","trackType":"wav"} Dec 30 23:41:55 volumio volumio[1034]: verbose: CURRENT POSITION 1 Dec 30 23:41:55 volumio volumio[1034]: info: CoreStateMachine::syncState stateService stop Dec 30 23:41:55 volumio volumio[1034]: info: CoreStateMachine::syncState currentStatus stop Dec 30 23:41:55 volumio volumio[1034]: info: CoreStateMachine::pushState Dec 30 23:41:55 volumio volumio[1034]: info: CoreCommandRouter::volumioPushState Dec 30 23:41:55 volumio volumio[1034]: info: MRS: Pushing multiroomSync output update for this device Dec 30 23:41:55 volumio volumio[1034]: info: MRS: Pushing multiroomSync output Dec 30 23:41:55 volumio volumio[1034]: info: CoreCommandRouter::volumioGetState Dec 30 23:41:55 volumio volumio[1034]: info: No code Dec 30 23:41:55 volumio volumio[1034]: info: CoreStateMachine::pushState Dec 30 23:41:55 volumio volumio[1034]: info: CoreCommandRouter::volumioPushState Dec 30 23:41:55 volumio volumio[1034]: info: MRS: Pushing multiroomSync output update for this device Dec 30 23:41:55 volumio volumio[1034]: info: MRS: Pushing multiroomSync output Dec 30 23:41:55 volumio volumio[1034]: info: CoreCommandRouter::volumioGetState Dec 30 23:41:55 volumio volumio[1034]: info: ------------------------------ 54ms Dec 30 23:41:55 volumio volumio[1034]: SPOTIFY: RECEIVED VOLUMIO VOLUME 94 Dec 30 23:41:55 volumio volumio[1034]: SPOTIFY: RECEIVED VOLUMIO VOLUME 94 Dec 30 23:41:55 volumio volumio[1034]: SPOTIFY: RECEIVED VOLUMIO VOLUME 94 Dec 30 23:41:55 volumio volumio[1034]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.i1cx8kbcLQgPykzSpG9njOjYcf93.439e1a71635533bec2decd71379aa5d4.state.status' Dec 30 23:41:55 volumio volumio[1034]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.i1cx8kbcLQgPykzSpG9njOjYcf93.439e1a71635533bec2decd71379aa5d4.state.status' Dec 30 23:41:56 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 30 23:41:56 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 30 23:41:56 volumio volumio[1034]: info: Discovery: Getting this device information Dec 30 23:41:56 volumio volumio[1034]: info: CoreCommandRouter::volumioGetState Dec 30 23:41:56 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 30 23:41:56 volumio volumio[1034]: verbose: New Socket.io Connection to 192.168.1.9:3000 from 192.168.1.13 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 10 Dec 30 23:41:56 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 30 23:41:56 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 30 23:41:57 volumio volumio[1034]: info: Preload queue cleared Dec 30 23:41:57 volumio volumio[1034]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 30 23:41:57 volumio volumio[1034]: info: CoreStateMachine::ClearQueue Dec 30 23:41:57 volumio volumio[1034]: info: CoreStateMachine::stop Dec 30 23:41:57 volumio volumio[1034]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 30 23:41:57 volumio volumio[1034]: info: CorePlayQueue::clearPlayQueue Dec 30 23:41:57 volumio volumio[1034]: info: CorePlayQueue::saveQueue Dec 30 23:41:57 volumio volumio[1034]: info: CoreCommandRouter::volumioPushQueue Dec 30 23:41:57 volumio volumio[1034]: info: CoreStateMachine::addQueueItems Dec 30 23:41:57 volumio volumio[1034]: info: CorePlayQueue::addQueueItems Dec 30 23:41:57 volumio volumio[1034]: info: Preload queue cleared Dec 30 23:41:57 volumio volumio[1034]: info: Adding Item to queue: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$C Dec 30 23:41:57 volumio volumio[1034]: info: Using cached record of: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$C Dec 30 23:41:57 volumio volumio[1034]: info: CoreCommandRouter::volumioPushQueue Dec 30 23:41:57 volumio volumio[1034]: info: CorePlayQueue::saveQueue Dec 30 23:41:57 volumio volumio[1034]: info: CoreStateMachine::updateTrackBlock Dec 30 23:41:57 volumio volumio[1034]: info: CorePlayQueue::getTrackBlock Dec 30 23:41:57 volumio volumio[1034]: info: CoreCommandRouter::volumioPlay Dec 30 23:41:57 volumio volumio[1034]: info: CoreStateMachine::play index 0 Dec 30 23:41:57 volumio volumio[1034]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 30 23:41:57 volumio volumio[1034]: info: CoreStateMachine::addQueueItems Dec 30 23:41:57 volumio volumio[1034]: info: CorePlayQueue::addQueueItems Dec 30 23:41:57 volumio volumio[1034]: info: Preload queue cleared Dec 30 23:41:57 volumio volumio[1034]: info: Adding Item to queue: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$D Dec 30 23:41:57 volumio volumio[1034]: info: Using cached record of: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$D Dec 30 23:41:57 volumio volumio[1034]: info: Adding Item to queue: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$E Dec 30 23:41:57 volumio volumio[1034]: info: Using cached record of: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$E Dec 30 23:41:57 volumio volumio[1034]: info: Adding Item to queue: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$13 Dec 30 23:41:57 volumio volumio[1034]: info: Using cached record of: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$13 Dec 30 23:41:57 volumio volumio[1034]: info: Adding Item to queue: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$F Dec 30 23:41:57 volumio volumio[1034]: info: Using cached record of: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$F Dec 30 23:41:57 volumio volumio[1034]: info: Adding Item to queue: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$10 Dec 30 23:41:57 volumio volumio[1034]: info: Using cached record of: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$10 Dec 30 23:41:57 volumio volumio[1034]: info: Adding Item to queue: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$11 Dec 30 23:41:57 volumio volumio[1034]: info: Using cached record of: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$11 Dec 30 23:41:57 volumio volumio[1034]: info: Adding Item to queue: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$12 Dec 30 23:41:57 volumio volumio[1034]: info: Using cached record of: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$12 Dec 30 23:41:57 volumio volumio[1034]: info: Adding Item to queue: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$0 Dec 30 23:41:57 volumio volumio[1034]: info: Using cached record of: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$0 Dec 30 23:41:57 volumio volumio[1034]: info: Adding Item to queue: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$1 Dec 30 23:41:57 volumio volumio[1034]: info: Using cached record of: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$1 Dec 30 23:41:57 volumio volumio[1034]: info: Adding Item to queue: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$2 Dec 30 23:41:57 volumio volumio[1034]: info: Using cached record of: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$2 Dec 30 23:41:57 volumio volumio[1034]: info: Adding Item to queue: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$3 Dec 30 23:41:57 volumio volumio[1034]: info: Using cached record of: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$3 Dec 30 23:41:57 volumio volumio[1034]: info: Adding Item to queue: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$4 Dec 30 23:41:57 volumio volumio[1034]: info: Using cached record of: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$4 Dec 30 23:41:57 volumio volumio[1034]: info: Adding Item to queue: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$5 Dec 30 23:41:57 volumio volumio[1034]: info: Using cached record of: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$5 Dec 30 23:41:57 volumio volumio[1034]: info: Adding Item to queue: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$6 Dec 30 23:41:57 volumio volumio[1034]: info: Using cached record of: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$6 Dec 30 23:41:57 volumio volumio[1034]: info: Adding Item to queue: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$7 Dec 30 23:41:57 volumio volumio[1034]: info: Using cached record of: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$7 Dec 30 23:41:57 volumio volumio[1034]: info: Adding Item to queue: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$8 Dec 30 23:41:57 volumio volumio[1034]: info: Using cached record of: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$8 Dec 30 23:41:57 volumio volumio[1034]: info: Adding Item to queue: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$9 Dec 30 23:41:57 volumio volumio[1034]: info: Using cached record of: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$9 Dec 30 23:41:57 volumio volumio[1034]: info: Adding Item to queue: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$A Dec 30 23:41:57 volumio volumio[1034]: info: Using cached record of: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$A Dec 30 23:41:57 volumio volumio[1034]: info: Adding Item to queue: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$B Dec 30 23:41:57 volumio volumio[1034]: info: Using cached record of: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$B Dec 30 23:41:57 volumio volumio[1034]: info: CoreStateMachine::stop Dec 30 23:41:57 volumio volumio[1034]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 30 23:41:57 volumio volumio[1034]: info: CoreCommandRouter::volumioPushQueue Dec 30 23:41:57 volumio volumio[1034]: info: CorePlayQueue::saveQueue Dec 30 23:41:57 volumio volumio[1034]: info: CoreStateMachine::play index undefined Dec 30 23:41:57 volumio volumio[1034]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 30 23:41:57 volumio volumio[1034]: info: CoreStateMachine::updateTrackBlock Dec 30 23:41:57 volumio volumio[1034]: info: CorePlayQueue::getTrackBlock Dec 30 23:41:57 volumio volumio[1034]: info: CorePlayQueue::getTrack 0 Dec 30 23:41:57 volumio volumio[1034]: info: CoreStateMachine::startPlaybackTimer Dec 30 23:41:57 volumio volumio[1034]: info: CorePlayQueue::getTrack 0 Dec 30 23:41:57 volumio volumio[1034]: info: [1767138117724] ControllerUPNPBrowser::clearAddPlayTrack Dec 30 23:41:57 volumio volumio[1034]: verbose: ControllerMpd::sendMpdCommand stop Dec 30 23:41:57 volumio volumio[1034]: info: sendMpdCommand stop took 5 milliseconds Dec 30 23:41:57 volumio volumio[1034]: verbose: ControllerMpd::sendMpdCommand clear Dec 30 23:41:57 volumio volumio[1034]: info: Dec 30 23:41:57 volumio volumio[1034]: ---------------------------- MPD announces system playlist update Dec 30 23:41:57 volumio volumio[1034]: info: Ignoring MPD Status Update Dec 30 23:41:57 volumio volumio[1034]: info: sendMpdCommand clear took 1 milliseconds Dec 30 23:41:57 volumio volumio[1034]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.9:8200/MediaItems/46.wav" Dec 30 23:41:57 volumio volumio[1034]: error: updateQueue error: null Dec 30 23:41:57 volumio volumio[1034]: info: ------------------------------ 2ms Dec 30 23:41:57 volumio volumio[1034]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.9:8200/MediaItems/46.wav" Dec 30 23:41:57 volumio volumio[1034]: info: Dec 30 23:41:57 volumio volumio[1034]: ---------------------------- MPD announces system playlist update Dec 30 23:41:57 volumio volumio[1034]: info: Ignoring MPD Status Update Dec 30 23:41:57 volumio volumio[1034]: info: sendMpdCommand add "http://192.168.1.9:8200/MediaItems/46.wav" took 1 milliseconds Dec 30 23:41:57 volumio volumio[1034]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 30 23:41:57 volumio volumio[1034]: verbose: ControllerMpd::sendMpdCommand play Dec 30 23:41:57 volumio volumio[1034]: info: ------------------------------ 2ms Dec 30 23:41:57 volumio volumio[1034]: info: sendMpdCommand play took 2 milliseconds Dec 30 23:41:57 volumio volumio[1034]: info: Dec 30 23:41:57 volumio volumio[1034]: ---------------------------- MPD announces state update: player Dec 30 23:41:57 volumio volumio[1034]: info: ControllerMpd::getState Dec 30 23:41:57 volumio volumio[1034]: verbose: ControllerMpd::sendMpdCommand status Dec 30 23:41:57 volumio volumio[1034]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 30 23:41:57 volumio volumio[1034]: info: sendMpdCommand status took 5 milliseconds Dec 30 23:41:57 volumio volumio[1034]: verbose: ControllerMpd::parseState Dec 30 23:41:57 volumio volumio[1034]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 30 23:41:57 volumio volumio[1034]: info: sendMpdCommand clearerror took 2 milliseconds Dec 30 23:41:57 volumio volumio[1034]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 30 23:41:57 volumio volumio[1034]: verbose: ControllerMpd::parseTrackInfo Dec 30 23:41:57 volumio volumio[1034]: info: ControllerMpd::pushState Dec 30 23:41:57 volumio volumio[1034]: info: CoreCommandRouter::servicePushState Dec 30 23:41:57 volumio volumio[1034]: info: CoreStateMachine::pushState Dec 30 23:41:57 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 23:41:57 volumio volumio[1034]: info: CoreCommandRouter::volumioPushState Dec 30 23:41:57 volumio volumio[1034]: info: MRS: Pushing multiroomSync output update for this device Dec 30 23:41:57 volumio volumio[1034]: info: MRS: Pushing multiroomSync output Dec 30 23:41:57 volumio volumio[1034]: info: CoreCommandRouter::volumioGetState Dec 30 23:41:57 volumio volumio[1034]: info: CorePlayQueue::getTrack 0 Dec 30 23:41:57 volumio volumio[1034]: 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":"46.wav","artist":null,"album":null,"uri":"http://192.168.1.9:8200/MediaItems/46.wav","trackType":"wav"} Dec 30 23:41:57 volumio volumio[1034]: verbose: CURRENT POSITION 0 Dec 30 23:41:57 volumio volumio[1034]: info: CoreStateMachine::syncState stateService stop Dec 30 23:41:57 volumio volumio[1034]: info: CoreStateMachine::syncState currentStatus stop Dec 30 23:41:57 volumio volumio[1034]: info: CoreStateMachine::pushState Dec 30 23:41:57 volumio volumio[1034]: info: CoreCommandRouter::volumioPushState Dec 30 23:41:57 volumio volumio[1034]: info: MRS: Pushing multiroomSync output update for this device Dec 30 23:41:57 volumio volumio[1034]: info: MRS: Pushing multiroomSync output Dec 30 23:41:57 volumio volumio[1034]: info: CoreCommandRouter::volumioGetState Dec 30 23:41:57 volumio volumio[1034]: info: No code Dec 30 23:41:57 volumio volumio[1034]: info: CoreStateMachine::pushState Dec 30 23:41:57 volumio volumio[1034]: info: CoreCommandRouter::volumioPushState Dec 30 23:41:57 volumio volumio[1034]: info: MRS: Pushing multiroomSync output update for this device Dec 30 23:41:57 volumio volumio[1034]: info: MRS: Pushing multiroomSync output Dec 30 23:41:57 volumio volumio[1034]: info: CoreCommandRouter::volumioGetState Dec 30 23:41:57 volumio volumio[1034]: info: ------------------------------ 47ms Dec 30 23:41:57 volumio volumio[1034]: SPOTIFY: RECEIVED VOLUMIO VOLUME 94 Dec 30 23:41:57 volumio volumio[1034]: SPOTIFY: RECEIVED VOLUMIO VOLUME 94 Dec 30 23:41:57 volumio volumio[1034]: SPOTIFY: RECEIVED VOLUMIO VOLUME 94 Dec 30 23:41:57 volumio volumio[1034]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.i1cx8kbcLQgPykzSpG9njOjYcf93.439e1a71635533bec2decd71379aa5d4.state.status' Dec 30 23:41:57 volumio volumio[1034]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.i1cx8kbcLQgPykzSpG9njOjYcf93.439e1a71635533bec2decd71379aa5d4.state.status' Dec 30 23:42:03 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 30 23:42:03 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 30 23:42:03 volumio sudo[1867]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 30 23:42:03 volumio sudo[1867]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 23:42:03 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 30 23:42:03 volumio volumio[1034]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 30 23:42:03 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 30 23:42:03 volumio sudo[1867]: pam_unix(sudo:session): session closed for user root Dec 30 23:42:03 volumio volumio[1034]: info: Received Get System Version Dec 30 23:42:03 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 30 23:42:03 volumio volumio[1034]: info: Received Get System Info Dec 30 23:42:03 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 30 23:42:03 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 30 23:42:03 volumio volumio[1034]: info: Discovery: Getting this device information Dec 30 23:42:03 volumio volumio[1034]: info: CoreCommandRouter::volumioGetState Dec 30 23:42:03 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 30 23:42:03 volumio sudo[1870]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 30 23:42:03 volumio sudo[1870]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 23:42:03 volumio sudo[1870]: pam_unix(sudo:session): session closed for user root Dec 30 23:42:03 volumio volumio[1034]: verbose: New Socket.io Connection to 192.168.1.9 from 192.168.1.13 UA: Mozilla/5.0 (Linux; Android 12; SM-N975F Build/SP1A.210812.016; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.34 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Dec 30 23:42:03 volumio volumio[1034]: info: CoreCommandRouter::volumioGetVisibleSources Dec 30 23:42:03 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 30 23:42:03 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 30 23:42:03 volumio volumio[1034]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Dec 30 23:42:03 volumio volumio[1034]: info: CoreCommandRouter::volumioGetState Dec 30 23:42:03 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 30 23:42:03 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 30 23:42:03 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 30 23:42:03 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 30 23:42:03 volumio sudo[1873]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 30 23:42:03 volumio sudo[1873]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 23:42:03 volumio sudo[1873]: pam_unix(sudo:session): session closed for user root Dec 30 23:42:03 volumio sudo[1876]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 30 23:42:03 volumio sudo[1876]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 23:42:03 volumio sudo[1876]: pam_unix(sudo:session): session closed for user root Dec 30 23:42:03 volumio volumio[1034]: verbose: New Socket.io Connection to 192.168.1.9 from 192.168.1.13 UA: Mozilla/5.0 (Linux; Android 12; SM-N975F Build/SP1A.210812.016; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.34 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Dec 30 23:42:04 volumio volumio[1034]: info: CoreCommandRouter::volumioGetState Dec 30 23:42:04 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 30 23:42:04 volumio volumio[1034]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Dec 30 23:42:04 volumio volumio[1034]: info: Listing playlists Dec 30 23:42:04 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 30 23:42:04 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 30 23:42:04 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 30 23:42:04 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 30 23:42:08 volumio volumio[1034]: info: CorePlayQueue::getTrack 0 Dec 30 23:42:08 volumio volumio[1034]: info: CorePlayQueue::getTrack 1 Dec 30 23:42:08 volumio volumio[1034]: info: Prefetching next song Dec 30 23:42:08 volumio volumio[1034]: info: Doing Prefetch in UPNP Dec 30 23:42:08 volumio volumio[1034]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.9:8200/MediaItems/50.wav" Dec 30 23:42:08 volumio volumio[1034]: info: Dec 30 23:42:08 volumio volumio[1034]: ---------------------------- MPD announces system playlist update Dec 30 23:42:08 volumio volumio[1034]: info: Ignoring MPD Status Update Dec 30 23:42:08 volumio volumio[1034]: info: sendMpdCommand add "http://192.168.1.9:8200/MediaItems/50.wav" took 1 milliseconds Dec 30 23:42:08 volumio volumio[1034]: verbose: ControllerMpd::sendMpdCommand consume 1 Dec 30 23:42:08 volumio volumio[1034]: info: Dec 30 23:42:08 volumio volumio[1034]: ---------------------------- MPD announces state update: options Dec 30 23:42:08 volumio volumio[1034]: info: ------------------------------ 2ms Dec 30 23:42:08 volumio volumio[1034]: info: sendMpdCommand consume 1 took 1 milliseconds Dec 30 23:42:08 volumio volumio[1034]: info: ControllerMpd::getState Dec 30 23:42:08 volumio volumio[1034]: verbose: ControllerMpd::sendMpdCommand status Dec 30 23:42:08 volumio volumio[1034]: info: sendMpdCommand status took 1 milliseconds Dec 30 23:42:08 volumio volumio[1034]: verbose: ControllerMpd::parseState Dec 30 23:42:08 volumio volumio[1034]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 30 23:42:08 volumio volumio[1034]: info: sendMpdCommand playlistinfo took 0 milliseconds Dec 30 23:42:08 volumio volumio[1034]: verbose: ControllerMpd::parseTrackInfo Dec 30 23:42:08 volumio volumio[1034]: info: ControllerMpd::pushState Dec 30 23:42:08 volumio volumio[1034]: info: CoreCommandRouter::servicePushState Dec 30 23:42:08 volumio volumio[1034]: info: CoreStateMachine::pushState Dec 30 23:42:08 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 23:42:08 volumio volumio[1034]: info: CoreCommandRouter::volumioPushState Dec 30 23:42:08 volumio volumio[1034]: info: MRS: Pushing multiroomSync output update for this device Dec 30 23:42:08 volumio volumio[1034]: info: MRS: Pushing multiroomSync output Dec 30 23:42:08 volumio volumio[1034]: info: CoreCommandRouter::volumioGetState Dec 30 23:42:08 volumio volumio[1034]: info: CorePlayQueue::getTrack 0 Dec 30 23:42:08 volumio volumio[1034]: 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":"46.wav","artist":null,"album":null,"uri":"http://192.168.1.9:8200/MediaItems/46.wav","trackType":"wav"} Dec 30 23:42:08 volumio volumio[1034]: verbose: CURRENT POSITION 0 Dec 30 23:42:08 volumio volumio[1034]: info: CoreStateMachine::syncState stateService stop Dec 30 23:42:08 volumio volumio[1034]: info: CoreStateMachine::syncState currentStatus stop Dec 30 23:42:08 volumio volumio[1034]: info: CoreStateMachine::pushState Dec 30 23:42:08 volumio volumio[1034]: info: CoreCommandRouter::volumioPushState Dec 30 23:42:08 volumio volumio[1034]: info: MRS: Pushing multiroomSync output update for this device Dec 30 23:42:08 volumio volumio[1034]: info: MRS: Pushing multiroomSync output Dec 30 23:42:08 volumio volumio[1034]: info: CoreCommandRouter::volumioGetState Dec 30 23:42:08 volumio volumio[1034]: info: No code Dec 30 23:42:08 volumio volumio[1034]: info: CoreStateMachine::pushState Dec 30 23:42:08 volumio volumio[1034]: info: CoreCommandRouter::volumioPushState Dec 30 23:42:08 volumio volumio[1034]: info: MRS: Pushing multiroomSync output update for this device Dec 30 23:42:08 volumio volumio[1034]: info: MRS: Pushing multiroomSync output Dec 30 23:42:08 volumio volumio[1034]: info: CoreCommandRouter::volumioGetState Dec 30 23:42:08 volumio volumio[1034]: info: ------------------------------ 49ms Dec 30 23:42:08 volumio volumio[1034]: SPOTIFY: RECEIVED VOLUMIO VOLUME 94 Dec 30 23:42:08 volumio volumio[1034]: SPOTIFY: RECEIVED VOLUMIO VOLUME 94 Dec 30 23:42:08 volumio volumio[1034]: SPOTIFY: RECEIVED VOLUMIO VOLUME 94 Dec 30 23:42:08 volumio volumio[1034]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.i1cx8kbcLQgPykzSpG9njOjYcf93.439e1a71635533bec2decd71379aa5d4.state.status' Dec 30 23:42:08 volumio volumio[1034]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.i1cx8kbcLQgPykzSpG9njOjYcf93.439e1a71635533bec2decd71379aa5d4.state.status' Dec 30 23:42:09 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Dec 30 23:42:09 volumio volumio[1034]: error: MRS: impossible to set Google-Nest-Hub-34672546eab7ceda2820e93e2f053844 volume: device not found Dec 30 23:42:11 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Dec 30 23:42:11 volumio volumio[1034]: error: MRS: impossible to set Google-Nest-Mini-873813127aba523d26210904d55360c7 volume: device not found Dec 30 23:42:11 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Dec 30 23:42:11 volumio volumio[1034]: error: MRS: impossible to set Chromecast-dde4653fe1673a8e21913e739685f3d7 volume: device not found Dec 30 23:42:13 volumio volumio[1034]: info: CoreStateMachine::startPlaybackTimer Dec 30 23:42:13 volumio volumio[1034]: info: CorePlayQueue::getTrack 1 Dec 30 23:42:13 volumio volumio[1034]: info: CoreStateMachine::pushState Dec 30 23:42:13 volumio volumio[1034]: info: CorePlayQueue::getTrack 1 Dec 30 23:42:13 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 23:42:13 volumio volumio[1034]: info: CoreCommandRouter::volumioPushState Dec 30 23:42:13 volumio volumio[1034]: info: MRS: Pushing multiroomSync output update for this device Dec 30 23:42:13 volumio volumio[1034]: info: MRS: Pushing multiroomSync output Dec 30 23:42:13 volumio volumio[1034]: info: CoreCommandRouter::volumioGetState Dec 30 23:42:13 volumio volumio[1034]: info: CorePlayQueue::getTrack 1 Dec 30 23:42:13 volumio volumio[1034]: SPOTIFY: RECEIVED VOLUMIO VOLUME 94 Dec 30 23:42:21 volumio volumio[1034]: info: CorePlayQueue::getTrack 1 Dec 30 23:42:21 volumio volumio[1034]: info: CorePlayQueue::getTrack 2 Dec 30 23:42:21 volumio volumio[1034]: info: Prefetching next song Dec 30 23:42:21 volumio volumio[1034]: info: Doing Prefetch in UPNP Dec 30 23:42:21 volumio volumio[1034]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.9:8200/MediaItems/51.wav" Dec 30 23:42:21 volumio volumio[1034]: info: Dec 30 23:42:21 volumio volumio[1034]: ---------------------------- MPD announces system playlist update Dec 30 23:42:21 volumio volumio[1034]: info: Ignoring MPD Status Update Dec 30 23:42:21 volumio volumio[1034]: info: sendMpdCommand add "http://192.168.1.9:8200/MediaItems/51.wav" took 1 milliseconds Dec 30 23:42:21 volumio volumio[1034]: verbose: ControllerMpd::sendMpdCommand consume 1 Dec 30 23:42:21 volumio volumio[1034]: info: ------------------------------ 2ms Dec 30 23:42:21 volumio volumio[1034]: info: sendMpdCommand consume 1 took 1 milliseconds Dec 30 23:42:22 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 30 23:42:22 volumio volumio[1034]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 30 23:42:22 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 30 23:42:22 volumio volumio[1034]: info: Received Get System Version Dec 30 23:42:22 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 30 23:42:22 volumio volumio[1034]: info: Received Get System Info Dec 30 23:42:22 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 30 23:42:22 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 30 23:42:22 volumio volumio[1034]: info: Discovery: Getting this device information Dec 30 23:42:22 volumio volumio[1034]: info: CoreCommandRouter::volumioGetState Dec 30 23:42:22 volumio volumio[1034]: info: CorePlayQueue::getTrack 1 Dec 30 23:42:22 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 30 23:42:22 volumio go-librespot[1226]: time="2025-12-30T23:42:22Z" level=trace msg="received accesspoint ping" Dec 30 23:42:22 volumio go-librespot[1226]: time="2025-12-30T23:42:22Z" level=trace msg="received accesspoint pong ack" Dec 30 23:42:24 volumio go-librespot[1226]: time="2025-12-30T23:42:24Z" level=trace msg="sent dealer ping" Dec 30 23:42:24 volumio go-librespot[1226]: time="2025-12-30T23:42:24Z" level=trace msg="received dealer pong" Dec 30 23:42:25 volumio volumio[1034]: info: CoreStateMachine::startPlaybackTimer Dec 30 23:42:25 volumio volumio[1034]: info: CorePlayQueue::getTrack 2 Dec 30 23:42:26 volumio volumio[1034]: info: CoreStateMachine::pushState Dec 30 23:42:26 volumio volumio[1034]: info: CorePlayQueue::getTrack 2 Dec 30 23:42:26 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 23:42:26 volumio volumio[1034]: info: CoreCommandRouter::volumioPushState Dec 30 23:42:26 volumio volumio[1034]: info: MRS: Pushing multiroomSync output update for this device Dec 30 23:42:26 volumio volumio[1034]: info: MRS: Pushing multiroomSync output Dec 30 23:42:26 volumio volumio[1034]: info: CoreCommandRouter::volumioGetState Dec 30 23:42:26 volumio volumio[1034]: info: CorePlayQueue::getTrack 2 Dec 30 23:42:26 volumio volumio[1034]: SPOTIFY: RECEIVED VOLUMIO VOLUME 94 Dec 30 23:42:31 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: multiroom , audioOutputPlay Dec 30 23:42:31 volumio volumio[1034]: info: Error : CoreCommandRouter::executeOnPlugin: No method [audioOutputPlay] in plugin multiroom Dec 30 23:42:31 volumio volumio[1034]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 30 23:42:31 volumio volumio[1034]: TypeError: Cannot read property 'then' of undefined Dec 30 23:42:31 volumio volumio[1034]: at outputs.audioOutputPlay (/volumio/app/plugins/audio_interface/outputs/index.js:367:9) Dec 30 23:42:31 volumio volumio[1034]: at CoreCommandRouter.audioOutputPlay (/volumio/app/index.js:2279:30) Dec 30 23:42:31 volumio volumio[1034]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1467:26) Dec 30 23:42:31 volumio volumio[1034]: at Socket.emit (events.js:315:20) Dec 30 23:42:31 volumio volumio[1034]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Dec 30 23:42:31 volumio volumio[1034]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Dec 30 23:42:31 volumio volumio[1034]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 30 23:42:32 volumio sudo[1932]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-30 23:41 Dec 30 23:42:32 volumio sudo[1932]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 05:43:24 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="15d0241b0cd805792809f3ddf4a0fc4e"