-- Logs begin at Sun 2025-02-16 06:16:28 UTC, end at Sun 2025-02-16 13:11:35 UTC. -- Feb 16 13:10:03 volumio sshd[7865]: Accepted password for volumio from 192.168.1.137 port 34454 ssh2 Feb 16 13:10:03 volumio sshd[7865]: pam_unix(sshd:session): session opened for user volumio by (uid=0) Feb 16 13:10:03 volumio systemd[1]: Created slice User Slice of UID 1000. Feb 16 13:10:03 volumio systemd[1]: Starting User Runtime Directory /run/user/1000... Feb 16 13:10:03 volumio systemd-logind[398]: New session c1 of user volumio. Feb 16 13:10:03 volumio systemd[1]: Started User Runtime Directory /run/user/1000. Feb 16 13:10:03 volumio systemd[1]: Starting User Manager for UID 1000... Feb 16 13:10:03 volumio systemd[7873]: pam_unix(systemd-user:session): session opened for user volumio by (uid=0) Feb 16 13:10:03 volumio systemd[7873]: Failed to create timezone change event source: Permission denied Feb 16 13:10:03 volumio systemd[7873]: Reached target Paths. Feb 16 13:10:03 volumio systemd[7873]: mpd.socket: Failed to create listening socket (0.0.0.0:6600): Address already in use Feb 16 13:10:03 volumio systemd[7873]: mpd.socket: Failed to listen on sockets: Address already in use Feb 16 13:10:03 volumio systemd[7873]: mpd.socket: Failed with result 'resources'. Feb 16 13:10:03 volumio systemd[7873]: Failed to listen on mpd.socket. Feb 16 13:10:03 volumio systemd[7873]: Listening on GnuPG network certificate management daemon. Feb 16 13:10:03 volumio systemd[7873]: Listening on GnuPG cryptographic agent and passphrase cache (access for web browsers). Feb 16 13:10:03 volumio systemd[7873]: Listening on GnuPG cryptographic agent and passphrase cache (restricted). Feb 16 13:10:03 volumio systemd[7873]: Listening on GnuPG cryptographic agent (ssh-agent emulation). Feb 16 13:10:03 volumio systemd[7873]: Reached target Timers. Feb 16 13:10:03 volumio systemd[7873]: Listening on GnuPG cryptographic agent and passphrase cache. Feb 16 13:10:03 volumio systemd[7873]: Starting D-Bus User Message Bus Socket. Feb 16 13:10:03 volumio systemd[7873]: Listening on Sound System. Feb 16 13:10:03 volumio systemd[7873]: Listening on D-Bus User Message Bus Socket. Feb 16 13:10:03 volumio systemd[7873]: Reached target Sockets. Feb 16 13:10:03 volumio systemd[7873]: Reached target Basic System. Feb 16 13:10:03 volumio systemd[1]: Started User Manager for UID 1000. Feb 16 13:10:03 volumio systemd[7873]: Starting Music Player Daemon... Feb 16 13:10:03 volumio systemd[1]: Started Session c1 of user volumio. Feb 16 13:10:05 volumio mpd[7886]: Feb 16 13:10 : exception: Failed to bind to '0.0.0.0:6600' Feb 16 13:10:05 volumio mpd[7886]: Feb 16 13:10 : exception: nested: Failed to bind socket: Address already in use Feb 16 13:10:05 volumio systemd[7873]: mpd.service: Main process exited, code=exited, status=1/FAILURE Feb 16 13:10:05 volumio systemd[7873]: mpd.service: Failed with result 'exit-code'. Feb 16 13:10:05 volumio systemd[7873]: Failed to start Music Player Daemon. Feb 16 13:10:05 volumio systemd[7873]: Reached target Default. Feb 16 13:10:05 volumio systemd[7873]: Startup finished in 1.544s. Feb 16 13:10:05 volumio sshd[7865]: pam_unix(sshd:session): session closed for user volumio Feb 16 13:10:05 volumio systemd-logind[398]: Session c1 logged out. Waiting for processes to exit. Feb 16 13:10:05 volumio systemd[1]: session-c1.scope: Succeeded. Feb 16 13:10:05 volumio systemd-logind[398]: Removed session c1. Feb 16 13:10:06 volumio kernel: hdmitx: system: irq 80000004 Feb 16 13:10:07 volumio kernel: hdmitx: system: irq 80000002 Feb 16 13:10:10 volumio sudo[7904]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 16 13:10:10 volumio sudo[7904]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 13:10:10 volumio sudo[7904]: pam_unix(sudo:session): session closed for user root Feb 16 13:10:10 volumio sudo[7908]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 16 13:10:10 volumio sudo[7908]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 13:10:10 volumio sudo[7908]: pam_unix(sudo:session): session closed for user root Feb 16 13:10:10 volumio volumio[770]: verbose: New Socket.io Connection to 192.168.1.11 from 192.168.1.137 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Mobile Safari/537.36 Total Clients: 6 Feb 16 13:10:10 volumio volumio[770]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 16 13:10:10 volumio volumio[770]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 16 13:10:10 volumio volumio[770]: info: CoreCommandRouter::volumioGetVisibleSources Feb 16 13:10:10 volumio volumio[770]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 16 13:10:10 volumio volumio[770]: info: CoreCommandRouter::volumioGetState Feb 16 13:10:10 volumio volumio[770]: info: CorePlayQueue::getTrack 3 Feb 16 13:10:10 volumio volumio[770]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 16 13:10:10 volumio volumio[770]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 16 13:10:10 volumio volumio[770]: info: Received Get System Info Feb 16 13:10:10 volumio volumio[770]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 16 13:10:10 volumio volumio[770]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 16 13:10:10 volumio volumio[770]: info: Discovery: Getting this device information Feb 16 13:10:10 volumio volumio[770]: info: CoreCommandRouter::volumioGetState Feb 16 13:10:10 volumio volumio[770]: info: CorePlayQueue::getTrack 3 Feb 16 13:10:10 volumio volumio[770]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 16 13:10:10 volumio volumio[770]: info: CoreCommandRouter::volumioGetState Feb 16 13:10:10 volumio volumio[770]: info: CorePlayQueue::getTrack 3 Feb 16 13:10:10 volumio volumio[770]: info: Listing playlists Feb 16 13:10:10 volumio volumio[770]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 16 13:10:10 volumio volumio[770]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 16 13:10:10 volumio volumio[770]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 16 13:10:11 volumio kernel: hdmitx: system: irq 80000004 Feb 16 13:10:12 volumio volumio[770]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 16 13:10:12 volumio volumio[770]: info: Received Get System Info Feb 16 13:10:12 volumio volumio[770]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 16 13:10:12 volumio volumio[770]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 16 13:10:12 volumio volumio[770]: info: Discovery: Getting this device information Feb 16 13:10:12 volumio volumio[770]: info: CoreCommandRouter::volumioGetState Feb 16 13:10:12 volumio volumio[770]: info: CorePlayQueue::getTrack 3 Feb 16 13:10:12 volumio volumio[770]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 16 13:10:12 volumio kernel: hdmitx: system: irq 80000002 Feb 16 13:10:14 volumio volumio[770]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 16 13:10:14 volumio volumio[770]: info: Received Get System Info Feb 16 13:10:14 volumio volumio[770]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 16 13:10:14 volumio volumio[770]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 16 13:10:14 volumio volumio[770]: info: Discovery: Getting this device information Feb 16 13:10:14 volumio volumio[770]: info: CoreCommandRouter::volumioGetState Feb 16 13:10:14 volumio volumio[770]: info: CorePlayQueue::getTrack 3 Feb 16 13:10:14 volumio volumio[770]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 16 13:10:15 volumio systemd[1]: Stopping User Manager for UID 1000... Feb 16 13:10:15 volumio systemd[7873]: Stopped target Default. Feb 16 13:10:15 volumio systemd[7873]: Stopped target Basic System. Feb 16 13:10:15 volumio systemd[7873]: Stopped target Sockets. Feb 16 13:10:15 volumio systemd[7873]: gpg-agent-extra.socket: Succeeded. Feb 16 13:10:15 volumio systemd[7873]: Closed GnuPG cryptographic agent and passphrase cache (restricted). Feb 16 13:10:15 volumio systemd[7873]: gpg-agent.socket: Succeeded. Feb 16 13:10:15 volumio systemd[7873]: Closed GnuPG cryptographic agent and passphrase cache. Feb 16 13:10:15 volumio systemd[7873]: dirmngr.socket: Succeeded. Feb 16 13:10:15 volumio systemd[7873]: Closed GnuPG network certificate management daemon. Feb 16 13:10:15 volumio systemd[7873]: gpg-agent-browser.socket: Succeeded. Feb 16 13:10:15 volumio systemd[7873]: Closed GnuPG cryptographic agent and passphrase cache (access for web browsers). Feb 16 13:10:15 volumio systemd[7873]: pulseaudio.socket: Succeeded. Feb 16 13:10:15 volumio systemd[7873]: Closed Sound System. Feb 16 13:10:15 volumio systemd[7873]: Stopped target Timers. Feb 16 13:10:15 volumio systemd[7873]: gpg-agent-ssh.socket: Succeeded. Feb 16 13:10:15 volumio systemd[7873]: Closed GnuPG cryptographic agent (ssh-agent emulation). Feb 16 13:10:15 volumio systemd[7873]: dbus.socket: Succeeded. Feb 16 13:10:15 volumio systemd[7873]: Closed D-Bus User Message Bus Socket. Feb 16 13:10:15 volumio systemd[7873]: Stopped target Paths. Feb 16 13:10:15 volumio systemd[7873]: Reached target Shutdown. Feb 16 13:10:15 volumio systemd[7873]: systemd-exit.service: Succeeded. Feb 16 13:10:15 volumio systemd[7873]: Started Exit the Session. Feb 16 13:10:15 volumio systemd[7873]: Reached target Exit the Session. Feb 16 13:10:15 volumio systemd[7874]: pam_unix(systemd-user:session): session closed for user volumio Feb 16 13:10:15 volumio systemd[1]: user@1000.service: Succeeded. Feb 16 13:10:15 volumio systemd[1]: Stopped User Manager for UID 1000. Feb 16 13:10:15 volumio systemd[1]: Stopping User Runtime Directory /run/user/1000... Feb 16 13:10:15 volumio systemd[1]: run-user-1000.mount: Succeeded. Feb 16 13:10:15 volumio systemd[1]: user-runtime-dir@1000.service: Succeeded. Feb 16 13:10:15 volumio systemd[1]: Stopped User Runtime Directory /run/user/1000. Feb 16 13:10:15 volumio systemd[1]: Removed slice User Slice of UID 1000. Feb 16 13:10:20 volumio kernel: hdmitx: system: irq 80000004 Feb 16 13:10:20 volumio kernel: hdmitx: system: irq 80000002 Feb 16 13:10:27 volumio sudo[7926]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 16 13:10:27 volumio sudo[7926]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 13:10:27 volumio sudo[7926]: pam_unix(sudo:session): session closed for user root Feb 16 13:10:27 volumio sudo[7930]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 16 13:10:27 volumio sudo[7930]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 13:10:27 volumio sudo[7930]: pam_unix(sudo:session): session closed for user root Feb 16 13:10:27 volumio volumio[770]: verbose: New Socket.io Connection to 192.168.1.11 from 192.168.1.137 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Mobile Safari/537.36 Total Clients: 6 Feb 16 13:10:27 volumio volumio[770]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 16 13:10:27 volumio volumio[770]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 16 13:10:27 volumio volumio[770]: info: CoreCommandRouter::volumioGetVisibleSources Feb 16 13:10:27 volumio volumio[770]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 16 13:10:27 volumio volumio[770]: info: CoreCommandRouter::volumioGetState Feb 16 13:10:27 volumio volumio[770]: info: CorePlayQueue::getTrack 3 Feb 16 13:10:27 volumio volumio[770]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 16 13:10:27 volumio volumio[770]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 16 13:10:27 volumio volumio[770]: info: Received Get System Info Feb 16 13:10:27 volumio volumio[770]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 16 13:10:27 volumio volumio[770]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 16 13:10:27 volumio volumio[770]: info: Discovery: Getting this device information Feb 16 13:10:27 volumio volumio[770]: info: CoreCommandRouter::volumioGetState Feb 16 13:10:27 volumio volumio[770]: info: CorePlayQueue::getTrack 3 Feb 16 13:10:27 volumio volumio[770]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 16 13:10:27 volumio volumio[770]: info: CoreCommandRouter::volumioGetState Feb 16 13:10:27 volumio volumio[770]: info: CorePlayQueue::getTrack 3 Feb 16 13:10:27 volumio volumio[770]: info: Listing playlists Feb 16 13:10:27 volumio volumio[770]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 16 13:10:27 volumio volumio[770]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 16 13:10:27 volumio volumio[770]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 16 13:10:28 volumio volumio[770]: info: CoreCommandRouter::volumioGetQueue Feb 16 13:10:28 volumio volumio[770]: info: CoreStateMachine::getQueue Feb 16 13:10:28 volumio volumio[770]: info: CorePlayQueue::getQueue Feb 16 13:10:29 volumio volumio[770]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 16 13:10:29 volumio volumio[770]: info: Received Get System Info Feb 16 13:10:29 volumio volumio[770]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 16 13:10:29 volumio volumio[770]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 16 13:10:29 volumio volumio[770]: info: Discovery: Getting this device information Feb 16 13:10:29 volumio volumio[770]: info: CoreCommandRouter::volumioGetState Feb 16 13:10:29 volumio volumio[770]: info: CorePlayQueue::getTrack 3 Feb 16 13:10:29 volumio volumio[770]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 16 13:10:29 volumio volumio[770]: info: Preload queue cleared Feb 16 13:10:29 volumio volumio[770]: info: CoreCommandRouter::volumioAddQueueItems Feb 16 13:10:29 volumio volumio[770]: info: CoreStateMachine::addQueueItems Feb 16 13:10:29 volumio volumio[770]: info: CorePlayQueue::addQueueItems Feb 16 13:10:29 volumio volumio[770]: info: Preload queue cleared Feb 16 13:10:29 volumio volumio[770]: info: Adding Item to queue: webrp/0 Feb 16 13:10:29 volumio volumio[770]: info: Exploding uri webrp/0 in service radio_paradise Feb 16 13:10:29 volumio volumio[770]: info: CoreCommandRouter::volumioPushQueue Feb 16 13:10:29 volumio volumio[770]: info: CorePlayQueue::saveQueue Feb 16 13:10:29 volumio volumio[770]: info: CoreStateMachine::updateTrackBlock Feb 16 13:10:29 volumio volumio[770]: info: CorePlayQueue::getTrackBlock Feb 16 13:10:29 volumio volumio[770]: info: CoreCommandRouter::volumioPlay Feb 16 13:10:29 volumio volumio[770]: info: CoreStateMachine::play index 3 Feb 16 13:10:29 volumio volumio[770]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 16 13:10:29 volumio volumio[770]: info: CoreStateMachine::stop Feb 16 13:10:29 volumio volumio[770]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 16 13:10:29 volumio volumio[770]: info: CoreStateMachine::stPlaybackTimer Feb 16 13:10:29 volumio volumio[770]: info: CoreStateMachine::updateTrackBlock Feb 16 13:10:29 volumio volumio[770]: info: CorePlayQueue::getTrackBlock Feb 16 13:10:29 volumio volumio[770]: info: CoreStateMachine::pushState Feb 16 13:10:29 volumio volumio[770]: info: CorePlayQueue::getTrack 3 Feb 16 13:10:29 volumio volumio[770]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 13:10:29 volumio volumio[770]: info: CoreCommandRouter::volumioPushState Feb 16 13:10:29 volumio volumio[770]: info: CoreStateMachine::serviceStop Feb 16 13:10:29 volumio volumio[770]: info: CorePlayQueue::getTrack 3 Feb 16 13:10:29 volumio volumio[770]: info: CoreCommandRouter::serviceStop Feb 16 13:10:29 volumio volumio[770]: info: ControllerMpd::stop Feb 16 13:10:29 volumio volumio[770]: verbose: ControllerMpd::sendMpdCommand stop Feb 16 13:10:29 volumio volumio[770]: info: Feb 16 13:10:29 volumio volumio[770]: ---------------------------- MPD announces state update: player Feb 16 13:10:29 volumio kernel: aiu i2s playback disable Feb 16 13:10:29 volumio kernel: audio_hw_958_enable 0 Feb 16 13:10:29 volumio volumio[770]: info: sendMpdCommand stop took 52 milliseconds Feb 16 13:10:29 volumio volumio[770]: info: ControllerMpd::getState Feb 16 13:10:29 volumio volumio[770]: verbose: ControllerMpd::sendMpdCommand status Feb 16 13:10:29 volumio volumio[770]: info: CoreCommandRouter::servicePushState Feb 16 13:10:29 volumio volumio[770]: info: CoreStateMachine::pushState Feb 16 13:10:29 volumio volumio[770]: info: CorePlayQueue::getTrack 3 Feb 16 13:10:29 volumio volumio[770]: info: CoreCommandRouter::volumioPushState Feb 16 13:10:29 volumio volumio[770]: info: CorePlayQueue::getTrack 3 Feb 16 13:10:29 volumio volumio[770]: verbose: STATE SERVICE {"status":"stop","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/B0008D5HMK.jpg","uri":"https://stream.radioparadise.com/flac","name":"Stolen Away on 55th and 3rd","title":"Stolen Away on 55th and 3rd","artist":"Dave Matthews Band","album":"Stand Up","streaming":true,"disableUiControls":true,"duration":246,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Feb 16 13:10:29 volumio volumio[770]: verbose: CURRENT POSITION 3 Feb 16 13:10:29 volumio volumio[770]: info: CoreStateMachine::syncState stateService stop Feb 16 13:10:29 volumio volumio[770]: info: CoreStateMachine::syncState currentStatus stop Feb 16 13:10:29 volumio volumio[770]: info: CoreStateMachine::pushState Feb 16 13:10:29 volumio volumio[770]: info: CorePlayQueue::getTrack 3 Feb 16 13:10:29 volumio volumio[770]: info: CoreCommandRouter::volumioPushState Feb 16 13:10:29 volumio volumio[770]: info: No code Feb 16 13:10:29 volumio volumio[770]: info: CoreStateMachine::pushState Feb 16 13:10:29 volumio volumio[770]: info: CorePlayQueue::getTrack 3 Feb 16 13:10:29 volumio volumio[770]: info: CoreCommandRouter::volumioPushState Feb 16 13:10:29 volumio volumio[770]: info: CoreStateMachine::play index undefined Feb 16 13:10:29 volumio volumio[770]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 16 13:10:29 volumio volumio[770]: info: CorePlayQueue::getTrack 3 Feb 16 13:10:29 volumio volumio[770]: info: CoreStateMachine::startPlaybackTimer Feb 16 13:10:29 volumio volumio[770]: info: CorePlayQueue::getTrack 3 Feb 16 13:10:29 volumio volumio[770]: verbose: ControllerMpd::sendMpdCommand stop Feb 16 13:10:29 volumio volumio[770]: info: sendMpdCommand status took 29 milliseconds Feb 16 13:10:29 volumio volumio[770]: info: sendMpdCommand stop took 11 milliseconds Feb 16 13:10:29 volumio volumio[770]: verbose: ControllerMpd::parseState Feb 16 13:10:29 volumio volumio[770]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 16 13:10:29 volumio volumio[770]: verbose: ControllerMpd::sendMpdCommand clear Feb 16 13:10:29 volumio volumio[770]: info: touch_display: Setting screensaver timeout to 0 seconds. Feb 16 13:10:29 volumio volumio[770]: info: Feb 16 13:10:29 volumio volumio[770]: ---------------------------- MPD announces system playlist update Feb 16 13:10:29 volumio volumio[770]: info: Ignoring MPD Status Update Feb 16 13:10:29 volumio volumio[770]: info: sendMpdCommand playlistinfo took 21 milliseconds Feb 16 13:10:29 volumio volumio[770]: info: sendMpdCommand clear took 21 milliseconds Feb 16 13:10:29 volumio volumio[770]: verbose: ControllerMpd::parseTrackInfo Feb 16 13:10:29 volumio volumio[770]: verbose: ControllerMpd::sendMpdCommand consume 1 Feb 16 13:10:29 volumio volumio[770]: info: ControllerMpd::pushState Feb 16 13:10:29 volumio volumio[770]: info: CoreCommandRouter::servicePushState Feb 16 13:10:29 volumio volumio[770]: info: CoreStateMachine::pushState Feb 16 13:10:29 volumio volumio[770]: info: CorePlayQueue::getTrack 3 Feb 16 13:10:29 volumio volumio[770]: info: CoreCommandRouter::volumioPushState Feb 16 13:10:29 volumio volumio[770]: info: CorePlayQueue::getTrack 3 Feb 16 13:10:29 volumio volumio[770]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Feb 16 13:10:29 volumio volumio[770]: info: ------------------------------ 68ms Feb 16 13:10:29 volumio volumio[770]: error: updateQueue error: null Feb 16 13:10:29 volumio volumio[770]: info: ------------------------------ 18ms Feb 16 13:10:29 volumio volumio[770]: info: sendMpdCommand consume 1 took 14 milliseconds Feb 16 13:10:29 volumio volumio[770]: info: [1739711429472] [RadioParadise] set to consume mode, adding url: https://stream.radioparadise.com/flac Feb 16 13:10:29 volumio volumio[770]: verbose: ControllerMpd::sendMpdCommand add "https://stream.radioparadise.com/flac" Feb 16 13:10:29 volumio volumio[770]: info: Feb 16 13:10:29 volumio volumio[770]: ---------------------------- MPD announces system playlist update Feb 16 13:10:29 volumio volumio[770]: info: Ignoring MPD Status Update Feb 16 13:10:29 volumio volumio[770]: info: sendMpdCommand add "https://stream.radioparadise.com/flac" took 7 milliseconds Feb 16 13:10:29 volumio volumio[770]: verbose: ControllerMpd::sendMpdCommand play Feb 16 13:10:29 volumio volumio[770]: info: ------------------------------ 13ms Feb 16 13:10:29 volumio volumio[770]: info: sendMpdCommand play took 8 milliseconds Feb 16 13:10:29 volumio volumio[770]: info: [1739711429492] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=0 Feb 16 13:10:29 volumio volumio[770]: info: CoreCommandRouter::volumioGetState Feb 16 13:10:29 volumio volumio[770]: info: CorePlayQueue::getTrack 3 Feb 16 13:10:29 volumio volumio[770]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Feb 16 13:10:29 volumio volumio[770]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 16 13:10:29 volumio volumio[770]: info: Received Get System Info Feb 16 13:10:29 volumio volumio[770]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 16 13:10:29 volumio volumio[770]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 16 13:10:29 volumio volumio[770]: info: Discovery: Getting this device information Feb 16 13:10:29 volumio volumio[770]: info: CoreCommandRouter::volumioGetState Feb 16 13:10:29 volumio volumio[770]: info: CorePlayQueue::getTrack 3 Feb 16 13:10:29 volumio volumio[770]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 16 13:10:29 volumio volumio[770]: info: touch_display: Setting screensaver timeout to 0 seconds. Feb 16 13:10:29 volumio volumio[770]: info: Feb 16 13:10:29 volumio volumio[770]: ---------------------------- MPD announces state update: player Feb 16 13:10:29 volumio volumio[770]: info: ControllerMpd::getState Feb 16 13:10:29 volumio volumio[770]: verbose: ControllerMpd::sendMpdCommand status Feb 16 13:10:29 volumio kernel: [aml-i2s-dai]i2s dma ffffff80004b1000,phy addr 1573257216,mode 0,ch 2 Feb 16 13:10:29 volumio kernel: ----aml_hw_iec958_init,runtime->rate=44100,sample_rate=5-- Feb 16 13:10:29 volumio kernel: aml_set_spdif_clk rate Feb 16 13:10:29 volumio kernel: share the same clock Feb 16 13:10:29 volumio kernel: iec958 mode PCM16 Feb 16 13:10:29 volumio kernel: IEC958 16bit Feb 16 13:10:29 volumio kernel: hdmitx: audio: aout notify rate 44100 Feb 16 13:10:29 volumio kernel: hdmitx: audio: aout notify size 16 Feb 16 13:10:29 volumio kernel: 958 with i2s Feb 16 13:10:29 volumio kernel: aiu i2s playback enable Feb 16 13:10:29 volumio kernel: audio_hw_958_enable 1 Feb 16 13:10:29 volumio volumio[770]: info: Feb 16 13:10:29 volumio volumio[770]: ---------------------------- MPD announces system playlist update Feb 16 13:10:29 volumio volumio[770]: info: Ignoring MPD Status Update Feb 16 13:10:29 volumio volumio[770]: info: Feb 16 13:10:29 volumio volumio[770]: ---------------------------- MPD announces state update: player Feb 16 13:10:29 volumio volumio[770]: info: sendMpdCommand status took 34 milliseconds Feb 16 13:10:29 volumio volumio[770]: info: ControllerMpd::getState Feb 16 13:10:29 volumio volumio[770]: verbose: ControllerMpd::sendMpdCommand status Feb 16 13:10:29 volumio volumio[770]: verbose: ControllerMpd::parseState Feb 16 13:10:29 volumio volumio[770]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 16 13:10:29 volumio volumio[770]: info: ------------------------------ 4ms Feb 16 13:10:29 volumio volumio[770]: info: sendMpdCommand status took 2 milliseconds Feb 16 13:10:29 volumio volumio[770]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 16 13:10:29 volumio volumio[770]: verbose: ControllerMpd::parseState Feb 16 13:10:29 volumio volumio[770]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 16 13:10:29 volumio volumio[770]: verbose: ControllerMpd::parseTrackInfo Feb 16 13:10:29 volumio volumio[770]: info: ControllerMpd::pushState Feb 16 13:10:29 volumio volumio[770]: info: CoreCommandRouter::servicePushState Feb 16 13:10:29 volumio volumio[770]: info: CorePlayQueue::getTrack 3 Feb 16 13:10:29 volumio volumio[770]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Feb 16 13:10:29 volumio volumio[770]: info: ------------------------------ 41ms Feb 16 13:10:29 volumio volumio[770]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 16 13:10:29 volumio volumio[770]: verbose: ControllerMpd::parseTrackInfo Feb 16 13:10:29 volumio volumio[770]: info: ControllerMpd::pushState Feb 16 13:10:29 volumio volumio[770]: info: CoreCommandRouter::servicePushState Feb 16 13:10:29 volumio volumio[770]: info: CorePlayQueue::getTrack 3 Feb 16 13:10:29 volumio volumio[770]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Feb 16 13:10:29 volumio volumio[770]: info: ------------------------------ 7ms Feb 16 13:10:30 volumio volumio[770]: info: [1739711430148] [RadioParadise] received new metadata: {"time":28,"artist":"Dave Matthews Band","title":"Stolen Away on 55th and 3rd","album":"Stand Up","year":"2005","cover":"https://img.radioparadise.com/covers/l/B0008D5HMK.jpg","cover_med":"https://img.radioparadise.com/covers/m/B0008D5HMK.jpg","cover_small":"https://img.radioparadise.com/covers/s/B0008D5HMK.jpg"} Feb 16 13:10:30 volumio volumio[770]: info: CorePlayQueue::getTrack 3 Feb 16 13:10:30 volumio volumio[770]: info: CoreCommandRouter::servicePushState Feb 16 13:10:30 volumio volumio[770]: info: CorePlayQueue::getTrack 3 Feb 16 13:10:30 volumio volumio[770]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/B0008D5HMK.jpg","uri":"https://stream.radioparadise.com/flac","name":"Stolen Away on 55th and 3rd","title":"Stolen Away on 55th and 3rd","artist":"Dave Matthews Band","album":"Stand Up","streaming":true,"disableUiControls":true,"duration":33,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Feb 16 13:10:30 volumio volumio[770]: verbose: CURRENT POSITION 3 Feb 16 13:10:30 volumio volumio[770]: info: CoreStateMachine::syncState stateService play Feb 16 13:10:30 volumio volumio[770]: info: CoreStateMachine::syncState currentStatus stop Feb 16 13:10:30 volumio volumio[770]: info: [1739711430150] [RadioParadise] setting new timer with duration of 33000 seconds. Feb 16 13:10:30 volumio volumio[770]: info: CorePlayQueue::getTrack 3 Feb 16 13:10:30 volumio volumio[770]: info: CorePlayQueue::getTrack 4 Feb 16 13:10:45 volumio volumio[770]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 16 13:10:45 volumio volumio[770]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Feb 16 13:10:45 volumio volumio[770]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Feb 16 13:10:45 volumio volumio[770]: info: Received Get System Version Feb 16 13:10:45 volumio volumio[770]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 16 13:10:45 volumio volumio[770]: info: Received Get System Info Feb 16 13:10:45 volumio volumio[770]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 16 13:10:45 volumio volumio[770]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 16 13:10:45 volumio volumio[770]: info: Discovery: Getting this device information Feb 16 13:10:45 volumio volumio[770]: info: CoreCommandRouter::volumioGetState Feb 16 13:10:45 volumio volumio[770]: info: CorePlayQueue::getTrack 3 Feb 16 13:10:45 volumio volumio[770]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 16 13:10:48 volumio volumio[770]: info: CoreCommandRouter::volumioPlay Feb 16 13:10:48 volumio volumio[770]: info: CoreStateMachine::play index undefined Feb 16 13:10:48 volumio volumio[770]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 16 13:10:48 volumio volumio[770]: info: CorePlayQueue::getTrack 3 Feb 16 13:10:49 volumio volumio[770]: info: CoreCommandRouter::volumioPlay Feb 16 13:10:49 volumio volumio[770]: info: CoreStateMachine::play index undefined Feb 16 13:10:49 volumio volumio[770]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 16 13:10:49 volumio volumio[770]: info: CorePlayQueue::getTrack 3 Feb 16 13:10:56 volumio volumio[770]: info: Preload queue cleared Feb 16 13:10:56 volumio volumio[770]: info: CoreCommandRouter::volumioAddQueueItems Feb 16 13:10:56 volumio volumio[770]: info: CoreStateMachine::addQueueItems Feb 16 13:10:56 volumio volumio[770]: info: CorePlayQueue::addQueueItems Feb 16 13:10:56 volumio volumio[770]: info: Preload queue cleared Feb 16 13:10:56 volumio volumio[770]: info: Adding Item to queue: webrp/3 Feb 16 13:10:56 volumio volumio[770]: info: Exploding uri webrp/3 in service radio_paradise Feb 16 13:10:56 volumio volumio[770]: info: CoreCommandRouter::volumioPushQueue Feb 16 13:10:56 volumio volumio[770]: info: CorePlayQueue::saveQueue Feb 16 13:10:56 volumio volumio[770]: info: CoreStateMachine::updateTrackBlock Feb 16 13:10:56 volumio volumio[770]: info: CorePlayQueue::getTrackBlock Feb 16 13:10:56 volumio volumio[770]: info: CoreCommandRouter::volumioPlay Feb 16 13:10:56 volumio volumio[770]: info: CoreStateMachine::play index 4 Feb 16 13:10:56 volumio volumio[770]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 16 13:10:56 volumio volumio[770]: info: CoreStateMachine::stop Feb 16 13:10:56 volumio volumio[770]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 16 13:10:56 volumio volumio[770]: info: CoreStateMachine::stPlaybackTimer Feb 16 13:10:56 volumio volumio[770]: info: CoreStateMachine::updateTrackBlock Feb 16 13:10:56 volumio volumio[770]: info: CorePlayQueue::getTrackBlock Feb 16 13:10:56 volumio volumio[770]: info: CoreStateMachine::pushState Feb 16 13:10:56 volumio volumio[770]: info: CorePlayQueue::getTrack 3 Feb 16 13:10:56 volumio volumio[770]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 13:10:56 volumio volumio[770]: info: CoreCommandRouter::volumioPushState Feb 16 13:10:56 volumio volumio[770]: info: CoreStateMachine::serviceStop Feb 16 13:10:56 volumio volumio[770]: info: CorePlayQueue::getTrack 3 Feb 16 13:10:56 volumio volumio[770]: info: CoreCommandRouter::serviceStop Feb 16 13:10:56 volumio volumio[770]: info: ControllerMpd::stop Feb 16 13:10:56 volumio volumio[770]: verbose: ControllerMpd::sendMpdCommand stop Feb 16 13:10:56 volumio volumio[770]: info: Feb 16 13:10:56 volumio volumio[770]: ---------------------------- MPD announces state update: player Feb 16 13:10:56 volumio volumio[770]: info: sendMpdCommand stop took 84 milliseconds Feb 16 13:10:56 volumio volumio[770]: info: ControllerMpd::getState Feb 16 13:10:56 volumio volumio[770]: verbose: ControllerMpd::sendMpdCommand status Feb 16 13:10:56 volumio volumio[770]: info: CoreCommandRouter::servicePushState Feb 16 13:10:56 volumio volumio[770]: info: CoreStateMachine::pushState Feb 16 13:10:56 volumio volumio[770]: info: CorePlayQueue::getTrack 3 Feb 16 13:10:56 volumio volumio[770]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 13:10:56 volumio volumio[770]: info: CoreCommandRouter::volumioPushState Feb 16 13:10:56 volumio kernel: aiu i2s playback disable Feb 16 13:10:56 volumio kernel: audio_hw_958_enable 0 Feb 16 13:10:56 volumio volumio[770]: info: CorePlayQueue::getTrack 3 Feb 16 13:10:56 volumio volumio[770]: verbose: STATE SERVICE {"status":"stop","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/B0008D5HMK.jpg","uri":"https://stream.radioparadise.com/flac","name":"Stolen Away on 55th and 3rd","title":"Stolen Away on 55th and 3rd","artist":"Dave Matthews Band","album":"Stand Up","streaming":true,"disableUiControls":true,"duration":33,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Feb 16 13:10:56 volumio volumio[770]: verbose: CURRENT POSITION 3 Feb 16 13:10:56 volumio volumio[770]: info: CoreStateMachine::syncState stateService stop Feb 16 13:10:56 volumio volumio[770]: info: CoreStateMachine::syncState currentStatus stop Feb 16 13:10:56 volumio volumio[770]: info: CoreStateMachine::pushState Feb 16 13:10:56 volumio volumio[770]: info: CorePlayQueue::getTrack 3 Feb 16 13:10:56 volumio volumio[770]: info: CoreCommandRouter::volumioPushState Feb 16 13:10:56 volumio volumio[770]: info: No code Feb 16 13:10:56 volumio volumio[770]: info: CoreStateMachine::pushState Feb 16 13:10:56 volumio volumio[770]: info: CorePlayQueue::getTrack 3 Feb 16 13:10:56 volumio volumio[770]: info: CoreCommandRouter::volumioPushState Feb 16 13:10:56 volumio volumio[770]: info: CoreStateMachine::play index undefined Feb 16 13:10:56 volumio volumio[770]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 16 13:10:56 volumio volumio[770]: info: CorePlayQueue::getTrack 4 Feb 16 13:10:56 volumio volumio[770]: info: CoreStateMachine::startPlaybackTimer Feb 16 13:10:56 volumio volumio[770]: info: CorePlayQueue::getTrack 4 Feb 16 13:10:56 volumio volumio[770]: verbose: ControllerMpd::sendMpdCommand stop Feb 16 13:10:56 volumio volumio[770]: info: sendMpdCommand status took 19 milliseconds Feb 16 13:10:56 volumio volumio[770]: info: sendMpdCommand stop took 2 milliseconds Feb 16 13:10:56 volumio volumio[770]: verbose: ControllerMpd::parseState Feb 16 13:10:56 volumio volumio[770]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 16 13:10:56 volumio volumio[770]: verbose: ControllerMpd::sendMpdCommand clear Feb 16 13:10:56 volumio volumio[770]: info: Feb 16 13:10:56 volumio volumio[770]: ---------------------------- MPD announces system playlist update Feb 16 13:10:56 volumio volumio[770]: info: Ignoring MPD Status Update Feb 16 13:10:56 volumio volumio[770]: info: sendMpdCommand playlistinfo took 8 milliseconds Feb 16 13:10:56 volumio volumio[770]: info: sendMpdCommand clear took 8 milliseconds Feb 16 13:10:56 volumio volumio[770]: verbose: ControllerMpd::parseTrackInfo Feb 16 13:10:56 volumio volumio[770]: verbose: ControllerMpd::sendMpdCommand consume 1 Feb 16 13:10:56 volumio volumio[770]: info: ControllerMpd::pushState Feb 16 13:10:56 volumio volumio[770]: info: CoreCommandRouter::servicePushState Feb 16 13:10:56 volumio volumio[770]: info: CoreStateMachine::pushState Feb 16 13:10:56 volumio volumio[770]: info: CorePlayQueue::getTrack 4 Feb 16 13:10:56 volumio volumio[770]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 13:10:56 volumio volumio[770]: info: CoreCommandRouter::volumioPushState Feb 16 13:10:56 volumio volumio[770]: info: CorePlayQueue::getTrack 4 Feb 16 13:10:56 volumio volumio[770]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Feb 16 13:10:56 volumio volumio[770]: info: ------------------------------ 42ms Feb 16 13:10:56 volumio volumio[770]: error: updateQueue error: null Feb 16 13:10:56 volumio volumio[770]: info: ------------------------------ 12ms Feb 16 13:10:56 volumio volumio[770]: info: sendMpdCommand consume 1 took 10 milliseconds Feb 16 13:10:56 volumio volumio[770]: info: [1739711456802] [RadioParadise] set to consume mode, adding url: https://stream.radioparadise.com/world-etc-flac Feb 16 13:10:56 volumio volumio[770]: verbose: ControllerMpd::sendMpdCommand add "https://stream.radioparadise.com/world-etc-flac" Feb 16 13:10:56 volumio volumio[770]: info: Feb 16 13:10:56 volumio volumio[770]: ---------------------------- MPD announces system playlist update Feb 16 13:10:56 volumio volumio[770]: info: Ignoring MPD Status Update Feb 16 13:10:56 volumio volumio[770]: info: sendMpdCommand add "https://stream.radioparadise.com/world-etc-flac" took 6 milliseconds Feb 16 13:10:56 volumio volumio[770]: verbose: ControllerMpd::sendMpdCommand play Feb 16 13:10:56 volumio volumio[770]: info: ------------------------------ 5ms Feb 16 13:10:56 volumio volumio[770]: info: sendMpdCommand play took 3 milliseconds Feb 16 13:10:56 volumio volumio[770]: info: [1739711456813] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=3 Feb 16 13:10:57 volumio volumio[770]: info: Feb 16 13:10:57 volumio volumio[770]: ---------------------------- MPD announces state update: player Feb 16 13:10:57 volumio volumio[770]: info: ControllerMpd::getState Feb 16 13:10:57 volumio volumio[770]: verbose: ControllerMpd::sendMpdCommand status Feb 16 13:10:57 volumio kernel: [aml-i2s-dai]i2s dma ffffff80004b1000,phy addr 1573257216,mode 0,ch 2 Feb 16 13:10:57 volumio kernel: ----aml_hw_iec958_init,runtime->rate=44100,sample_rate=5-- Feb 16 13:10:57 volumio kernel: aml_set_spdif_clk rate Feb 16 13:10:57 volumio kernel: share the same clock Feb 16 13:10:57 volumio kernel: iec958 mode PCM16 Feb 16 13:10:57 volumio kernel: IEC958 16bit Feb 16 13:10:57 volumio kernel: hdmitx: audio: aout notify rate 44100 Feb 16 13:10:57 volumio kernel: hdmitx: audio: aout notify size 16 Feb 16 13:10:57 volumio kernel: 958 with i2s Feb 16 13:10:57 volumio volumio[770]: info: Feb 16 13:10:57 volumio volumio[770]: ---------------------------- MPD announces state update: player Feb 16 13:10:57 volumio kernel: aiu i2s playback enable Feb 16 13:10:57 volumio kernel: audio_hw_958_enable 1 Feb 16 13:10:57 volumio volumio[770]: info: sendMpdCommand status took 39 milliseconds Feb 16 13:10:57 volumio volumio[770]: info: ControllerMpd::getState Feb 16 13:10:57 volumio volumio[770]: verbose: ControllerMpd::sendMpdCommand status Feb 16 13:10:57 volumio volumio[770]: verbose: ControllerMpd::parseState Feb 16 13:10:57 volumio volumio[770]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 16 13:10:57 volumio volumio[770]: info: Feb 16 13:10:57 volumio volumio[770]: ---------------------------- MPD announces system playlist update Feb 16 13:10:57 volumio volumio[770]: info: Ignoring MPD Status Update Feb 16 13:10:57 volumio volumio[770]: info: sendMpdCommand status took 3 milliseconds Feb 16 13:10:57 volumio volumio[770]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 16 13:10:57 volumio volumio[770]: verbose: ControllerMpd::parseState Feb 16 13:10:57 volumio volumio[770]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 16 13:10:57 volumio volumio[770]: verbose: ControllerMpd::parseTrackInfo Feb 16 13:10:57 volumio volumio[770]: info: ControllerMpd::pushState Feb 16 13:10:57 volumio volumio[770]: info: CoreCommandRouter::servicePushState Feb 16 13:10:57 volumio volumio[770]: info: CorePlayQueue::getTrack 4 Feb 16 13:10:57 volumio volumio[770]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Feb 16 13:10:57 volumio volumio[770]: info: ------------------------------ 47ms Feb 16 13:10:57 volumio volumio[770]: info: ------------------------------ 5ms Feb 16 13:10:57 volumio volumio[770]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 16 13:10:57 volumio volumio[770]: verbose: ControllerMpd::parseTrackInfo Feb 16 13:10:57 volumio volumio[770]: info: ControllerMpd::pushState Feb 16 13:10:57 volumio volumio[770]: info: CoreCommandRouter::servicePushState Feb 16 13:10:57 volumio volumio[770]: info: CorePlayQueue::getTrack 4 Feb 16 13:10:57 volumio volumio[770]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Feb 16 13:10:57 volumio volumio[770]: info: ------------------------------ 14ms Feb 16 13:10:57 volumio volumio[770]: info: [1739711457491] [RadioParadise] received new metadata: {"time":167,"artist":"Ismaël Lô","title":"Tajabone","album":"Jammu Africa","year":"1996","cover":"https://img.radioparadise.com/covers/l/B00008NEQV.jpg","cover_med":"https://img.radioparadise.com/covers/m/B00008NEQV.jpg","cover_small":"https://img.radioparadise.com/covers/s/B00008NEQV.jpg"} Feb 16 13:10:57 volumio volumio[770]: info: CorePlayQueue::getTrack 4 Feb 16 13:10:57 volumio volumio[770]: info: CoreCommandRouter::servicePushState Feb 16 13:10:57 volumio volumio[770]: info: CorePlayQueue::getTrack 4 Feb 16 13:10:57 volumio volumio[770]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/B00008NEQV.jpg","uri":"https://stream.radioparadise.com/world-etc-flac","name":"Tajabone","title":"Tajabone","artist":"Ismaël Lô","album":"Jammu Africa","streaming":true,"disableUiControls":true,"duration":172,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Feb 16 13:10:57 volumio volumio[770]: verbose: CURRENT POSITION 4 Feb 16 13:10:57 volumio volumio[770]: info: CoreStateMachine::syncState stateService play Feb 16 13:10:57 volumio volumio[770]: info: CoreStateMachine::syncState currentStatus stop Feb 16 13:10:57 volumio volumio[770]: info: [1739711457493] [RadioParadise] setting new timer with duration of 172000 seconds. Feb 16 13:10:57 volumio volumio[770]: info: CorePlayQueue::getTrack 4 Feb 16 13:10:57 volumio volumio[770]: info: CorePlayQueue::getTrack 5 Feb 16 13:10:59 volumio volumio[770]: info: Preload queue cleared Feb 16 13:10:59 volumio volumio[770]: info: CoreCommandRouter::volumioAddQueueItems Feb 16 13:10:59 volumio volumio[770]: info: CoreStateMachine::addQueueItems Feb 16 13:10:59 volumio volumio[770]: info: CorePlayQueue::addQueueItems Feb 16 13:10:59 volumio volumio[770]: info: Preload queue cleared Feb 16 13:10:59 volumio volumio[770]: info: Adding Item to queue: webrp/1 Feb 16 13:10:59 volumio volumio[770]: info: Exploding uri webrp/1 in service radio_paradise Feb 16 13:10:59 volumio volumio[770]: info: CoreCommandRouter::volumioPushQueue Feb 16 13:10:59 volumio volumio[770]: info: CorePlayQueue::saveQueue Feb 16 13:10:59 volumio volumio[770]: info: CoreStateMachine::updateTrackBlock Feb 16 13:10:59 volumio volumio[770]: info: CorePlayQueue::getTrackBlock Feb 16 13:10:59 volumio volumio[770]: info: CoreCommandRouter::volumioPlay Feb 16 13:10:59 volumio volumio[770]: info: CoreStateMachine::play index 5 Feb 16 13:10:59 volumio volumio[770]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 16 13:10:59 volumio volumio[770]: info: CoreStateMachine::stop Feb 16 13:10:59 volumio volumio[770]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 16 13:10:59 volumio volumio[770]: info: CoreStateMachine::stPlaybackTimer Feb 16 13:10:59 volumio volumio[770]: info: CoreStateMachine::updateTrackBlock Feb 16 13:10:59 volumio volumio[770]: info: CorePlayQueue::getTrackBlock Feb 16 13:10:59 volumio volumio[770]: info: CoreStateMachine::pushState Feb 16 13:10:59 volumio volumio[770]: info: CorePlayQueue::getTrack 4 Feb 16 13:10:59 volumio volumio[770]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 13:10:59 volumio volumio[770]: info: CoreCommandRouter::volumioPushState Feb 16 13:10:59 volumio volumio[770]: info: CoreStateMachine::serviceStop Feb 16 13:10:59 volumio volumio[770]: info: CorePlayQueue::getTrack 4 Feb 16 13:10:59 volumio volumio[770]: info: CoreCommandRouter::serviceStop Feb 16 13:10:59 volumio volumio[770]: info: ControllerMpd::stop Feb 16 13:10:59 volumio volumio[770]: verbose: ControllerMpd::sendMpdCommand stop Feb 16 13:10:59 volumio volumio[770]: info: Feb 16 13:10:59 volumio volumio[770]: ---------------------------- MPD announces state update: player Feb 16 13:10:59 volumio volumio[770]: info: sendMpdCommand stop took 56 milliseconds Feb 16 13:10:59 volumio volumio[770]: info: ControllerMpd::getState Feb 16 13:10:59 volumio volumio[770]: verbose: ControllerMpd::sendMpdCommand status Feb 16 13:10:59 volumio volumio[770]: info: CoreCommandRouter::servicePushState Feb 16 13:10:59 volumio volumio[770]: info: CoreStateMachine::pushState Feb 16 13:10:59 volumio volumio[770]: info: CorePlayQueue::getTrack 4 Feb 16 13:10:59 volumio volumio[770]: info: CoreCommandRouter::volumioPushState Feb 16 13:10:59 volumio volumio[770]: info: CorePlayQueue::getTrack 4 Feb 16 13:10:59 volumio volumio[770]: verbose: STATE SERVICE {"status":"stop","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/B00008NEQV.jpg","uri":"https://stream.radioparadise.com/world-etc-flac","name":"Tajabone","title":"Tajabone","artist":"Ismaël Lô","album":"Jammu Africa","streaming":true,"disableUiControls":true,"duration":172,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Feb 16 13:10:59 volumio volumio[770]: verbose: CURRENT POSITION 4 Feb 16 13:10:59 volumio volumio[770]: info: CoreStateMachine::syncState stateService stop Feb 16 13:10:59 volumio volumio[770]: info: CoreStateMachine::syncState currentStatus stop Feb 16 13:10:59 volumio volumio[770]: info: CoreStateMachine::pushState Feb 16 13:10:59 volumio volumio[770]: info: CorePlayQueue::getTrack 4 Feb 16 13:10:59 volumio kernel: aiu i2s playback disable Feb 16 13:10:59 volumio kernel: audio_hw_958_enable 0 Feb 16 13:10:59 volumio volumio[770]: info: CoreCommandRouter::volumioPushState Feb 16 13:10:59 volumio volumio[770]: info: No code Feb 16 13:10:59 volumio volumio[770]: info: CoreStateMachine::pushState Feb 16 13:10:59 volumio volumio[770]: info: CorePlayQueue::getTrack 4 Feb 16 13:10:59 volumio volumio[770]: info: CoreCommandRouter::volumioPushState Feb 16 13:10:59 volumio volumio[770]: info: CoreStateMachine::play index undefined Feb 16 13:10:59 volumio volumio[770]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 16 13:10:59 volumio volumio[770]: info: CorePlayQueue::getTrack 5 Feb 16 13:10:59 volumio volumio[770]: info: CoreStateMachine::startPlaybackTimer Feb 16 13:10:59 volumio volumio[770]: info: CorePlayQueue::getTrack 5 Feb 16 13:10:59 volumio volumio[770]: verbose: ControllerMpd::sendMpdCommand stop Feb 16 13:10:59 volumio volumio[770]: info: sendMpdCommand status took 25 milliseconds Feb 16 13:10:59 volumio volumio[770]: info: sendMpdCommand stop took 1 milliseconds Feb 16 13:10:59 volumio volumio[770]: verbose: ControllerMpd::parseState Feb 16 13:10:59 volumio volumio[770]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 16 13:10:59 volumio volumio[770]: verbose: ControllerMpd::sendMpdCommand clear Feb 16 13:10:59 volumio volumio[770]: info: Feb 16 13:10:59 volumio volumio[770]: ---------------------------- MPD announces system playlist update Feb 16 13:10:59 volumio volumio[770]: info: Ignoring MPD Status Update Feb 16 13:10:59 volumio volumio[770]: info: sendMpdCommand playlistinfo took 8 milliseconds Feb 16 13:10:59 volumio volumio[770]: info: sendMpdCommand clear took 8 milliseconds Feb 16 13:10:59 volumio volumio[770]: verbose: ControllerMpd::parseTrackInfo Feb 16 13:10:59 volumio volumio[770]: verbose: ControllerMpd::sendMpdCommand consume 1 Feb 16 13:10:59 volumio volumio[770]: info: ControllerMpd::pushState Feb 16 13:10:59 volumio volumio[770]: info: CoreCommandRouter::servicePushState Feb 16 13:10:59 volumio volumio[770]: info: CoreStateMachine::pushState Feb 16 13:10:59 volumio volumio[770]: info: CorePlayQueue::getTrack 5 Feb 16 13:10:59 volumio volumio[770]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 13:10:59 volumio volumio[770]: info: CoreCommandRouter::volumioPushState Feb 16 13:10:59 volumio volumio[770]: info: CorePlayQueue::getTrack 5 Feb 16 13:10:59 volumio volumio[770]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Feb 16 13:10:59 volumio volumio[770]: info: ------------------------------ 47ms Feb 16 13:10:59 volumio volumio[770]: error: updateQueue error: null Feb 16 13:10:59 volumio volumio[770]: info: ------------------------------ 15ms Feb 16 13:10:59 volumio volumio[770]: info: sendMpdCommand consume 1 took 13 milliseconds Feb 16 13:10:59 volumio volumio[770]: info: [1739711459658] [RadioParadise] set to consume mode, adding url: https://stream.radioparadise.com/mellow-flac Feb 16 13:10:59 volumio volumio[770]: verbose: ControllerMpd::sendMpdCommand add "https://stream.radioparadise.com/mellow-flac" Feb 16 13:10:59 volumio volumio[770]: info: Feb 16 13:10:59 volumio volumio[770]: ---------------------------- MPD announces system playlist update Feb 16 13:10:59 volumio volumio[770]: info: Ignoring MPD Status Update Feb 16 13:10:59 volumio volumio[770]: info: sendMpdCommand add "https://stream.radioparadise.com/mellow-flac" took 6 milliseconds Feb 16 13:10:59 volumio volumio[770]: verbose: ControllerMpd::sendMpdCommand play Feb 16 13:10:59 volumio volumio[770]: info: ------------------------------ 15ms Feb 16 13:10:59 volumio volumio[770]: info: sendMpdCommand play took 6 milliseconds Feb 16 13:10:59 volumio volumio[770]: info: [1739711459680] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=1 Feb 16 13:11:00 volumio volumio[770]: info: Feb 16 13:11:00 volumio volumio[770]: ---------------------------- MPD announces state update: player Feb 16 13:11:00 volumio volumio[770]: info: ControllerMpd::getState Feb 16 13:11:00 volumio volumio[770]: verbose: ControllerMpd::sendMpdCommand status Feb 16 13:11:00 volumio kernel: [aml-i2s-dai]i2s dma ffffff80004b1000,phy addr 1573257216,mode 0,ch 2 Feb 16 13:11:00 volumio kernel: ----aml_hw_iec958_init,runtime->rate=44100,sample_rate=5-- Feb 16 13:11:00 volumio kernel: aml_set_spdif_clk rate Feb 16 13:11:00 volumio kernel: share the same clock Feb 16 13:11:00 volumio kernel: iec958 mode PCM16 Feb 16 13:11:00 volumio kernel: IEC958 16bit Feb 16 13:11:00 volumio kernel: hdmitx: audio: aout notify rate 44100 Feb 16 13:11:00 volumio kernel: hdmitx: audio: aout notify size 16 Feb 16 13:11:00 volumio volumio[770]: info: Feb 16 13:11:00 volumio volumio[770]: ---------------------------- MPD announces state update: player Feb 16 13:11:00 volumio volumio[770]: info: sendMpdCommand status took 31 milliseconds Feb 16 13:11:00 volumio volumio[770]: info: ControllerMpd::getState Feb 16 13:11:00 volumio volumio[770]: verbose: ControllerMpd::sendMpdCommand status Feb 16 13:11:00 volumio volumio[770]: verbose: ControllerMpd::parseState Feb 16 13:11:00 volumio volumio[770]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 16 13:11:00 volumio kernel: 958 with i2s Feb 16 13:11:00 volumio kernel: aiu i2s playback enable Feb 16 13:11:00 volumio kernel: audio_hw_958_enable 1 Feb 16 13:11:00 volumio volumio[770]: info: Feb 16 13:11:00 volumio volumio[770]: ---------------------------- MPD announces system playlist update Feb 16 13:11:00 volumio volumio[770]: info: Ignoring MPD Status Update Feb 16 13:11:00 volumio volumio[770]: info: sendMpdCommand status took 4 milliseconds Feb 16 13:11:00 volumio volumio[770]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 16 13:11:00 volumio volumio[770]: verbose: ControllerMpd::parseState Feb 16 13:11:00 volumio volumio[770]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 16 13:11:00 volumio volumio[770]: verbose: ControllerMpd::parseTrackInfo Feb 16 13:11:00 volumio volumio[770]: info: ControllerMpd::pushState Feb 16 13:11:00 volumio volumio[770]: info: CoreCommandRouter::servicePushState Feb 16 13:11:00 volumio volumio[770]: info: CorePlayQueue::getTrack 5 Feb 16 13:11:00 volumio volumio[770]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Feb 16 13:11:00 volumio volumio[770]: info: ------------------------------ 41ms Feb 16 13:11:00 volumio volumio[770]: info: ------------------------------ 4ms Feb 16 13:11:00 volumio volumio[770]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 16 13:11:00 volumio volumio[770]: verbose: ControllerMpd::parseTrackInfo Feb 16 13:11:00 volumio volumio[770]: info: ControllerMpd::pushState Feb 16 13:11:00 volumio volumio[770]: info: CoreCommandRouter::servicePushState Feb 16 13:11:00 volumio volumio[770]: info: CorePlayQueue::getTrack 5 Feb 16 13:11:00 volumio volumio[770]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Feb 16 13:11:00 volumio volumio[770]: info: ------------------------------ 12ms Feb 16 13:11:00 volumio volumio[770]: info: [1739711460319] [RadioParadise] received new metadata: {"time":92,"artist":"Tracy Chapman","title":"Say Hallelujah","album":"Let It Rain","year":"2002","cover":"https://img.radioparadise.com/covers/l/B00006JXYH.jpg","cover_med":"https://img.radioparadise.com/covers/m/B00006JXYH.jpg","cover_small":"https://img.radioparadise.com/covers/s/B00006JXYH.jpg"} Feb 16 13:11:00 volumio volumio[770]: info: CorePlayQueue::getTrack 5 Feb 16 13:11:00 volumio volumio[770]: info: CoreCommandRouter::servicePushState Feb 16 13:11:00 volumio volumio[770]: info: CorePlayQueue::getTrack 5 Feb 16 13:11:00 volumio volumio[770]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/B00006JXYH.jpg","uri":"https://stream.radioparadise.com/mellow-flac","name":"Say Hallelujah","title":"Say Hallelujah","artist":"Tracy Chapman","album":"Let It Rain","streaming":true,"disableUiControls":true,"duration":97,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Feb 16 13:11:00 volumio volumio[770]: verbose: CURRENT POSITION 5 Feb 16 13:11:00 volumio volumio[770]: info: CoreStateMachine::syncState stateService play Feb 16 13:11:00 volumio volumio[770]: info: CoreStateMachine::syncState currentStatus stop Feb 16 13:11:00 volumio volumio[770]: info: [1739711460323] [RadioParadise] setting new timer with duration of 97000 seconds. Feb 16 13:11:00 volumio volumio[770]: info: CorePlayQueue::getTrack 5 Feb 16 13:11:00 volumio volumio[770]: info: CorePlayQueue::getTrack 6 Feb 16 13:11:02 volumio volumio[770]: info: Preload queue cleared Feb 16 13:11:02 volumio volumio[770]: info: CoreCommandRouter::volumioAddQueueItems Feb 16 13:11:02 volumio volumio[770]: info: CoreStateMachine::addQueueItems Feb 16 13:11:02 volumio volumio[770]: info: CorePlayQueue::addQueueItems Feb 16 13:11:02 volumio volumio[770]: info: Preload queue cleared Feb 16 13:11:02 volumio volumio[770]: info: Adding Item to queue: webrp/0 Feb 16 13:11:02 volumio volumio[770]: info: Using cached record of: webrp/0 Feb 16 13:11:02 volumio volumio[770]: info: CoreCommandRouter::volumioPushQueue Feb 16 13:11:02 volumio volumio[770]: info: CorePlayQueue::saveQueue Feb 16 13:11:02 volumio volumio[770]: info: CoreStateMachine::updateTrackBlock Feb 16 13:11:02 volumio volumio[770]: info: CorePlayQueue::getTrackBlock Feb 16 13:11:02 volumio volumio[770]: info: CoreCommandRouter::volumioPlay Feb 16 13:11:02 volumio volumio[770]: info: CoreStateMachine::play index 6 Feb 16 13:11:02 volumio volumio[770]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 16 13:11:02 volumio volumio[770]: info: CoreStateMachine::stop Feb 16 13:11:02 volumio volumio[770]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 16 13:11:02 volumio volumio[770]: info: CoreStateMachine::stPlaybackTimer Feb 16 13:11:02 volumio volumio[770]: info: CoreStateMachine::updateTrackBlock Feb 16 13:11:02 volumio volumio[770]: info: CorePlayQueue::getTrackBlock Feb 16 13:11:02 volumio volumio[770]: info: CoreStateMachine::pushState Feb 16 13:11:02 volumio volumio[770]: info: CorePlayQueue::getTrack 5 Feb 16 13:11:02 volumio volumio[770]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 13:11:02 volumio volumio[770]: info: CoreCommandRouter::volumioPushState Feb 16 13:11:02 volumio volumio[770]: info: CoreStateMachine::serviceStop Feb 16 13:11:02 volumio volumio[770]: info: CorePlayQueue::getTrack 5 Feb 16 13:11:02 volumio volumio[770]: info: CoreCommandRouter::serviceStop Feb 16 13:11:02 volumio volumio[770]: info: ControllerMpd::stop Feb 16 13:11:02 volumio volumio[770]: verbose: ControllerMpd::sendMpdCommand stop Feb 16 13:11:02 volumio volumio[770]: info: Feb 16 13:11:02 volumio volumio[770]: ---------------------------- MPD announces state update: player Feb 16 13:11:02 volumio volumio[770]: info: sendMpdCommand stop took 34 milliseconds Feb 16 13:11:02 volumio volumio[770]: info: ControllerMpd::getState Feb 16 13:11:02 volumio volumio[770]: verbose: ControllerMpd::sendMpdCommand status Feb 16 13:11:02 volumio volumio[770]: info: CoreCommandRouter::servicePushState Feb 16 13:11:02 volumio volumio[770]: info: CoreStateMachine::pushState Feb 16 13:11:02 volumio volumio[770]: info: CorePlayQueue::getTrack 5 Feb 16 13:11:02 volumio volumio[770]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 13:11:02 volumio volumio[770]: info: CoreCommandRouter::volumioPushState Feb 16 13:11:02 volumio volumio[770]: info: CorePlayQueue::getTrack 5 Feb 16 13:11:02 volumio kernel: aiu i2s playback disable Feb 16 13:11:02 volumio kernel: audio_hw_958_enable 0 Feb 16 13:11:02 volumio volumio[770]: verbose: STATE SERVICE {"status":"stop","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/B00006JXYH.jpg","uri":"https://stream.radioparadise.com/mellow-flac","name":"Say Hallelujah","title":"Say Hallelujah","artist":"Tracy Chapman","album":"Let It Rain","streaming":true,"disableUiControls":true,"duration":97,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Feb 16 13:11:02 volumio volumio[770]: verbose: CURRENT POSITION 5 Feb 16 13:11:02 volumio volumio[770]: info: CoreStateMachine::syncState stateService stop Feb 16 13:11:02 volumio volumio[770]: info: CoreStateMachine::syncState currentStatus stop Feb 16 13:11:02 volumio volumio[770]: info: CoreStateMachine::pushState Feb 16 13:11:02 volumio volumio[770]: info: CorePlayQueue::getTrack 5 Feb 16 13:11:02 volumio volumio[770]: info: CoreCommandRouter::volumioPushState Feb 16 13:11:02 volumio volumio[770]: info: No code Feb 16 13:11:02 volumio volumio[770]: info: CoreStateMachine::pushState Feb 16 13:11:02 volumio volumio[770]: info: CorePlayQueue::getTrack 5 Feb 16 13:11:02 volumio volumio[770]: info: CoreCommandRouter::volumioPushState Feb 16 13:11:02 volumio volumio[770]: info: CoreStateMachine::play index undefined Feb 16 13:11:02 volumio volumio[770]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 16 13:11:02 volumio volumio[770]: info: CorePlayQueue::getTrack 6 Feb 16 13:11:02 volumio volumio[770]: info: CoreStateMachine::startPlaybackTimer Feb 16 13:11:02 volumio volumio[770]: info: CorePlayQueue::getTrack 6 Feb 16 13:11:02 volumio volumio[770]: verbose: ControllerMpd::sendMpdCommand stop Feb 16 13:11:02 volumio volumio[770]: info: sendMpdCommand status took 19 milliseconds Feb 16 13:11:02 volumio volumio[770]: info: sendMpdCommand stop took 2 milliseconds Feb 16 13:11:02 volumio volumio[770]: verbose: ControllerMpd::parseState Feb 16 13:11:02 volumio volumio[770]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 16 13:11:02 volumio volumio[770]: verbose: ControllerMpd::sendMpdCommand clear Feb 16 13:11:02 volumio volumio[770]: info: Feb 16 13:11:02 volumio volumio[770]: ---------------------------- MPD announces system playlist update Feb 16 13:11:02 volumio volumio[770]: info: Ignoring MPD Status Update Feb 16 13:11:02 volumio volumio[770]: info: sendMpdCommand playlistinfo took 11 milliseconds Feb 16 13:11:02 volumio volumio[770]: info: sendMpdCommand clear took 11 milliseconds Feb 16 13:11:02 volumio volumio[770]: verbose: ControllerMpd::parseTrackInfo Feb 16 13:11:02 volumio volumio[770]: verbose: ControllerMpd::sendMpdCommand consume 1 Feb 16 13:11:02 volumio volumio[770]: info: ControllerMpd::pushState Feb 16 13:11:02 volumio volumio[770]: info: CoreCommandRouter::servicePushState Feb 16 13:11:02 volumio volumio[770]: info: CoreStateMachine::pushState Feb 16 13:11:02 volumio volumio[770]: info: CorePlayQueue::getTrack 6 Feb 16 13:11:02 volumio volumio[770]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 13:11:02 volumio volumio[770]: info: CoreCommandRouter::volumioPushState Feb 16 13:11:02 volumio volumio[770]: info: CorePlayQueue::getTrack 6 Feb 16 13:11:02 volumio volumio[770]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Feb 16 13:11:02 volumio volumio[770]: info: ------------------------------ 44ms Feb 16 13:11:02 volumio volumio[770]: error: updateQueue error: null Feb 16 13:11:02 volumio volumio[770]: info: ------------------------------ 20ms Feb 16 13:11:02 volumio volumio[770]: info: sendMpdCommand consume 1 took 14 milliseconds Feb 16 13:11:02 volumio volumio[770]: info: [1739711462706] [RadioParadise] set to consume mode, adding url: https://stream.radioparadise.com/flac Feb 16 13:11:02 volumio volumio[770]: verbose: ControllerMpd::sendMpdCommand add "https://stream.radioparadise.com/flac" Feb 16 13:11:02 volumio volumio[770]: info: Feb 16 13:11:02 volumio volumio[770]: ---------------------------- MPD announces system playlist update Feb 16 13:11:02 volumio volumio[770]: info: Ignoring MPD Status Update Feb 16 13:11:02 volumio volumio[770]: info: sendMpdCommand add "https://stream.radioparadise.com/flac" took 6 milliseconds Feb 16 13:11:02 volumio volumio[770]: verbose: ControllerMpd::sendMpdCommand play Feb 16 13:11:02 volumio volumio[770]: info: ------------------------------ 7ms Feb 16 13:11:02 volumio volumio[770]: info: sendMpdCommand play took 6 milliseconds Feb 16 13:11:02 volumio volumio[770]: info: [1739711462723] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=0 Feb 16 13:11:03 volumio volumio[770]: info: Feb 16 13:11:03 volumio volumio[770]: ---------------------------- MPD announces state update: player Feb 16 13:11:03 volumio volumio[770]: info: ControllerMpd::getState Feb 16 13:11:03 volumio volumio[770]: verbose: ControllerMpd::sendMpdCommand status Feb 16 13:11:03 volumio kernel: [aml-i2s-dai]i2s dma ffffff80004b1000,phy addr 1573257216,mode 0,ch 2 Feb 16 13:11:03 volumio kernel: ----aml_hw_iec958_init,runtime->rate=44100,sample_rate=5-- Feb 16 13:11:03 volumio kernel: aml_set_spdif_clk rate Feb 16 13:11:03 volumio kernel: share the same clock Feb 16 13:11:03 volumio kernel: iec958 mode PCM16 Feb 16 13:11:03 volumio kernel: IEC958 16bit Feb 16 13:11:03 volumio kernel: hdmitx: audio: aout notify rate 44100 Feb 16 13:11:03 volumio kernel: hdmitx: audio: aout notify size 16 Feb 16 13:11:03 volumio kernel: 958 with i2s Feb 16 13:11:03 volumio volumio[770]: info: Feb 16 13:11:03 volumio volumio[770]: ---------------------------- MPD announces state update: player Feb 16 13:11:03 volumio volumio[770]: info: sendMpdCommand status took 35 milliseconds Feb 16 13:11:03 volumio volumio[770]: info: ControllerMpd::getState Feb 16 13:11:03 volumio volumio[770]: verbose: ControllerMpd::sendMpdCommand status Feb 16 13:11:03 volumio volumio[770]: verbose: ControllerMpd::parseState Feb 16 13:11:03 volumio volumio[770]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 16 13:11:03 volumio volumio[770]: info: Feb 16 13:11:03 volumio volumio[770]: ---------------------------- MPD announces system playlist update Feb 16 13:11:03 volumio volumio[770]: info: Ignoring MPD Status Update Feb 16 13:11:03 volumio volumio[770]: info: sendMpdCommand status took 6 milliseconds Feb 16 13:11:03 volumio volumio[770]: info: sendMpdCommand playlistinfo took 6 milliseconds Feb 16 13:11:03 volumio kernel: aiu i2s playback enable Feb 16 13:11:03 volumio kernel: audio_hw_958_enable 1 Feb 16 13:11:03 volumio volumio[770]: verbose: ControllerMpd::parseState Feb 16 13:11:03 volumio volumio[770]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 16 13:11:03 volumio volumio[770]: verbose: ControllerMpd::parseTrackInfo Feb 16 13:11:03 volumio volumio[770]: info: ControllerMpd::pushState Feb 16 13:11:03 volumio volumio[770]: info: CoreCommandRouter::servicePushState Feb 16 13:11:03 volumio volumio[770]: info: CorePlayQueue::getTrack 6 Feb 16 13:11:03 volumio volumio[770]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Feb 16 13:11:03 volumio volumio[770]: info: ------------------------------ 45ms Feb 16 13:11:03 volumio volumio[770]: info: ------------------------------ 6ms Feb 16 13:11:03 volumio volumio[770]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 16 13:11:03 volumio volumio[770]: verbose: ControllerMpd::parseTrackInfo Feb 16 13:11:03 volumio volumio[770]: info: ControllerMpd::pushState Feb 16 13:11:03 volumio volumio[770]: info: CoreCommandRouter::servicePushState Feb 16 13:11:03 volumio volumio[770]: info: CorePlayQueue::getTrack 6 Feb 16 13:11:03 volumio volumio[770]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Feb 16 13:11:03 volumio volumio[770]: info: ------------------------------ 12ms Feb 16 13:11:03 volumio volumio[770]: info: [1739711463355] [RadioParadise] received new metadata: {"time":249,"artist":"Santana","title":"Oye como va","album":"Abraxas","year":"1970","cover":"https://img.radioparadise.com/covers/l/B0000062FL.jpg","cover_med":"https://img.radioparadise.com/covers/m/B0000062FL.jpg","cover_small":"https://img.radioparadise.com/covers/s/B0000062FL.jpg"} Feb 16 13:11:03 volumio volumio[770]: info: CorePlayQueue::getTrack 6 Feb 16 13:11:03 volumio volumio[770]: info: CoreCommandRouter::servicePushState Feb 16 13:11:03 volumio volumio[770]: info: CorePlayQueue::getTrack 6 Feb 16 13:11:03 volumio volumio[770]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/B0000062FL.jpg","uri":"https://stream.radioparadise.com/flac","name":"Oye como va","title":"Oye como va","artist":"Santana","album":"Abraxas","streaming":true,"disableUiControls":true,"duration":254,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Feb 16 13:11:03 volumio volumio[770]: verbose: CURRENT POSITION 6 Feb 16 13:11:03 volumio volumio[770]: info: CoreStateMachine::syncState stateService play Feb 16 13:11:03 volumio volumio[770]: info: CoreStateMachine::syncState currentStatus stop Feb 16 13:11:03 volumio volumio[770]: info: [1739711463358] [RadioParadise] setting new timer with duration of 254000 seconds. Feb 16 13:11:03 volumio volumio[770]: info: CorePlayQueue::getTrack 6 Feb 16 13:11:03 volumio volumio[770]: info: CorePlayQueue::getTrack 7 Feb 16 13:11:13 volumio sudo[7973]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 16 13:11:13 volumio sudo[7973]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 13:11:13 volumio sudo[7975]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 16 13:11:13 volumio sudo[7973]: pam_unix(sudo:session): session closed for user root Feb 16 13:11:13 volumio sudo[7975]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 13:11:13 volumio sudo[7975]: pam_unix(sudo:session): session closed for user root Feb 16 13:11:13 volumio volumio[770]: verbose: New Socket.io Connection to 192.168.1.11 from 192.168.1.137 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Mobile Safari/537.36 Total Clients: 6 Feb 16 13:11:13 volumio volumio[770]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 16 13:11:13 volumio volumio[770]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 16 13:11:13 volumio volumio[770]: info: CoreCommandRouter::volumioGetVisibleSources Feb 16 13:11:13 volumio volumio[770]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 16 13:11:13 volumio volumio[770]: info: CoreCommandRouter::volumioGetState Feb 16 13:11:13 volumio volumio[770]: info: CorePlayQueue::getTrack 6 Feb 16 13:11:13 volumio volumio[770]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 16 13:11:13 volumio volumio[770]: info: CoreCommandRouter::volumioGetQueue Feb 16 13:11:13 volumio volumio[770]: info: CoreStateMachine::getQueue Feb 16 13:11:13 volumio volumio[770]: info: CorePlayQueue::getQueue Feb 16 13:11:13 volumio volumio[770]: info: Listing playlists Feb 16 13:11:13 volumio volumio[770]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 16 13:11:13 volumio volumio[770]: info: Received Get System Info Feb 16 13:11:13 volumio volumio[770]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 16 13:11:13 volumio volumio[770]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 16 13:11:13 volumio volumio[770]: info: Discovery: Getting this device information Feb 16 13:11:13 volumio volumio[770]: info: CoreCommandRouter::volumioGetState Feb 16 13:11:13 volumio volumio[770]: info: CorePlayQueue::getTrack 6 Feb 16 13:11:13 volumio volumio[770]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 16 13:11:13 volumio volumio[770]: info: CoreCommandRouter::volumioGetState Feb 16 13:11:13 volumio volumio[770]: info: CorePlayQueue::getTrack 6 Feb 16 13:11:13 volumio volumio[770]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 16 13:11:13 volumio volumio[770]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 16 13:11:14 volumio volumio[770]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 16 13:11:15 volumio volumio[770]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 16 13:11:15 volumio volumio[770]: info: Received Get System Info Feb 16 13:11:15 volumio volumio[770]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 16 13:11:15 volumio volumio[770]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 16 13:11:15 volumio volumio[770]: info: Discovery: Getting this device information Feb 16 13:11:15 volumio volumio[770]: info: CoreCommandRouter::volumioGetState Feb 16 13:11:15 volumio volumio[770]: info: CorePlayQueue::getTrack 6 Feb 16 13:11:15 volumio volumio[770]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 16 13:11:15 volumio volumio[770]: info: CoreCommandRouter::volumioPause Feb 16 13:11:15 volumio volumio[770]: info: CoreStateMachine::pause Feb 16 13:11:15 volumio volumio[770]: info: CoreStateMachine::stPlaybackTimer Feb 16 13:11:15 volumio volumio[770]: info: CoreStateMachine::servicePause Feb 16 13:11:15 volumio volumio[770]: info: CorePlayQueue::getTrack 6 Feb 16 13:11:15 volumio volumio[770]: info: CoreCommandRouter::servicePause Feb 16 13:11:15 volumio volumio[770]: verbose: ControllerMpd::sendMpdCommand pause Feb 16 13:11:16 volumio volumio[770]: info: Feb 16 13:11:16 volumio volumio[770]: ---------------------------- MPD announces state update: player Feb 16 13:11:16 volumio volumio[770]: info: sendMpdCommand pause took 65 milliseconds Feb 16 13:11:16 volumio volumio[770]: info: ControllerMpd::getState Feb 16 13:11:16 volumio volumio[770]: verbose: ControllerMpd::sendMpdCommand status Feb 16 13:11:16 volumio volumio[770]: info: CorePlayQueue::getTrack 6 Feb 16 13:11:16 volumio volumio[770]: info: CoreCommandRouter::servicePushState Feb 16 13:11:16 volumio volumio[770]: info: CorePlayQueue::getTrack 6 Feb 16 13:11:16 volumio volumio[770]: verbose: STATE SERVICE {"status":"pause","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/B0000062FL.jpg","uri":"https://stream.radioparadise.com/flac","name":"Oye como va","title":"Oye como va","artist":"Santana","album":"Abraxas","streaming":true,"disableUiControls":true,"duration":254,"seek":12581,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Feb 16 13:11:16 volumio volumio[770]: verbose: CURRENT POSITION 6 Feb 16 13:11:16 volumio volumio[770]: info: CoreStateMachine::syncState stateService pause Feb 16 13:11:16 volumio volumio[770]: info: CoreStateMachine::syncState currentStatus pause Feb 16 13:11:16 volumio volumio[770]: info: CoreStateMachine::pushState Feb 16 13:11:16 volumio volumio[770]: info: CorePlayQueue::getTrack 6 Feb 16 13:11:16 volumio volumio[770]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 13:11:16 volumio volumio[770]: info: CoreCommandRouter::volumioPushState Feb 16 13:11:16 volumio volumio[770]: info: CoreStateMachine::stPlaybackTimer Feb 16 13:11:16 volumio kernel: aiu i2s playback disable Feb 16 13:11:16 volumio kernel: audio_hw_958_enable 0 Feb 16 13:11:16 volumio volumio[770]: info: sendMpdCommand status took 9 milliseconds Feb 16 13:11:16 volumio volumio[770]: verbose: ControllerMpd::parseState Feb 16 13:11:16 volumio volumio[770]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 16 13:11:16 volumio volumio[770]: info: sendMpdCommand playlistinfo took 14 milliseconds Feb 16 13:11:16 volumio volumio[770]: verbose: ControllerMpd::parseTrackInfo Feb 16 13:11:16 volumio volumio[770]: info: ControllerMpd::pushState Feb 16 13:11:16 volumio volumio[770]: info: CoreCommandRouter::servicePushState Feb 16 13:11:16 volumio volumio[770]: info: CorePlayQueue::getTrack 6 Feb 16 13:11:16 volumio volumio[770]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Feb 16 13:11:16 volumio volumio[770]: info: ------------------------------ 26ms Feb 16 13:11:16 volumio volumio[770]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 16 13:11:16 volumio volumio[770]: info: Received Get System Info Feb 16 13:11:16 volumio volumio[770]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 16 13:11:16 volumio volumio[770]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 16 13:11:16 volumio volumio[770]: info: Discovery: Getting this device information Feb 16 13:11:16 volumio volumio[770]: info: CoreCommandRouter::volumioGetState Feb 16 13:11:16 volumio volumio[770]: info: CorePlayQueue::getTrack 6 Feb 16 13:11:16 volumio volumio[770]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 16 13:11:29 volumio volumio[770]: info: Clearing queue after UPNP request Feb 16 13:11:30 volumio volumio[770]: info: CoreStateMachine::ClearQueue Feb 16 13:11:30 volumio volumio[770]: info: CoreStateMachine::stop Feb 16 13:11:30 volumio volumio[770]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 16 13:11:30 volumio volumio[770]: info: CoreStateMachine::updateTrackBlock Feb 16 13:11:30 volumio volumio[770]: info: CorePlayQueue::getTrackBlock Feb 16 13:11:30 volumio volumio[770]: info: CoreStateMachine::stPlaybackTimer Feb 16 13:11:30 volumio volumio[770]: info: CoreStateMachine::pushState Feb 16 13:11:30 volumio volumio[770]: info: CorePlayQueue::getTrack 6 Feb 16 13:11:30 volumio volumio[770]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 13:11:30 volumio volumio[770]: info: CoreCommandRouter::volumioPushState Feb 16 13:11:30 volumio volumio[770]: info: CoreStateMachine::serviceStop Feb 16 13:11:30 volumio volumio[770]: info: CorePlayQueue::getTrack 6 Feb 16 13:11:30 volumio volumio[770]: info: CoreCommandRouter::serviceStop Feb 16 13:11:30 volumio volumio[770]: info: ControllerMpd::stop Feb 16 13:11:30 volumio volumio[770]: verbose: ControllerMpd::sendMpdCommand stop Feb 16 13:11:30 volumio volumio[770]: info: CorePlayQueue::clearPlayQueue Feb 16 13:11:30 volumio volumio[770]: info: CorePlayQueue::saveQueue Feb 16 13:11:30 volumio volumio[770]: info: CoreCommandRouter::volumioPushState Feb 16 13:11:30 volumio volumio[770]: info: CoreCommandRouter::volumioPushQueue Feb 16 13:11:30 volumio volumio[770]: error: Upnp client error: Error: This socket has been ended by the other party Feb 16 13:11:30 volumio volumio[770]: info: Feb 16 13:11:30 volumio volumio[770]: ---------------------------- MPD announces state update: player Feb 16 13:11:30 volumio volumio[770]: info: sendMpdCommand stop took 19 milliseconds Feb 16 13:11:30 volumio volumio[770]: info: ControllerMpd::getState Feb 16 13:11:30 volumio volumio[770]: verbose: ControllerMpd::sendMpdCommand status Feb 16 13:11:30 volumio volumio[770]: info: CoreCommandRouter::servicePushState Feb 16 13:11:30 volumio volumio[770]: info: CoreStateMachine::pushState Feb 16 13:11:30 volumio volumio[770]: info: CorePlayQueue::getTrack 6 Feb 16 13:11:30 volumio volumio[770]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 13:11:30 volumio volumio[770]: info: CoreCommandRouter::volumioPushState Feb 16 13:11:30 volumio volumio[770]: info: CorePlayQueue::getTrack 6 Feb 16 13:11:30 volumio volumio[770]: verbose: STATE SERVICE {"status":"stop","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/B0000062FL.jpg","uri":"https://stream.radioparadise.com/flac","name":"Oye como va","title":"Oye como va","artist":"Santana","album":"Abraxas","streaming":true,"disableUiControls":true,"duration":254,"seek":12581,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Feb 16 13:11:30 volumio volumio[770]: verbose: CURRENT POSITION 6 Feb 16 13:11:30 volumio volumio[770]: info: CoreStateMachine::syncState stateService stop Feb 16 13:11:30 volumio volumio[770]: info: CoreStateMachine::syncState currentStatus stop Feb 16 13:11:30 volumio volumio[770]: info: CoreStateMachine::pushState Feb 16 13:11:30 volumio volumio[770]: info: CorePlayQueue::getTrack 6 Feb 16 13:11:30 volumio volumio[770]: info: CoreCommandRouter::volumioPushState Feb 16 13:11:30 volumio volumio[770]: info: No code Feb 16 13:11:30 volumio volumio[770]: info: CoreStateMachine::pushState Feb 16 13:11:30 volumio volumio[770]: info: CorePlayQueue::getTrack 6 Feb 16 13:11:30 volumio volumio[770]: info: CoreCommandRouter::volumioPushState Feb 16 13:11:30 volumio volumio[770]: info: sendMpdCommand status took 20 milliseconds Feb 16 13:11:30 volumio volumio[770]: verbose: ControllerMpd::parseState Feb 16 13:11:30 volumio volumio[770]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 16 13:11:30 volumio volumio[770]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 16 13:11:30 volumio volumio[770]: verbose: ControllerMpd::parseTrackInfo Feb 16 13:11:30 volumio volumio[770]: info: ControllerMpd::pushState Feb 16 13:11:30 volumio volumio[770]: info: CoreCommandRouter::servicePushState Feb 16 13:11:30 volumio volumio[770]: info: CoreStateMachine::pushState Feb 16 13:11:30 volumio volumio[770]: info: CorePlayQueue::getTrack 6 Feb 16 13:11:30 volumio volumio[770]: info: CoreCommandRouter::volumioPushState Feb 16 13:11:30 volumio volumio[770]: info: CorePlayQueue::getTrack 6 Feb 16 13:11:30 volumio volumio[770]: 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":"flac","artist":"Radio Paradise (flac) - playlist: radioparadise.com","album":null,"uri":"https://stream.radioparadise.com/flac","trackType":"com/flac"} Feb 16 13:11:30 volumio volumio[770]: verbose: CURRENT POSITION 6 Feb 16 13:11:30 volumio volumio[770]: info: CoreStateMachine::syncState stateService stop Feb 16 13:11:30 volumio volumio[770]: info: CoreStateMachine::syncState currentStatus stop Feb 16 13:11:30 volumio volumio[770]: info: CoreStateMachine::pushState Feb 16 13:11:30 volumio volumio[770]: info: CorePlayQueue::getTrack 6 Feb 16 13:11:30 volumio volumio[770]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 13:11:30 volumio volumio[770]: info: CoreCommandRouter::volumioPushState Feb 16 13:11:30 volumio volumio[770]: info: No code Feb 16 13:11:30 volumio volumio[770]: info: CoreStateMachine::pushState Feb 16 13:11:30 volumio volumio[770]: info: CorePlayQueue::getTrack 6 Feb 16 13:11:30 volumio volumio[770]: info: CoreCommandRouter::volumioPushState Feb 16 13:11:30 volumio volumio[770]: info: ------------------------------ 35ms Feb 16 13:11:31 volumio volumio[770]: info: Feb 16 13:11:31 volumio volumio[770]: ---------------------------- MPD announces state update: options Feb 16 13:11:31 volumio volumio[770]: info: ControllerMpd::getState Feb 16 13:11:31 volumio volumio[770]: verbose: ControllerMpd::sendMpdCommand status Feb 16 13:11:31 volumio volumio[770]: info: sendMpdCommand status took 2 milliseconds Feb 16 13:11:31 volumio volumio[770]: verbose: ControllerMpd::parseState Feb 16 13:11:31 volumio volumio[770]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 16 13:11:31 volumio volumio[770]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 16 13:11:31 volumio volumio[770]: verbose: ControllerMpd::parseTrackInfo Feb 16 13:11:31 volumio volumio[770]: info: ControllerMpd::pushState Feb 16 13:11:31 volumio volumio[770]: info: CoreCommandRouter::servicePushState Feb 16 13:11:31 volumio volumio[770]: info: CoreStateMachine::pushState Feb 16 13:11:31 volumio volumio[770]: info: CorePlayQueue::getTrack 6 Feb 16 13:11:31 volumio volumio[770]: info: CoreCommandRouter::volumioPushState Feb 16 13:11:31 volumio volumio[770]: info: CorePlayQueue::getTrack 6 Feb 16 13:11:31 volumio volumio[770]: 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":"flac","artist":"Radio Paradise (flac) - playlist: radioparadise.com","album":null,"uri":"https://stream.radioparadise.com/flac","trackType":"com/flac"} Feb 16 13:11:31 volumio volumio[770]: verbose: CURRENT POSITION 6 Feb 16 13:11:31 volumio volumio[770]: info: CoreStateMachine::syncState stateService stop Feb 16 13:11:31 volumio volumio[770]: info: CoreStateMachine::syncState currentStatus stop Feb 16 13:11:31 volumio volumio[770]: info: CoreStateMachine::pushState Feb 16 13:11:31 volumio volumio[770]: info: CorePlayQueue::getTrack 6 Feb 16 13:11:31 volumio volumio[770]: info: CoreCommandRouter::volumioPushState Feb 16 13:11:31 volumio volumio[770]: info: No code Feb 16 13:11:31 volumio volumio[770]: info: CoreStateMachine::pushState Feb 16 13:11:31 volumio volumio[770]: info: CorePlayQueue::getTrack 6 Feb 16 13:11:31 volumio volumio[770]: info: CoreCommandRouter::volumioPushState Feb 16 13:11:31 volumio volumio[770]: info: ------------------------------ 28ms Feb 16 13:11:31 volumio volumio[770]: info: Starting UPNP Playback Feb 16 13:11:31 volumio volumio[770]: info: Preparing playback through UPNP Feb 16 13:11:31 volumio volumio[770]: info: CoreCommandRouter::volumioGetState Feb 16 13:11:31 volumio volumio[770]: info: CorePlayQueue::getTrack 6 Feb 16 13:11:31 volumio volumio[770]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 16 13:11:32 volumio volumio[770]: info: Feb 16 13:11:32 volumio volumio[770]: ---------------------------- MPD announces system playlist update Feb 16 13:11:32 volumio volumio[770]: info: Ignoring MPD Status Update Feb 16 13:11:32 volumio volumio[770]: info: ------------------------------ 2ms Feb 16 13:11:32 volumio volumio[770]: info: Feb 16 13:11:32 volumio volumio[770]: ---------------------------- MPD announces system playlist update Feb 16 13:11:32 volumio volumio[770]: info: Ignoring MPD Status Update Feb 16 13:11:32 volumio volumio[770]: info: ------------------------------ 1ms Feb 16 13:11:32 volumio volumio[770]: info: Feb 16 13:11:32 volumio volumio[770]: ---------------------------- MPD announces system playlist update Feb 16 13:11:32 volumio volumio[770]: info: Ignoring MPD Status Update Feb 16 13:11:32 volumio volumio[770]: info: Feb 16 13:11:32 volumio volumio[770]: ---------------------------- MPD announces system playlist update Feb 16 13:11:32 volumio volumio[770]: info: Ignoring MPD Status Update Feb 16 13:11:32 volumio volumio[770]: info: ------------------------------ 3ms Feb 16 13:11:32 volumio volumio[770]: info: ------------------------------ 3ms Feb 16 13:11:32 volumio volumio[770]: info: Feb 16 13:11:32 volumio volumio[770]: ---------------------------- MPD announces system playlist update Feb 16 13:11:32 volumio volumio[770]: info: Ignoring MPD Status Update Feb 16 13:11:32 volumio volumio[770]: info: ------------------------------ 2ms Feb 16 13:11:32 volumio volumio[770]: info: Feb 16 13:11:32 volumio volumio[770]: ---------------------------- MPD announces system playlist update Feb 16 13:11:32 volumio volumio[770]: info: Ignoring MPD Status Update Feb 16 13:11:32 volumio volumio[770]: info: ------------------------------ 1ms Feb 16 13:11:32 volumio volumio[770]: info: Feb 16 13:11:32 volumio volumio[770]: ---------------------------- MPD announces state update: player Feb 16 13:11:32 volumio volumio[770]: info: ControllerMpd::getState Feb 16 13:11:32 volumio volumio[770]: verbose: ControllerMpd::sendMpdCommand status Feb 16 13:11:32 volumio kernel: [aml-i2s-dai]i2s dma ffffff80004b1000,phy addr 1573257216,mode 0,ch 2 Feb 16 13:11:32 volumio kernel: ----aml_hw_iec958_init,runtime->rate=44100,sample_rate=5-- Feb 16 13:11:32 volumio kernel: aml_set_spdif_clk rate Feb 16 13:11:32 volumio kernel: share the same clock Feb 16 13:11:32 volumio kernel: iec958 mode PCM16 Feb 16 13:11:32 volumio kernel: IEC958 16bit Feb 16 13:11:32 volumio kernel: hdmitx: audio: aout notify rate 44100 Feb 16 13:11:32 volumio kernel: hdmitx: audio: aout notify size 16 Feb 16 13:11:32 volumio kernel: 958 with i2s Feb 16 13:11:32 volumio kernel: aiu i2s playback enable Feb 16 13:11:32 volumio kernel: audio_hw_958_enable 1 Feb 16 13:11:32 volumio volumio[770]: info: Feb 16 13:11:32 volumio volumio[770]: ---------------------------- MPD announces state update: player Feb 16 13:11:32 volumio volumio[770]: info: sendMpdCommand status took 36 milliseconds Feb 16 13:11:32 volumio volumio[770]: info: ControllerMpd::getState Feb 16 13:11:32 volumio volumio[770]: verbose: ControllerMpd::sendMpdCommand status Feb 16 13:11:32 volumio volumio[770]: verbose: ControllerMpd::parseState Feb 16 13:11:32 volumio volumio[770]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 16 13:11:32 volumio volumio[770]: info: Feb 16 13:11:32 volumio volumio[770]: ---------------------------- MPD announces system playlist update Feb 16 13:11:32 volumio volumio[770]: info: Ignoring MPD Status Update Feb 16 13:11:32 volumio volumio[770]: info: Feb 16 13:11:32 volumio volumio[770]: ---------------------------- MPD announces state update: player Feb 16 13:11:32 volumio volumio[770]: info: ControllerMpd::getState Feb 16 13:11:32 volumio volumio[770]: verbose: ControllerMpd::sendMpdCommand status Feb 16 13:11:32 volumio volumio[770]: info: sendMpdCommand status took 3 milliseconds Feb 16 13:11:32 volumio volumio[770]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 16 13:11:32 volumio volumio[770]: verbose: ControllerMpd::parseState Feb 16 13:11:32 volumio volumio[770]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 16 13:11:32 volumio volumio[770]: verbose: ControllerMpd::parseTrackInfo Feb 16 13:11:32 volumio volumio[770]: info: ControllerMpd::pushState Feb 16 13:11:32 volumio volumio[770]: info: CoreCommandRouter::servicePushState Feb 16 13:11:32 volumio volumio[770]: verbose: In UPNP mode Feb 16 13:11:32 volumio volumio[770]: verbose: STATE SERVICE {"status":"play","position":1,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flac","artist":"Radio Paradise (flac) - playlist: radioparadise.com","album":null,"uri":"https://stream.radioparadise.com/flac","trackType":"com/flac"} Feb 16 13:11:32 volumio volumio[770]: verbose: CURRENT POSITION 6 Feb 16 13:11:32 volumio volumio[770]: info: CoreStateMachine::syncState stateService play Feb 16 13:11:32 volumio volumio[770]: info: CoreStateMachine::syncState currentStatus stop Feb 16 13:11:32 volumio volumio[770]: info: ------------------------------ 44ms Feb 16 13:11:32 volumio volumio[770]: info: ------------------------------ 7ms Feb 16 13:11:32 volumio volumio[770]: info: sendMpdCommand status took 6 milliseconds Feb 16 13:11:32 volumio volumio[770]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 16 13:11:32 volumio volumio[770]: verbose: ControllerMpd::parseState Feb 16 13:11:32 volumio volumio[770]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 16 13:11:32 volumio volumio[770]: verbose: ControllerMpd::parseTrackInfo Feb 16 13:11:32 volumio volumio[770]: info: ControllerMpd::pushState Feb 16 13:11:32 volumio volumio[770]: info: CoreCommandRouter::servicePushState Feb 16 13:11:32 volumio volumio[770]: verbose: In UPNP mode Feb 16 13:11:32 volumio volumio[770]: verbose: STATE SERVICE {"status":"play","position":1,"seek":617,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flac","artist":"Radio Paradise (flac) - playlist: radioparadise.com","album":null,"uri":"https://stream.radioparadise.com/flac","trackType":"com/flac"} Feb 16 13:11:32 volumio volumio[770]: verbose: CURRENT POSITION 6 Feb 16 13:11:32 volumio volumio[770]: info: CoreStateMachine::syncState stateService play Feb 16 13:11:32 volumio volumio[770]: info: CoreStateMachine::syncState currentStatus play Feb 16 13:11:32 volumio volumio[770]: info: Received an update from plugin. extracting info from payload Feb 16 13:11:32 volumio volumio[770]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 16 13:11:32 volumio volumio[770]: info: CoreStateMachine::pushState Feb 16 13:11:32 volumio volumio[770]: info: CorePlayQueue::getTrack 6 Feb 16 13:11:32 volumio volumio[770]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Feb 16 13:11:32 volumio volumio[770]: info: ------------------------------ 26ms Feb 16 13:11:32 volumio volumio[770]: info: sendMpdCommand playlistinfo took 17 milliseconds Feb 16 13:11:32 volumio volumio[770]: verbose: ControllerMpd::parseTrackInfo Feb 16 13:11:32 volumio volumio[770]: info: ControllerMpd::pushState Feb 16 13:11:32 volumio volumio[770]: info: CoreCommandRouter::servicePushState Feb 16 13:11:32 volumio volumio[770]: verbose: In UPNP mode Feb 16 13:11:32 volumio volumio[770]: verbose: STATE SERVICE {"status":"play","position":1,"seek":708,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flac","artist":"Radio Paradise (flac) - playlist: radioparadise.com","album":null,"uri":"https://stream.radioparadise.com/flac","trackType":"com/flac"} Feb 16 13:11:32 volumio volumio[770]: verbose: CURRENT POSITION 6 Feb 16 13:11:32 volumio volumio[770]: info: CoreStateMachine::syncState stateService play Feb 16 13:11:32 volumio volumio[770]: info: CoreStateMachine::syncState currentStatus play Feb 16 13:11:32 volumio volumio[770]: info: Received an update from plugin. extracting info from payload Feb 16 13:11:32 volumio volumio[770]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 16 13:11:32 volumio volumio[770]: info: CoreStateMachine::pushState Feb 16 13:11:32 volumio volumio[770]: info: CorePlayQueue::getTrack 6 Feb 16 13:11:32 volumio volumio[770]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Feb 16 13:11:32 volumio volumio[770]: info: ------------------------------ 34ms Feb 16 13:11:33 volumio volumio[770]: info: Starting UPNP Playback Feb 16 13:11:33 volumio volumio[770]: info: Preparing playback through UPNP Feb 16 13:11:33 volumio volumio[770]: info: Consume mode Feb 16 13:11:33 volumio volumio[770]: info: CoreCommandRouter::volumioGetState Feb 16 13:11:33 volumio volumio[770]: info: CorePlayQueue::getTrack 6 Feb 16 13:11:33 volumio volumio[770]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 16 13:11:33 volumio volumio[770]: TypeError: Cannot read property 'name' of undefined Feb 16 13:11:33 volumio volumio[770]: at CoreStateMachine.getState (/volumio/app/statemachine.js:122:48) Feb 16 13:11:33 volumio volumio[770]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:106:28) Feb 16 13:11:33 volumio volumio[770]: at UpnpInterface.prepareUpnpPlayback (/volumio/app/plugins/audio_interface/upnp/index.js:257:34) Feb 16 13:11:33 volumio volumio[770]: at Socket. (/volumio/app/plugins/audio_interface/upnp/index.js:44:14) Feb 16 13:11:33 volumio volumio[770]: at Socket.emit (events.js:400:28) Feb 16 13:11:33 volumio volumio[770]: at addChunk (internal/streams/readable.js:293:12) Feb 16 13:11:33 volumio volumio[770]: at readableAddChunk (internal/streams/readable.js:263:11) Feb 16 13:11:33 volumio volumio[770]: at Socket.Readable.push (internal/streams/readable.js:206:10) Feb 16 13:11:33 volumio volumio[770]: at TCP.onStreamRead (internal/stream_base_commons.js:188:23) Feb 16 13:11:33 volumio volumio[770]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 16 13:11:35 volumio sudo[8020]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-16 13:10 Feb 16 13:11:35 volumio sudo[8020]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="5dd4b16d278e65eba977a0fa3cd41bbd8df8b286" VOLUMIO_FE_VERSION="c433755e569617abac52264d17ad61be303ef463" VOLUMIO_FE3_VERSION="2419ddd8e895c5bdc456962163c9dc7c0f187aa7" VOLUMIO_BE_VERSION="104e2b522b98905ce27e15e27e932df346b00b06" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 Jun 2023 06:10:01 PM CEST" VOLUMIO_VERSION="3.503" VOLUMIO_HARDWARE="odroidc2" VOLUMIO_DEVICENAME="Odroid-C2" VOLUMIO_HASH="f3e1c48190cad2ad342f6044d18a9780"