-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Thu 2025-07-03 15:11:43 CEST. -- Jul 03 15:10:03 volumio systemd[1]: systemd-timedated.service: Succeeded. Jul 03 15:10:05 volumio volumio[872]: info: CoreCommandRouter::volumioGetState Jul 03 15:10:05 volumio volumio[872]: info: CorePlayQueue::getTrack 0 Jul 03 15:10:05 volumio volumio[872]: info: Listing playlists Jul 03 15:10:05 volumio volumio[872]: info: MyVolumio token set successfully Jul 03 15:10:05 volumio volumio[872]: info: MYVOLUMIO: Adding device Jul 03 15:10:05 volumio volumio[872]: info: MYVOLUMIO: Evaluating Server Jul 03 15:10:06 volumio volumio[872]: info: MyVolumio status changed Jul 03 15:10:06 volumio volumio[872]: info: Streaming services startup Jul 03 15:10:06 volumio volumio[872]: info: Starting Streaming Daemon Jul 03 15:10:06 volumio volumio[872]: info: Removing browser output: myVolumio user plan is not superstar Jul 03 15:10:06 volumio volumio[872]: info: Removing audio output: Jul 03 15:10:06 volumio volumio[872]: info: Stoppping Tunnel 1 Jul 03 15:10:06 volumio sudo[1678]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jul 03 15:10:06 volumio sudo[1678]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 15:10:06 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 03 15:10:06 volumio volumio[872]: info: Received Get System Info Jul 03 15:10:06 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 03 15:10:06 volumio sudo[1681]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jul 03 15:10:06 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 03 15:10:06 volumio sudo[1681]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 15:10:06 volumio volumio[872]: info: Discovery: Getting this device information Jul 03 15:10:06 volumio volumio[872]: info: CoreCommandRouter::volumioGetState Jul 03 15:10:06 volumio volumio[872]: info: CorePlayQueue::getTrack 0 Jul 03 15:10:06 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 03 15:10:06 volumio sudo[1678]: pam_unix(sudo:session): session closed for user root Jul 03 15:10:06 volumio volumio[872]: error: Cannot start Volumio Streaming Daemon Jul 03 15:10:06 volumio volumio[872]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jul 03 15:10:06 volumio volumio[872]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jul 03 15:10:06 volumio sudo[1681]: pam_unix(sudo:session): session closed for user root Jul 03 15:10:06 volumio volumio[872]: info: Remote SSH Stopped Jul 03 15:10:06 volumio volumio[872]: info: Setting Geolocation for MyVolumio to eu4 Jul 03 15:10:06 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 15:10:06 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jul 03 15:10:06 volumio volumio[872]: info: Successfully Added MyVolumio device Jul 03 15:10:07 volumio volumio[872]: info: Updating MyVolumio device info Jul 03 15:10:07 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 15:10:07 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 15:10:07 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 15:10:07 volumio volumio[872]: info: Successfully Updated MyVolumio device Jul 03 15:10:08 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jul 03 15:10:08 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 03 15:10:08 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 03 15:10:08 volumio volumio[872]: info: Received Get System Info Jul 03 15:10:08 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 03 15:10:08 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 03 15:10:08 volumio volumio[872]: info: Discovery: Getting this device information Jul 03 15:10:08 volumio volumio[872]: info: CoreCommandRouter::volumioGetState Jul 03 15:10:08 volumio volumio[872]: info: CorePlayQueue::getTrack 0 Jul 03 15:10:08 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 03 15:10:10 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 03 15:10:10 volumio volumio[872]: info: Received Get System Info Jul 03 15:10:10 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 03 15:10:10 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 03 15:10:10 volumio volumio[872]: info: Discovery: Getting this device information Jul 03 15:10:10 volumio volumio[872]: info: CoreCommandRouter::volumioGetState Jul 03 15:10:10 volumio volumio[872]: info: CorePlayQueue::getTrack 0 Jul 03 15:10:10 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 03 15:10:11 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 03 15:10:11 volumio volumio[872]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Jul 03 15:10:11 volumio volumio[872]: info: CoreCommandRouter::volumioGetState Jul 03 15:10:11 volumio volumio[872]: info: CorePlayQueue::getTrack 0 Jul 03 15:10:11 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 03 15:10:11 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 15:10:11 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 03 15:10:11 volumio volumio-remote-updater[628]: No test mode Jul 03 15:10:11 volumio volumio-remote-updater[628]: No alpha test mode Jul 03 15:10:11 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jul 03 15:10:11 volumio volumio[872]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jul 03 15:10:11 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jul 03 15:10:15 volumio volumio[872]: info: CoreCommandRouter::volumioGetState Jul 03 15:10:15 volumio volumio[872]: info: CorePlayQueue::getTrack 0 Jul 03 15:10:16 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 15:10:16 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 15:10:16 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jul 03 15:10:16 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jul 03 15:10:16 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jul 03 15:10:16 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jul 03 15:10:16 volumio volumio[872]: info: CoreCommandRouter::volumioGetBrowseSources Jul 03 15:10:16 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 03 15:10:16 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 03 15:10:16 volumio volumio[872]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jul 03 15:10:16 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jul 03 15:10:16 volumio volumio[872]: info: Received Get System Version Jul 03 15:10:16 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 03 15:10:16 volumio volumio[872]: info: Received Get System Info Jul 03 15:10:16 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 03 15:10:16 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 03 15:10:16 volumio volumio[872]: info: Discovery: Getting this device information Jul 03 15:10:16 volumio volumio[872]: info: CoreCommandRouter::volumioGetState Jul 03 15:10:16 volumio volumio[872]: info: CorePlayQueue::getTrack 0 Jul 03 15:10:16 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 03 15:10:17 volumio volumio[872]: error: MyVolumio Plugin failed to authenticate in a timely fashion Jul 03 15:10:17 volumio volumio[872]: info: Completed starting MyVolumio Plugin Jul 03 15:10:17 volumio volumio[872]: [Metrics] CommandRouter: 50s 550.85ms Jul 03 15:10:17 volumio volumio[872]: info: CoreCommandRouter::volumiosetStartupVolume Jul 03 15:10:17 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 15:10:17 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 15:10:17 volumio volumio[872]: info: CoreCommandRouter::Close All Modals sent Jul 03 15:10:17 volumio volumio[872]: info: CoreCommandRouter::Close All Modals sent Jul 03 15:10:18 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jul 03 15:10:18 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 03 15:10:18 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jul 03 15:10:18 volumio volumio[872]: info: Version has changed, forcing UI Reload Jul 03 15:10:18 volumio volumio[872]: info: CoreCommandRouter::Reload Ui Jul 03 15:10:19 volumio volumio[872]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Jul 03 15:10:19 volumio sudo[1940]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 03 15:10:19 volumio sudo[1940]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 15:10:19 volumio sudo[1940]: pam_unix(sudo:session): session closed for user root Jul 03 15:10:19 volumio sudo[1943]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 03 15:10:19 volumio sudo[1943]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 15:10:19 volumio sudo[1943]: pam_unix(sudo:session): session closed for user root Jul 03 15:10:19 volumio volumio[872]: verbose: New Socket.io Connection to 192.168.1.120 from 192.168.1.144 UA: Mozilla/5.0 (Linux; Android 14; 2409BRN2CY Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.117 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Jul 03 15:10:19 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 03 15:10:19 volumio volumio[872]: info: CoreCommandRouter::volumioGetVisibleSources Jul 03 15:10:19 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 03 15:10:19 volumio volumio[872]: info: CoreCommandRouter::volumioGetState Jul 03 15:10:19 volumio volumio[872]: info: CorePlayQueue::getTrack 0 Jul 03 15:10:19 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 03 15:10:19 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 03 15:10:19 volumio volumio[872]: info: Received Get System Info Jul 03 15:10:19 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 03 15:10:19 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 03 15:10:19 volumio volumio[872]: info: Discovery: Getting this device information Jul 03 15:10:19 volumio volumio[872]: info: CoreCommandRouter::volumioGetState Jul 03 15:10:19 volumio volumio[872]: info: CorePlayQueue::getTrack 0 Jul 03 15:10:19 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 03 15:10:19 volumio volumio[872]: info: CoreCommandRouter::volumioGetState Jul 03 15:10:19 volumio volumio[872]: info: CorePlayQueue::getTrack 0 Jul 03 15:10:19 volumio volumio[872]: info: Listing playlists Jul 03 15:10:19 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jul 03 15:10:19 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 03 15:10:20 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jul 03 15:10:20 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 03 15:10:20 volumio volumio[872]: info: CoreCommandRouter::volumioGetVisibleSources Jul 03 15:10:20 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 03 15:10:20 volumio volumio[872]: info: CoreCommandRouter::volumioGetState Jul 03 15:10:20 volumio volumio[872]: info: CorePlayQueue::getTrack 0 Jul 03 15:10:20 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 03 15:10:20 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 03 15:10:20 volumio volumio[872]: info: Received Get System Info Jul 03 15:10:20 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 03 15:10:20 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 03 15:10:20 volumio volumio[872]: info: Discovery: Getting this device information Jul 03 15:10:20 volumio volumio[872]: info: CoreCommandRouter::volumioGetState Jul 03 15:10:20 volumio volumio[872]: info: CorePlayQueue::getTrack 0 Jul 03 15:10:20 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 03 15:10:20 volumio volumio[872]: info: CoreCommandRouter::volumioGetState Jul 03 15:10:20 volumio volumio[872]: info: CorePlayQueue::getTrack 0 Jul 03 15:10:20 volumio volumio[872]: info: Listing playlists Jul 03 15:10:20 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jul 03 15:10:20 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 03 15:10:20 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jul 03 15:10:20 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 03 15:10:20 volumio volumio[872]: info: Received Get System Info Jul 03 15:10:20 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 03 15:10:20 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 03 15:10:20 volumio volumio[872]: info: Discovery: Getting this device information Jul 03 15:10:20 volumio volumio[872]: info: CoreCommandRouter::volumioGetState Jul 03 15:10:20 volumio volumio[872]: info: CorePlayQueue::getTrack 0 Jul 03 15:10:20 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 03 15:10:21 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 03 15:10:21 volumio volumio[872]: info: Received Get System Info Jul 03 15:10:21 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 03 15:10:21 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 03 15:10:21 volumio volumio[872]: info: Discovery: Getting this device information Jul 03 15:10:21 volumio volumio[872]: info: CoreCommandRouter::volumioGetState Jul 03 15:10:21 volumio volumio[872]: info: CorePlayQueue::getTrack 0 Jul 03 15:10:21 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 03 15:10:21 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jul 03 15:10:21 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jul 03 15:10:21 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 03 15:10:22 volumio volumio[872]: info: Received Get System Info Jul 03 15:10:22 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 03 15:10:22 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 03 15:10:22 volumio volumio[872]: info: Discovery: Getting this device information Jul 03 15:10:22 volumio volumio[872]: info: CoreCommandRouter::volumioGetState Jul 03 15:10:22 volumio volumio[872]: info: CorePlayQueue::getTrack 0 Jul 03 15:10:22 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 03 15:10:22 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 03 15:10:22 volumio volumio[872]: info: Received Get System Info Jul 03 15:10:22 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 03 15:10:22 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 03 15:10:22 volumio volumio[872]: info: Discovery: Getting this device information Jul 03 15:10:22 volumio volumio[872]: info: CoreCommandRouter::volumioGetState Jul 03 15:10:22 volumio volumio[872]: info: CorePlayQueue::getTrack 0 Jul 03 15:10:22 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 03 15:10:24 volumio volumio[872]: info: BOOT COMPLETED Jul 03 15:10:24 volumio volumio[872]: info: mpdhttpout ---Boot completed detected! Patching mpd now! Jul 03 15:10:24 volumio volumio[872]: info: CoreCommandRouter::volumioGetState Jul 03 15:10:24 volumio volumio[872]: info: CorePlayQueue::getTrack 0 Jul 03 15:10:24 volumio volumio[872]: info: CoreCommandRouter::volumioPause Jul 03 15:10:24 volumio volumio[872]: info: CoreStateMachine::pause Jul 03 15:10:24 volumio volumio[872]: info: mpdhttpout --- Volumio set on pause Jul 03 15:10:24 volumio volumio[872]: info: mpdhttpout ---Configuration successfully added to mpd.conf. Jul 03 15:10:25 volumio volumio[872]: info: CoreCommandRouter::volumioGetState Jul 03 15:10:25 volumio volumio[872]: info: CorePlayQueue::getTrack 0 Jul 03 15:10:25 volumio volumio[872]: info: Listing playlists Jul 03 15:10:25 volumio volumio[872]: info: Listing playlists Jul 03 15:10:26 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Jul 03 15:10:26 volumio sudo[2004]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 03 15:10:26 volumio sudo[2004]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 15:10:26 volumio systemd[1]: Stopping Music Player Daemon... Jul 03 15:10:26 volumio volumio[872]: info: CoreCommandRouter::volumioGetState Jul 03 15:10:26 volumio volumio[872]: info: CorePlayQueue::getTrack 0 Jul 03 15:10:26 volumio systemd[1]: mpd.service: Succeeded. Jul 03 15:10:26 volumio systemd[1]: Stopped Music Player Daemon. Jul 03 15:10:26 volumio systemd[1]: Starting Music Player Daemon... Jul 03 15:10:26 volumio sudo[2006]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 03 15:10:26 volumio sudo[2006]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 15:10:27 volumio sudo[2006]: pam_unix(sudo:session): session closed for user root Jul 03 15:10:28 volumio mpd[2008]: Jul 03 15:10 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 03 15:10:28 volumio systemd[1]: Started Music Player Daemon. Jul 03 15:10:28 volumio sudo[2004]: pam_unix(sudo:session): session closed for user root Jul 03 15:10:28 volumio volumio[872]: error: updateQueue error: null Jul 03 15:10:28 volumio ntpd[840]: Soliciting pool server 162.159.200.1 Jul 03 15:10:29 volumio ntpd[840]: Soliciting pool server 91.210.128.220 Jul 03 15:10:29 volumio ntpd[840]: Soliciting pool server 194.146.251.101 Jul 03 15:10:29 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 03 15:10:29 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 03 15:10:30 volumio ntpd[840]: Soliciting pool server 213.222.217.10 Jul 03 15:10:30 volumio ntpd[840]: Soliciting pool server 46.174.178.150 Jul 03 15:10:30 volumio ntpd[840]: Soliciting pool server 193.59.134.156 Jul 03 15:10:31 volumio ntpd[840]: Soliciting pool server 212.127.78.21 Jul 03 15:10:31 volumio ntpd[840]: Soliciting pool server 162.159.200.123 Jul 03 15:10:31 volumio ntpd[840]: Soliciting pool server 91.212.242.21 Jul 03 15:10:32 volumio ntpd[840]: Soliciting pool server 156.17.20.254 Jul 03 15:10:32 volumio ntpd[840]: Soliciting pool server 51.68.141.5 Jul 03 15:10:33 volumio ntpd[840]: Soliciting pool server 178.215.228.24 Jul 03 15:10:34 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 03 15:10:34 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jul 03 15:10:34 volumio ntpd[840]: Soliciting pool server 2a0d:5440::24 Jul 03 15:10:34 volumio ntpd[840]: Soliciting pool server 193.25.222.136 Jul 03 15:10:35 volumio ntpd[840]: Soliciting pool server 194.146.251.114 Jul 03 15:10:35 volumio volumio[872]: info: CoreCommandRouter::volumioGetState Jul 03 15:10:35 volumio volumio[872]: info: CorePlayQueue::getTrack 0 Jul 03 15:10:36 volumio ntpd[840]: Soliciting pool server 213.222.217.11 Jul 03 15:10:37 volumio ntpd[840]: receive: Unexpected origin timestamp 0xec10ffcc.2b7b2cbb does not match aorg 0000000000.00000000 from server@212.127.78.21 xmt 0xec10ffcd.68304301 Jul 03 15:10:37 volumio ntpd[840]: receive: Unexpected origin timestamp 0xec10ffcc.2b7a2c17 does not match aorg 0000000000.00000000 from server@91.212.242.21 xmt 0xec10ffcd.68679817 Jul 03 15:10:37 volumio ntpd[840]: receive: Unexpected origin timestamp 0xec10ffcc.2b7cfd52 does not match aorg 0000000000.00000000 from server@162.159.200.1 xmt 0xec10ffcd.68507fae Jul 03 15:10:37 volumio ntpd[840]: receive: Unexpected origin timestamp 0xec10ffcc.2b77e7cd does not match aorg 0000000000.00000000 from server@162.159.200.123 xmt 0xec10ffcd.684facae Jul 03 15:10:38 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 03 15:10:38 volumio volumio[872]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jul 03 15:10:38 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jul 03 15:10:38 volumio volumio[872]: info: Received Get System Version Jul 03 15:10:38 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 03 15:10:38 volumio volumio[872]: info: Received Get System Info Jul 03 15:10:38 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 03 15:10:38 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 03 15:10:38 volumio volumio[872]: info: Discovery: Getting this device information Jul 03 15:10:38 volumio volumio[872]: info: CoreCommandRouter::volumioGetState Jul 03 15:10:38 volumio volumio[872]: info: CorePlayQueue::getTrack 0 Jul 03 15:10:38 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 03 15:10:38 volumio ntpd[840]: Soliciting pool server 212.127.95.218 Jul 03 15:10:38 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 03 15:10:38 volumio volumio[872]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jul 03 15:10:38 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jul 03 15:10:38 volumio volumio[872]: info: Received Get System Version Jul 03 15:10:38 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 03 15:10:38 volumio volumio[872]: info: Received Get System Info Jul 03 15:10:38 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 03 15:10:38 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 03 15:10:38 volumio volumio[872]: info: Discovery: Getting this device information Jul 03 15:10:38 volumio volumio[872]: info: CoreCommandRouter::volumioGetState Jul 03 15:10:38 volumio volumio[872]: info: CorePlayQueue::getTrack 0 Jul 03 15:10:38 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 03 15:10:40 volumio volumio[872]: info: Enabling plugin peppy_screensaver Jul 03 15:10:40 volumio volumio[872]: info: Loading plugin "peppy_screensaver"... Jul 03 15:10:40 volumio volumio[872]: info: Applying required configuration parameters for plugin peppy_screensaver Jul 03 15:10:40 volumio volumio[872]: info: Preparing to generate the ALSA configuration file Jul 03 15:10:40 volumio volumio[872]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Jul 03 15:10:40 volumio volumio[872]: info: Reading ALSA contributions from plugins. Jul 03 15:10:40 volumio volumio[872]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Jul 03 15:10:40 volumio volumio[872]: info: Asound.conf file written Jul 03 15:10:40 volumio sudo[2054]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jul 03 15:10:40 volumio sudo[2054]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 15:10:40 volumio sudo[2054]: pam_unix(sudo:session): session closed for user root Jul 03 15:10:40 volumio volumio[872]: info: Output device has changed, restarting MPD Jul 03 15:10:40 volumio sudo[2059]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 03 15:10:40 volumio sudo[2059]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 15:10:40 volumio sudo[2059]: pam_unix(sudo:session): session closed for user root Jul 03 15:10:40 volumio volumio[872]: info: Output device has changed, restarting Shairport Sync Jul 03 15:10:40 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 15:10:40 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 15:10:40 volumio sudo[2062]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 03 15:10:40 volumio sudo[2062]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 15:10:40 volumio systemd[1]: Stopping Music Player Daemon... Jul 03 15:10:41 volumio volumio[872]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 03 15:10:41 volumio volumio[872]: info: PLUGIN START: peppy_screensaver Jul 03 15:10:41 volumio systemd[1]: mpd.service: Succeeded. Jul 03 15:10:41 volumio systemd[1]: Stopped Music Player Daemon. Jul 03 15:10:41 volumio volumio[872]: info: Loading i18n strings for locale pl Jul 03 15:10:41 volumio volumio[872]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Jul 03 15:10:41 volumio volumio[872]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Jul 03 15:10:41 volumio volumio[872]: info: /tmp/myfifo created Jul 03 15:10:41 volumio systemd[1]: Starting Music Player Daemon... Jul 03 15:10:41 volumio volumio[872]: info: /tmp/myfifosa created Jul 03 15:10:41 volumio sudo[2073]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd-dummy index=7 pcm_substreams=1 fake_buffer=0 Jul 03 15:10:41 volumio sudo[2073]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 15:10:41 volumio sudo[2073]: pam_unix(sudo:session): session closed for user root Jul 03 15:10:41 volumio volumio[872]: info: snd-dummy loaded Jul 03 15:10:41 volumio sudo[2067]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 03 15:10:41 volumio sudo[2067]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 15:10:41 volumio sudo[2067]: pam_unix(sudo:session): session closed for user root Jul 03 15:10:41 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 15:10:41 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 15:10:41 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 15:10:41 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startShairportSync Jul 03 15:10:41 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 15:10:41 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 15:10:41 volumio volumio[872]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 03 15:10:41 volumio volumio[872]: info: Done. Jul 03 15:10:41 volumio volumio[872]: info: MPD Permissions set Jul 03 15:10:41 volumio volumio[872]: info: CoreCommandRouter::volumioGetState Jul 03 15:10:41 volumio volumio[872]: info: CorePlayQueue::getTrack 0 Jul 03 15:10:41 volumio volumio[872]: info: CoreCommandRouter::volumioGetState Jul 03 15:10:41 volumio volumio[872]: info: CorePlayQueue::getTrack 0 Jul 03 15:10:41 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 15:10:41 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 15:10:41 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 15:10:41 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 15:10:41 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 15:10:41 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 15:10:41 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 15:10:41 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 15:10:41 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 15:10:41 volumio sudo[2102]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount --bind /tmp/mpd.conf.tmpl /volumio/app/plugins/music_service/mpd/mpd.conf.tmpl Jul 03 15:10:41 volumio sudo[2102]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 15:10:41 volumio volumio[872]: info: Starting Shairport Sync Jul 03 15:10:41 volumio sudo[2102]: pam_unix(sudo:session): session closed for user root Jul 03 15:10:41 volumio volumio[872]: info: mpdhttpout ---Configuration successfully replaced in mpd.conf. Jul 03 15:10:41 volumio volumio[872]: info: CoreCommandRouter::volumioGetState Jul 03 15:10:41 volumio volumio[872]: info: CorePlayQueue::getTrack 0 Jul 03 15:10:41 volumio volumio[872]: info: CoreCommandRouter::volumioPause Jul 03 15:10:41 volumio volumio[872]: info: CoreStateMachine::pause Jul 03 15:10:41 volumio volumio[872]: info: mpdhttpout --- Volumio set on pause Jul 03 15:10:41 volumio volumio[872]: info: Starting Shairport Sync Jul 03 15:10:41 volumio sudo[2105]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 03 15:10:41 volumio sudo[2105]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 15:10:41 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 03 15:10:41 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: mpd , createMPDFile Jul 03 15:10:41 volumio systemd[1]: shairport-sync.service: Succeeded. Jul 03 15:10:41 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 03 15:10:41 volumio sudo[2109]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 03 15:10:41 volumio sudo[2109]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 15:10:41 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 03 15:10:41 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 03 15:10:41 volumio systemd[1]: shairport-sync.service: Succeeded. Jul 03 15:10:41 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 03 15:10:41 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 03 15:10:41 volumio sudo[2112]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 03 15:10:41 volumio sudo[2112]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 15:10:41 volumio sudo[2109]: pam_unix(sudo:session): session closed for user root Jul 03 15:10:41 volumio sudo[2112]: pam_unix(sudo:session): session closed for user root Jul 03 15:10:41 volumio sudo[2105]: pam_unix(sudo:session): session closed for user root Jul 03 15:10:41 volumio volumio[872]: info: MPD Permissions set Jul 03 15:10:41 volumio volumio[872]: info: Shairport-Sync Started Jul 03 15:10:41 volumio volumio[872]: info: Shairport-Sync Started Jul 03 15:10:41 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 15:10:41 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 15:10:41 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 15:10:41 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 15:10:41 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 15:10:41 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 15:10:41 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 15:10:41 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 15:10:42 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 15:10:42 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 15:10:42 volumio mpd[2080]: Jul 03 15:10 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 03 15:10:42 volumio systemd[1]: Started Music Player Daemon. Jul 03 15:10:42 volumio sudo[2062]: pam_unix(sudo:session): session closed for user root Jul 03 15:10:42 volumio volumio[872]: error: updateQueue error: null Jul 03 15:10:42 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Jul 03 15:10:42 volumio sudo[2126]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 03 15:10:42 volumio sudo[2126]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 15:10:42 volumio systemd[1]: Stopping Music Player Daemon... Jul 03 15:10:42 volumio volumio[872]: info: CoreCommandRouter::volumioGetState Jul 03 15:10:42 volumio volumio[872]: info: CorePlayQueue::getTrack 0 Jul 03 15:10:42 volumio systemd[1]: mpd.service: Succeeded. Jul 03 15:10:42 volumio systemd[1]: Stopped Music Player Daemon. Jul 03 15:10:42 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 15:10:42 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 15:10:42 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 15:10:42 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 03 15:10:42 volumio systemd[1]: Starting Music Player Daemon... Jul 03 15:10:42 volumio sudo[2129]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 03 15:10:42 volumio sudo[2129]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 15:10:42 volumio sudo[2129]: pam_unix(sudo:session): session closed for user root Jul 03 15:10:43 volumio mpd[2131]: Jul 03 15:10 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 03 15:10:43 volumio systemd[1]: Started Music Player Daemon. Jul 03 15:10:43 volumio sudo[2126]: pam_unix(sudo:session): session closed for user root Jul 03 15:10:43 volumio volumio[872]: error: updateQueue error: null Jul 03 15:10:43 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Jul 03 15:10:43 volumio sudo[2135]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 03 15:10:43 volumio sudo[2135]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 15:10:43 volumio systemd[1]: Stopping Music Player Daemon... Jul 03 15:10:43 volumio volumio[872]: info: CoreCommandRouter::volumioGetState Jul 03 15:10:43 volumio volumio[872]: info: CorePlayQueue::getTrack 0 Jul 03 15:10:43 volumio systemd[1]: mpd.service: Succeeded. Jul 03 15:10:43 volumio systemd[1]: Stopped Music Player Daemon. Jul 03 15:10:43 volumio systemd[1]: Starting Music Player Daemon... Jul 03 15:10:44 volumio sudo[2137]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 03 15:10:44 volumio sudo[2137]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 15:10:44 volumio sudo[2137]: pam_unix(sudo:session): session closed for user root Jul 03 15:10:44 volumio mpd[2139]: Jul 03 15:10 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 03 15:10:44 volumio systemd[1]: Started Music Player Daemon. Jul 03 15:10:44 volumio sudo[2135]: pam_unix(sudo:session): session closed for user root Jul 03 15:10:44 volumio volumio[872]: error: updateQueue error: null Jul 03 15:10:46 volumio volumio[872]: info: CoreCommandRouter::volumioGetState Jul 03 15:10:46 volumio volumio[872]: info: CorePlayQueue::getTrack 0 Jul 03 15:10:46 volumio volumio[872]: info: Listing playlists Jul 03 15:10:46 volumio volumio[872]: info: Listing playlists Jul 03 15:10:52 volumio volumio[872]: info: CoreCommandRouter::volumioGetQueue Jul 03 15:10:52 volumio volumio[872]: info: CoreStateMachine::getQueue Jul 03 15:10:52 volumio volumio[872]: info: CorePlayQueue::getQueue Jul 03 15:10:56 volumio volumio[872]: info: CoreCommandRouter::volumioGetState Jul 03 15:10:56 volumio volumio[872]: info: CorePlayQueue::getTrack 0 Jul 03 15:11:03 volumio volumio[872]: info: Preload queue cleared Jul 03 15:11:03 volumio volumio[872]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 03 15:11:03 volumio volumio[872]: info: CoreStateMachine::ClearQueue Jul 03 15:11:03 volumio volumio[872]: info: CoreStateMachine::stop Jul 03 15:11:03 volumio volumio[872]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 03 15:11:03 volumio volumio[872]: info: CorePlayQueue::clearPlayQueue Jul 03 15:11:03 volumio volumio[872]: info: CorePlayQueue::saveQueue Jul 03 15:11:03 volumio volumio[872]: info: CoreCommandRouter::volumioPushQueue Jul 03 15:11:03 volumio volumio[872]: info: CoreStateMachine::addQueueItems Jul 03 15:11:03 volumio volumio[872]: info: CorePlayQueue::addQueueItems Jul 03 15:11:03 volumio volumio[872]: info: Preload queue cleared Jul 03 15:11:03 volumio volumio[872]: info: Adding Item to queue: upnp/http://192.168.1.122:1843/ContentDirectory/71e3052c-d7cc-f58b-3e9d-ed0a0ef49141/control.xml@L3Zhci9tZWRpYS9zZGExLWF0YS1NVEZEREFWMjU2VEJOLTFBL011emEvRWxlbmkgLSBaxYJvdGUgUHJ6ZWJvamUgKDE5OTUpIChDRCxDb21waWxhdGlvbikgKFdBVkUpIChNckA3MykvQ2QvMDEuRWxlbmkgLSBBIFPFgm/FhGNlIFNvYmllIEzFm25pLndhdg== Jul 03 15:11:03 volumio volumio[872]: info: Exploding uri upnp/http://192.168.1.122:1843/ContentDirectory/71e3052c-d7cc-f58b-3e9d-ed0a0ef49141/control.xml@L3Zhci9tZWRpYS9zZGExLWF0YS1NVEZEREFWMjU2VEJOLTFBL011emEvRWxlbmkgLSBaxYJvdGUgUHJ6ZWJvamUgKDE5OTUpIChDRCxDb21waWxhdGlvbikgKFdBVkUpIChNckA3MykvQ2QvMDEuRWxlbmkgLSBBIFPFgm/FhGNlIFNvYmllIEzFm25pLndhdg== in service upnp_browser Jul 03 15:11:03 volumio volumio[872]: info: Adding Item to queue: upnp/http://192.168.1.122:1843/ContentDirectory/71e3052c-d7cc-f58b-3e9d-ed0a0ef49141/control.xml@L3Zhci9tZWRpYS9zZGExLWF0YS1NVEZEREFWMjU2VEJOLTFBL011emEvRWxlbmkgLSBaxYJvdGUgUHJ6ZWJvamUgKDE5OTUpIChDRCxDb21waWxhdGlvbikgKFdBVkUpIChNckA3MykvQ2QvMDIuRWxlbmkgLSBEbyBXaWR6ZW5pYSwgTcOzaiBLb2NoYW55Lndhdg== Jul 03 15:11:03 volumio volumio[872]: info: Exploding uri upnp/http://192.168.1.122:1843/ContentDirectory/71e3052c-d7cc-f58b-3e9d-ed0a0ef49141/control.xml@L3Zhci9tZWRpYS9zZGExLWF0YS1NVEZEREFWMjU2VEJOLTFBL011emEvRWxlbmkgLSBaxYJvdGUgUHJ6ZWJvamUgKDE5OTUpIChDRCxDb21waWxhdGlvbikgKFdBVkUpIChNckA3MykvQ2QvMDIuRWxlbmkgLSBEbyBXaWR6ZW5pYSwgTcOzaiBLb2NoYW55Lndhdg== in service upnp_browser Jul 03 15:11:03 volumio volumio[872]: info: Adding Item to queue: upnp/http://192.168.1.122:1843/ContentDirectory/71e3052c-d7cc-f58b-3e9d-ed0a0ef49141/control.xml@L3Zhci9tZWRpYS9zZGExLWF0YS1NVEZEREFWMjU2VEJOLTFBL011emEvRWxlbmkgLSBaxYJvdGUgUHJ6ZWJvamUgKDE5OTUpIChDRCxDb21waWxhdGlvbikgKFdBVkUpIChNckA3MykvQ2QvMDMuRWxlbmkgLSBNacWCb8WbxIcgSmFrIFdpbm8ud2F2 Jul 03 15:11:03 volumio volumio[872]: info: Exploding uri upnp/http://192.168.1.122:1843/ContentDirectory/71e3052c-d7cc-f58b-3e9d-ed0a0ef49141/control.xml@L3Zhci9tZWRpYS9zZGExLWF0YS1NVEZEREFWMjU2VEJOLTFBL011emEvRWxlbmkgLSBaxYJvdGUgUHJ6ZWJvamUgKDE5OTUpIChDRCxDb21waWxhdGlvbikgKFdBVkUpIChNckA3MykvQ2QvMDMuRWxlbmkgLSBNacWCb8WbxIcgSmFrIFdpbm8ud2F2 in service upnp_browser Jul 03 15:11:03 volumio volumio[872]: info: Adding Item to queue: upnp/http://192.168.1.122:1843/ContentDirectory/71e3052c-d7cc-f58b-3e9d-ed0a0ef49141/control.xml@L3Zhci9tZWRpYS9zZGExLWF0YS1NVEZEREFWMjU2VEJOLTFBL011emEvRWxlbmkgLSBaxYJvdGUgUHJ6ZWJvamUgKDE5OTUpIChDRCxDb21waWxhdGlvbikgKFdBVkUpIChNckA3MykvQ2QvMDQuRWxlbmkgLSBQbyBTxYJvbmVjem5laiBTdHJvbmllIMW7eWNpYS53YXY= Jul 03 15:11:03 volumio volumio[872]: info: Exploding uri upnp/http://192.168.1.122:1843/ContentDirectory/71e3052c-d7cc-f58b-3e9d-ed0a0ef49141/control.xml@L3Zhci9tZWRpYS9zZGExLWF0YS1NVEZEREFWMjU2VEJOLTFBL011emEvRWxlbmkgLSBaxYJvdGUgUHJ6ZWJvamUgKDE5OTUpIChDRCxDb21waWxhdGlvbikgKFdBVkUpIChNckA3MykvQ2QvMDQuRWxlbmkgLSBQbyBTxYJvbmVjem5laiBTdHJvbmllIMW7eWNpYS53YXY= in service upnp_browser Jul 03 15:11:03 volumio volumio[872]: info: Adding Item to queue: upnp/http://192.168.1.122:1843/ContentDirectory/71e3052c-d7cc-f58b-3e9d-ed0a0ef49141/control.xml@L3Zhci9tZWRpYS9zZGExLWF0YS1NVEZEREFWMjU2VEJOLTFBL011emEvRWxlbmkgLSBaxYJvdGUgUHJ6ZWJvamUgKDE5OTUpIChDRCxDb21waWxhdGlvbikgKFdBVkUpIChNckA3MykvQ2QvMDUuRWxlbmkgLSBUdSBOaWUgWmF3aW5pxYIgTmlrdC53YXY= Jul 03 15:11:03 volumio volumio[872]: info: Exploding uri upnp/http://192.168.1.122:1843/ContentDirectory/71e3052c-d7cc-f58b-3e9d-ed0a0ef49141/control.xml@L3Zhci9tZWRpYS9zZGExLWF0YS1NVEZEREFWMjU2VEJOLTFBL011emEvRWxlbmkgLSBaxYJvdGUgUHJ6ZWJvamUgKDE5OTUpIChDRCxDb21waWxhdGlvbikgKFdBVkUpIChNckA3MykvQ2QvMDUuRWxlbmkgLSBUdSBOaWUgWmF3aW5pxYIgTmlrdC53YXY= in service upnp_browser Jul 03 15:11:03 volumio volumio[872]: info: Adding Item to queue: upnp/http://192.168.1.122:1843/ContentDirectory/71e3052c-d7cc-f58b-3e9d-ed0a0ef49141/control.xml@L3Zhci9tZWRpYS9zZGExLWF0YS1NVEZEREFWMjU2VEJOLTFBL011emEvRWxlbmkgLSBaxYJvdGUgUHJ6ZWJvamUgKDE5OTUpIChDRCxDb21waWxhdGlvbikgKFdBVkUpIChNckA3MykvQ2QvMDYuRWxlbmkgLSBacm9iacSZIFRlbiBCxYLEhWQud2F2 Jul 03 15:11:03 volumio volumio[872]: info: Exploding uri upnp/http://192.168.1.122:1843/ContentDirectory/71e3052c-d7cc-f58b-3e9d-ed0a0ef49141/control.xml@L3Zhci9tZWRpYS9zZGExLWF0YS1NVEZEREFWMjU2VEJOLTFBL011emEvRWxlbmkgLSBaxYJvdGUgUHJ6ZWJvamUgKDE5OTUpIChDRCxDb21waWxhdGlvbikgKFdBVkUpIChNckA3MykvQ2QvMDYuRWxlbmkgLSBacm9iacSZIFRlbiBCxYLEhWQud2F2 in service upnp_browser Jul 03 15:11:03 volumio volumio[872]: info: Adding Item to queue: upnp/http://192.168.1.122:1843/ContentDirectory/71e3052c-d7cc-f58b-3e9d-ed0a0ef49141/control.xml@L3Zhci9tZWRpYS9zZGExLWF0YS1NVEZEREFWMjU2VEJOLTFBL011emEvRWxlbmkgLSBaxYJvdGUgUHJ6ZWJvamUgKDE5OTUpIChDRCxDb21waWxhdGlvbikgKFdBVkUpIChNckA3MykvQ2QvMDcuRWxlbmkgLSBXYWthY3lqbnkgTWHFgnkgRmxpcnQud2F2 Jul 03 15:11:03 volumio volumio[872]: info: Exploding uri upnp/http://192.168.1.122:1843/ContentDirectory/71e3052c-d7cc-f58b-3e9d-ed0a0ef49141/control.xml@L3Zhci9tZWRpYS9zZGExLWF0YS1NVEZEREFWMjU2VEJOLTFBL011emEvRWxlbmkgLSBaxYJvdGUgUHJ6ZWJvamUgKDE5OTUpIChDRCxDb21waWxhdGlvbikgKFdBVkUpIChNckA3MykvQ2QvMDcuRWxlbmkgLSBXYWthY3lqbnkgTWHFgnkgRmxpcnQud2F2 in service upnp_browser Jul 03 15:11:03 volumio volumio[872]: info: Adding Item to queue: upnp/http://192.168.1.122:1843/ContentDirectory/71e3052c-d7cc-f58b-3e9d-ed0a0ef49141/control.xml@L3Zhci9tZWRpYS9zZGExLWF0YS1NVEZEREFWMjU2VEJOLTFBL011emEvRWxlbmkgLSBaxYJvdGUgUHJ6ZWJvamUgKDE5OTUpIChDRCxDb21waWxhdGlvbikgKFdBVkUpIChNckA3MykvQ2QvMDguRWxlbmkgLSBUeWx1IEplc3QgQ2jFgm9wY8OzdyBOYSDFmndpZWNpZS53YXY= Jul 03 15:11:03 volumio volumio[872]: info: Exploding uri upnp/http://192.168.1.122:1843/ContentDirectory/71e3052c-d7cc-f58b-3e9d-ed0a0ef49141/control.xml@L3Zhci9tZWRpYS9zZGExLWF0YS1NVEZEREFWMjU2VEJOLTFBL011emEvRWxlbmkgLSBaxYJvdGUgUHJ6ZWJvamUgKDE5OTUpIChDRCxDb21waWxhdGlvbikgKFdBVkUpIChNckA3MykvQ2QvMDguRWxlbmkgLSBUeWx1IEplc3QgQ2jFgm9wY8OzdyBOYSDFmndpZWNpZS53YXY= in service upnp_browser Jul 03 15:11:03 volumio volumio[872]: info: Adding Item to queue: upnp/http://192.168.1.122:1843/ContentDirectory/71e3052c-d7cc-f58b-3e9d-ed0a0ef49141/control.xml@L3Zhci9tZWRpYS9zZGExLWF0YS1NVEZEREFWMjU2VEJOLTFBL011emEvRWxlbmkgLSBaxYJvdGUgUHJ6ZWJvamUgKDE5OTUpIChDRCxDb21waWxhdGlvbikgKFdBVkUpIChNckA3MykvQ2QvMDkuRWxlbmkgLSBJbWllbmlhIFR3ZWdvIE5pZSBDaGPEmSBabmHEhy53YXY= Jul 03 15:11:03 volumio volumio[872]: info: Exploding uri upnp/http://192.168.1.122:1843/ContentDirectory/71e3052c-d7cc-f58b-3e9d-ed0a0ef49141/control.xml@L3Zhci9tZWRpYS9zZGExLWF0YS1NVEZEREFWMjU2VEJOLTFBL011emEvRWxlbmkgLSBaxYJvdGUgUHJ6ZWJvamUgKDE5OTUpIChDRCxDb21waWxhdGlvbikgKFdBVkUpIChNckA3MykvQ2QvMDkuRWxlbmkgLSBJbWllbmlhIFR3ZWdvIE5pZSBDaGPEmSBabmHEhy53YXY= in service upnp_browser Jul 03 15:11:03 volumio volumio[872]: info: Adding Item to queue: upnp/http://192.168.1.122:1843/ContentDirectory/71e3052c-d7cc-f58b-3e9d-ed0a0ef49141/control.xml@L3Zhci9tZWRpYS9zZGExLWF0YS1NVEZEREFWMjU2VEJOLTFBL011emEvRWxlbmkgLSBaxYJvdGUgUHJ6ZWJvamUgKDE5OTUpIChDRCxDb21waWxhdGlvbikgKFdBVkUpIChNckA3MykvQ2QvMTAuRWxlbmkgLSBEbGEgQ2llYmllIEplc3RlbSBKYS53YXY= Jul 03 15:11:03 volumio volumio[872]: info: Exploding uri upnp/http://192.168.1.122:1843/ContentDirectory/71e3052c-d7cc-f58b-3e9d-ed0a0ef49141/control.xml@L3Zhci9tZWRpYS9zZGExLWF0YS1NVEZEREFWMjU2VEJOLTFBL011emEvRWxlbmkgLSBaxYJvdGUgUHJ6ZWJvamUgKDE5OTUpIChDRCxDb21waWxhdGlvbikgKFdBVkUpIChNckA3MykvQ2QvMTAuRWxlbmkgLSBEbGEgQ2llYmllIEplc3RlbSBKYS53YXY= in service upnp_browser Jul 03 15:11:03 volumio volumio[872]: info: Adding Item to queue: upnp/http://192.168.1.122:1843/ContentDirectory/71e3052c-d7cc-f58b-3e9d-ed0a0ef49141/control.xml@L3Zhci9tZWRpYS9zZGExLWF0YS1NVEZEREFWMjU2VEJOLTFBL011emEvRWxlbmkgLSBaxYJvdGUgUHJ6ZWJvamUgKDE5OTUpIChDRCxDb21waWxhdGlvbikgKFdBVkUpIChNckA3MykvQ2QvMTEuRWxlbmkgLSBOYSBXaWVsa8SFIE1pxYJvxZvEhy53YXY= Jul 03 15:11:03 volumio volumio[872]: info: Exploding uri upnp/http://192.168.1.122:1843/ContentDirectory/71e3052c-d7cc-f58b-3e9d-ed0a0ef49141/control.xml@L3Zhci9tZWRpYS9zZGExLWF0YS1NVEZEREFWMjU2VEJOLTFBL011emEvRWxlbmkgLSBaxYJvdGUgUHJ6ZWJvamUgKDE5OTUpIChDRCxDb21waWxhdGlvbikgKFdBVkUpIChNckA3MykvQ2QvMTEuRWxlbmkgLSBOYSBXaWVsa8SFIE1pxYJvxZvEhy53YXY= in service upnp_browser Jul 03 15:11:03 volumio volumio[872]: info: Adding Item to queue: upnp/http://192.168.1.122:1843/ContentDirectory/71e3052c-d7cc-f58b-3e9d-ed0a0ef49141/control.xml@L3Zhci9tZWRpYS9zZGExLWF0YS1NVEZEREFWMjU2VEJOLTFBL011emEvRWxlbmkgLSBaxYJvdGUgUHJ6ZWJvamUgKDE5OTUpIChDRCxDb21waWxhdGlvbikgKFdBVkUpIChNckA3MykvQ2QvMTIuRWxlbmkgLSBUcm9zemVjemvEmSBaaWVtaSwgVHJvc3plY3prxJkgU8WCb8WEY2Eud2F2 Jul 03 15:11:03 volumio volumio[872]: info: Exploding uri upnp/http://192.168.1.122:1843/ContentDirectory/71e3052c-d7cc-f58b-3e9d-ed0a0ef49141/control.xml@L3Zhci9tZWRpYS9zZGExLWF0YS1NVEZEREFWMjU2VEJOLTFBL011emEvRWxlbmkgLSBaxYJvdGUgUHJ6ZWJvamUgKDE5OTUpIChDRCxDb21waWxhdGlvbikgKFdBVkUpIChNckA3MykvQ2QvMTIuRWxlbmkgLSBUcm9zemVjemvEmSBaaWVtaSwgVHJvc3plY3prxJkgU8WCb8WEY2Eud2F2 in service upnp_browser Jul 03 15:11:03 volumio volumio[872]: info: Adding Item to queue: upnp/http://192.168.1.122:1843/ContentDirectory/71e3052c-d7cc-f58b-3e9d-ed0a0ef49141/control.xml@L3Zhci9tZWRpYS9zZGExLWF0YS1NVEZEREFWMjU2VEJOLTFBL011emEvRWxlbmkgLSBaxYJvdGUgUHJ6ZWJvamUgKDE5OTUpIChDRCxDb21waWxhdGlvbikgKFdBVkUpIChNckA3MykvQ2QvMTMuRWxlbmkgLSBOaWUgTXnFm2wsIENvIELEmWR6aWUgSnV0cm8ud2F2 Jul 03 15:11:03 volumio volumio[872]: info: Exploding uri upnp/http://192.168.1.122:1843/ContentDirectory/71e3052c-d7cc-f58b-3e9d-ed0a0ef49141/control.xml@L3Zhci9tZWRpYS9zZGExLWF0YS1NVEZEREFWMjU2VEJOLTFBL011emEvRWxlbmkgLSBaxYJvdGUgUHJ6ZWJvamUgKDE5OTUpIChDRCxDb21waWxhdGlvbikgKFdBVkUpIChNckA3MykvQ2QvMTMuRWxlbmkgLSBOaWUgTXnFm2wsIENvIELEmWR6aWUgSnV0cm8ud2F2 in service upnp_browser Jul 03 15:11:03 volumio volumio[872]: info: Adding Item to queue: upnp/http://192.168.1.122:1843/ContentDirectory/71e3052c-d7cc-f58b-3e9d-ed0a0ef49141/control.xml@L3Zhci9tZWRpYS9zZGExLWF0YS1NVEZEREFWMjU2VEJOLTFBL011emEvRWxlbmkgLSBaxYJvdGUgUHJ6ZWJvamUgKDE5OTUpIChDRCxDb21waWxhdGlvbikgKFdBVkUpIChNckA3MykvQ2QvMTQuRWxlbmkgLSBSb21hbnMgWmEgR3Jvc3oud2F2 Jul 03 15:11:03 volumio volumio[872]: info: Exploding uri upnp/http://192.168.1.122:1843/ContentDirectory/71e3052c-d7cc-f58b-3e9d-ed0a0ef49141/control.xml@L3Zhci9tZWRpYS9zZGExLWF0YS1NVEZEREFWMjU2VEJOLTFBL011emEvRWxlbmkgLSBaxYJvdGUgUHJ6ZWJvamUgKDE5OTUpIChDRCxDb21waWxhdGlvbikgKFdBVkUpIChNckA3MykvQ2QvMTQuRWxlbmkgLSBSb21hbnMgWmEgR3Jvc3oud2F2 in service upnp_browser Jul 03 15:11:03 volumio volumio[872]: info: Adding Item to queue: upnp/http://192.168.1.122:1843/ContentDirectory/71e3052c-d7cc-f58b-3e9d-ed0a0ef49141/control.xml@L3Zhci9tZWRpYS9zZGExLWF0YS1NVEZEREFWMjU2VEJOLTFBL011emEvRWxlbmkgLSBaxYJvdGUgUHJ6ZWJvamUgKDE5OTUpIChDRCxDb21waWxhdGlvbikgKFdBVkUpIChNckA3MykvQ2QvMTUuRWxlbmkgLSBCYXdteSBTacSZLCDFmm1pZWpteSBTacSZLndhdg== Jul 03 15:11:03 volumio volumio[872]: info: Exploding uri upnp/http://192.168.1.122:1843/ContentDirectory/71e3052c-d7cc-f58b-3e9d-ed0a0ef49141/control.xml@L3Zhci9tZWRpYS9zZGExLWF0YS1NVEZEREFWMjU2VEJOLTFBL011emEvRWxlbmkgLSBaxYJvdGUgUHJ6ZWJvamUgKDE5OTUpIChDRCxDb21waWxhdGlvbikgKFdBVkUpIChNckA3MykvQ2QvMTUuRWxlbmkgLSBCYXdteSBTacSZLCDFmm1pZWpteSBTacSZLndhdg== in service upnp_browser Jul 03 15:11:03 volumio volumio[872]: info: CoreCommandRouter::volumioPushQueue Jul 03 15:11:03 volumio volumio[872]: info: CorePlayQueue::saveQueue Jul 03 15:11:03 volumio volumio[872]: info: CoreStateMachine::updateTrackBlock Jul 03 15:11:03 volumio volumio[872]: info: CorePlayQueue::getTrackBlock Jul 03 15:11:03 volumio volumio[872]: info: CoreCommandRouter::volumioPlay Jul 03 15:11:03 volumio volumio[872]: info: CoreStateMachine::play index 14 Jul 03 15:11:03 volumio volumio[872]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 03 15:11:03 volumio volumio[872]: info: CoreStateMachine::addQueueItems Jul 03 15:11:03 volumio volumio[872]: info: CorePlayQueue::addQueueItems Jul 03 15:11:03 volumio volumio[872]: info: Preload queue cleared Jul 03 15:11:03 volumio volumio[872]: info: Adding Item to queue: upnp/http://192.168.1.122:1843/ContentDirectory/71e3052c-d7cc-f58b-3e9d-ed0a0ef49141/control.xml@L3Zhci9tZWRpYS9zZGExLWF0YS1NVEZEREFWMjU2VEJOLTFBL011emEvRWxlbmkgLSBaxYJvdGUgUHJ6ZWJvamUgKDE5OTUpIChDRCxDb21waWxhdGlvbikgKFdBVkUpIChNckA3MykvQ2QvMTYuRWxlbmkgLSBNw7NqIE1hxYJ5IMWad2lhdC53YXY= Jul 03 15:11:03 volumio volumio[872]: info: Exploding uri upnp/http://192.168.1.122:1843/ContentDirectory/71e3052c-d7cc-f58b-3e9d-ed0a0ef49141/control.xml@L3Zhci9tZWRpYS9zZGExLWF0YS1NVEZEREFWMjU2VEJOLTFBL011emEvRWxlbmkgLSBaxYJvdGUgUHJ6ZWJvamUgKDE5OTUpIChDRCxDb21waWxhdGlvbikgKFdBVkUpIChNckA3MykvQ2QvMTYuRWxlbmkgLSBNw7NqIE1hxYJ5IMWad2lhdC53YXY= in service upnp_browser Jul 03 15:11:03 volumio volumio[872]: info: CoreStateMachine::stop Jul 03 15:11:03 volumio volumio[872]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 03 15:11:03 volumio volumio[872]: info: CoreStateMachine::play index undefined Jul 03 15:11:03 volumio volumio[872]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 03 15:11:03 volumio volumio[872]: info: CorePlayQueue::getTrack 14 Jul 03 15:11:03 volumio volumio[872]: info: CoreStateMachine::startPlaybackTimer Jul 03 15:11:03 volumio volumio[872]: info: CorePlayQueue::getTrack 14 Jul 03 15:11:03 volumio volumio[872]: info: [1751548263526] ControllerUPNPBrowser::clearAddPlayTrack Jul 03 15:11:03 volumio volumio[872]: verbose: ControllerMpd::sendMpdCommand stop Jul 03 15:11:03 volumio volumio[872]: info: sendMpdCommand stop took 6 milliseconds Jul 03 15:11:03 volumio volumio[872]: verbose: ControllerMpd::sendMpdCommand clear Jul 03 15:11:03 volumio volumio[872]: info: Jul 03 15:11:03 volumio volumio[872]: ---------------------------- MPD announces system playlist update Jul 03 15:11:03 volumio volumio[872]: info: Ignoring MPD Status Update Jul 03 15:11:03 volumio volumio[872]: info: sendMpdCommand clear took 5 milliseconds Jul 03 15:11:03 volumio volumio[872]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.122:1843/%25/2ed5de944bac9ba4e3c155667d921deb/15.Eleni%2520-%2520Bawmy%2520Si%25c4%2599%252c%2520%25c5%259amiejmy%2520Si%25c4%2599.wav" Jul 03 15:11:03 volumio volumio[872]: error: updateQueue error: null Jul 03 15:11:03 volumio volumio[872]: info: ------------------------------ 7ms Jul 03 15:11:03 volumio volumio[872]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.122:1843/%25/2ed5de944bac9ba4e3c155667d921deb/15.Eleni%2520-%2520Bawmy%2520Si%25c4%2599%252c%2520%25c5%259amiejmy%2520Si%25c4%2599.wav" Jul 03 15:11:03 volumio volumio[872]: info: Jul 03 15:11:03 volumio volumio[872]: ---------------------------- MPD announces system playlist update Jul 03 15:11:03 volumio volumio[872]: info: Ignoring MPD Status Update Jul 03 15:11:03 volumio volumio[872]: info: sendMpdCommand add "http://192.168.1.122:1843/%25/2ed5de944bac9ba4e3c155667d921deb/15.Eleni%2520-%2520Bawmy%2520Si%25c4%2599%252c%2520%25c5%259amiejmy%2520Si%25c4%2599.wav" took 1 milliseconds Jul 03 15:11:03 volumio volumio[872]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 03 15:11:03 volumio volumio[872]: verbose: ControllerMpd::sendMpdCommand play Jul 03 15:11:03 volumio volumio[872]: info: ------------------------------ 4ms Jul 03 15:11:03 volumio volumio[872]: info: sendMpdCommand play took 2 milliseconds Jul 03 15:11:03 volumio volumio[872]: info: CoreCommandRouter::volumioPushQueue Jul 03 15:11:03 volumio volumio[872]: info: CorePlayQueue::saveQueue Jul 03 15:11:03 volumio volumio[872]: info: CoreStateMachine::updateTrackBlock Jul 03 15:11:03 volumio volumio[872]: info: CorePlayQueue::getTrackBlock Jul 03 15:11:03 volumio volumio[872]: info: Jul 03 15:11:03 volumio volumio[872]: ---------------------------- MPD announces state update: player Jul 03 15:11:03 volumio volumio[872]: info: ControllerMpd::getState Jul 03 15:11:03 volumio volumio[872]: verbose: ControllerMpd::sendMpdCommand status Jul 03 15:11:03 volumio volumio[872]: info: Jul 03 15:11:03 volumio volumio[872]: ---------------------------- MPD announces state update: player Jul 03 15:11:03 volumio volumio[872]: info: sendMpdCommand status took 18 milliseconds Jul 03 15:11:03 volumio kernel: bcm2835-i2s fe203000.i2s: I2S SYNC error! Jul 03 15:11:03 volumio volumio[872]: info: ControllerMpd::getState Jul 03 15:11:03 volumio volumio[872]: verbose: ControllerMpd::sendMpdCommand status Jul 03 15:11:03 volumio volumio[872]: verbose: ControllerMpd::parseState Jul 03 15:11:03 volumio volumio[872]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 03 15:11:03 volumio volumio[872]: info: sendMpdCommand status took 6 milliseconds Jul 03 15:11:03 volumio volumio[872]: verbose: ControllerMpd::parseState Jul 03 15:11:03 volumio volumio[872]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 03 15:11:03 volumio volumio[872]: info: sendMpdCommand playlistinfo took 6 milliseconds Jul 03 15:11:03 volumio volumio[872]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 03 15:11:03 volumio volumio[872]: verbose: ControllerMpd::parseTrackInfo Jul 03 15:11:03 volumio volumio[872]: info: ControllerMpd::pushState Jul 03 15:11:03 volumio volumio[872]: info: CoreCommandRouter::servicePushState Jul 03 15:11:03 volumio volumio[872]: info: CorePlayQueue::getTrack 14 Jul 03 15:11:03 volumio volumio[872]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":194,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"15.Eleni%2520-%2520Bawmy%2520Si%25c4%2599%252c%2520%25c5%259amiejmy%2520Si%25c4%2599.wav","artist":null,"album":null,"uri":"http://192.168.1.122:1843/%25/2ed5de944bac9ba4e3c155667d921deb/15.Eleni%2520-%2520Bawmy%2520Si%25c4%2599%252c%2520%25c5%259amiejmy%2520Si%25c4%2599.wav","trackType":"wav"} Jul 03 15:11:03 volumio volumio[872]: verbose: CURRENT POSITION 14 Jul 03 15:11:03 volumio volumio[872]: info: CoreStateMachine::syncState stateService play Jul 03 15:11:03 volumio volumio[872]: info: CoreStateMachine::syncState currentStatus stop Jul 03 15:11:03 volumio volumio[872]: info: ControllerMpd::pushState Jul 03 15:11:03 volumio volumio[872]: info: CoreCommandRouter::servicePushState Jul 03 15:11:03 volumio volumio[872]: info: CorePlayQueue::getTrack 14 Jul 03 15:11:03 volumio volumio[872]: verbose: STATE SERVICE {"status":"play","position":0,"seek":709,"duration":194,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"15.Eleni%2520-%2520Bawmy%2520Si%25c4%2599%252c%2520%25c5%259amiejmy%2520Si%25c4%2599.wav","artist":null,"album":null,"uri":"http://192.168.1.122:1843/%25/2ed5de944bac9ba4e3c155667d921deb/15.Eleni%2520-%2520Bawmy%2520Si%25c4%2599%252c%2520%25c5%259amiejmy%2520Si%25c4%2599.wav","trackType":"wav"} Jul 03 15:11:03 volumio volumio[872]: verbose: CURRENT POSITION 14 Jul 03 15:11:03 volumio volumio[872]: info: CoreStateMachine::syncState stateService play Jul 03 15:11:03 volumio volumio[872]: info: CoreStateMachine::syncState currentStatus play Jul 03 15:11:03 volumio volumio[872]: info: Received an update from plugin. extracting info from payload Jul 03 15:11:03 volumio volumio[872]: info: CoreStateMachine::pushState Jul 03 15:11:03 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 03 15:11:03 volumio volumio[872]: info: CoreCommandRouter::volumioPushState Jul 03 15:11:03 volumio volumio[872]: info: CoreStateMachine::pushState Jul 03 15:11:03 volumio volumio[872]: info: CoreCommandRouter::volumioPushState Jul 03 15:11:03 volumio volumio[872]: info: ------------------------------ 51ms Jul 03 15:11:03 volumio volumio[872]: info: ------------------------------ 46ms Jul 03 15:11:03 volumio volumio[872]: info: touch_display: Setting screensaver timeout to 0 seconds. Jul 03 15:11:06 volumio volumio[872]: info: CoreCommandRouter::volumioGetState Jul 03 15:11:06 volumio volumio[872]: info: Listing playlists Jul 03 15:11:06 volumio volumio[872]: info: Listing playlists Jul 03 15:11:16 volumio volumio[872]: info: CoreCommandRouter::volumioGetState Jul 03 15:11:24 volumio volumio[872]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 8 Jul 03 15:11:24 volumio volumio[872]: info: CoreCommandRouter::volumioGetState Jul 03 15:11:26 volumio volumio[872]: info: CoreCommandRouter::volumioGetState Jul 03 15:11:26 volumio volumio[872]: info: Listing playlists Jul 03 15:11:26 volumio volumio[872]: info: Listing playlists Jul 03 15:11:36 volumio volumio[872]: info: CoreCommandRouter::volumioGetState Jul 03 15:11:37 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 03 15:11:37 volumio volumio[872]: info: Preload queue cleared Jul 03 15:11:41 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 03 15:11:41 volumio volumio[872]: info: Preload queue cleared Jul 03 15:11:42 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 03 15:11:42 volumio volumio[872]: info: Preload queue cleared Jul 03 15:11:43 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 03 15:11:43 volumio volumio[872]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 03 15:11:43 volumio volumio[872]: TypeError: Cannot read property 'length' of undefined Jul 03 15:11:43 volumio volumio[872]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Jul 03 15:11:43 volumio volumio[872]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Jul 03 15:11:43 volumio volumio[872]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Jul 03 15:11:43 volumio volumio[872]: at Parser.emit (events.js:315:20) Jul 03 15:11:43 volumio volumio[872]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Jul 03 15:11:43 volumio volumio[872]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Jul 03 15:11:43 volumio volumio[872]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Jul 03 15:11:43 volumio volumio[872]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Jul 03 15:11:43 volumio volumio[872]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Jul 03 15:11:43 volumio volumio[872]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Jul 03 15:11:43 volumio volumio[872]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Jul 03 15:11:43 volumio volumio[872]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Jul 03 15:11:43 volumio volumio[872]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Jul 03 15:11:43 volumio volumio[872]: at IncomingMessage.emit (events.js:327:22) Jul 03 15:11:43 volumio volumio[872]: at endReadableNT (internal/streams/readable.js:1327:12) Jul 03 15:11:43 volumio volumio[872]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Jul 03 15:11:43 volumio volumio[872]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 03 15:11:43 volumio sudo[2427]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-03 15:10 Jul 03 15:11:43 volumio sudo[2427]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="811ff8312d42f9da0260be1d353081a805678820" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 02 Jul 2025 04:07:25 PM CEST" VOLUMIO_VERSION="3.819" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3b9115b1943141a9d7186aa434f8f3c2"