Mar 24 13:22:04 volumio ntpd[956]: CLOCK: time stepped by 336828.068864 Mar 24 13:22:04 volumio ntpd[956]: CLOCK: time changed from 2026-03-20 to 2026-03-24 Mar 24 13:22:04 volumio ntpd[956]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Mar 24 13:22:05 volumio systemd[1]: Starting apt-daily-upgrade.service - Daily apt upgrade and clean activities... Mar 24 13:22:05 volumio systemd[1]: Starting dpkg-db-backup.service - Daily dpkg database backup service... Mar 24 13:22:05 volumio systemd[1]: Starting e2scrub_all.service - Online ext4 Metadata Check for All Filesystems... Mar 24 13:22:05 volumio systemd[1]: Starting fstrim.service - Discard unused blocks on filesystems from /etc/fstab... Mar 24 13:22:05 volumio systemd[1]: Started ntpsec-rotate-stats.service - Rotate ntpd stats. Mar 24 13:22:05 volumio systemd[1]: e2scrub_all.service: Deactivated successfully. Mar 24 13:22:05 volumio systemd[1]: Finished e2scrub_all.service - Online ext4 Metadata Check for All Filesystems. Mar 24 13:22:05 volumio systemd[1]: ntpsec-rotate-stats.service: Deactivated successfully. Mar 24 13:22:15 volumio kernel: sdhost-bcm2835 3f202000.mmc: timeout waiting for hardware interrupt. Mar 24 13:22:15 volumio fstrim[8726]: /boot: 273.5 MiB (286760960 bytes) trimmed on /dev/mmcblk0p1 Mar 24 13:22:15 volumio systemd[1]: fstrim.service: Deactivated successfully. Mar 24 13:22:15 volumio systemd[1]: Finished fstrim.service - Discard unused blocks on filesystems from /etc/fstab. Mar 24 13:22:16 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 24 13:22:16 volumio systemd[1]: dpkg-db-backup.service: Deactivated successfully. Mar 24 13:22:16 volumio systemd[1]: Finished dpkg-db-backup.service - Daily dpkg database backup service. Mar 24 13:22:16 volumio volumio[1023]: info: Discovery: A device disappeared from network Mar 24 13:22:16 volumio volumio[1023]: info: Discovery: A device disappeared from network Mar 24 13:22:16 volumio volumio[1023]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Mar 24 13:22:16 volumio volumio[1023]: info: Discovery: adding 4c00d5fa-b676-44c7-b3ab-d3e1969cc3c5 Mar 24 13:22:16 volumio volumio[1023]: info: Discovery: Found device Volumio Mar 24 13:22:16 volumio volumio[1023]: info: CoreCommandRouter::volumioGetState Mar 24 13:22:16 volumio volumio[1023]: info: CorePlayQueue::getTrack 0 Mar 24 13:22:16 volumio volumio[1023]: info: Discovery: this is already registered, 4c00d5fa-b676-44c7-b3ab-d3e1969cc3c5 Mar 24 13:22:16 volumio volumio[1023]: info: Discovery: Found device Volumio Mar 24 13:22:16 volumio volumio[1023]: info: CoreCommandRouter::volumioGetState Mar 24 13:22:16 volumio volumio[1023]: info: CorePlayQueue::getTrack 0 Mar 24 13:22:16 volumio volumio[1023]: info: CoreCommandRouter::volumioGetState Mar 24 13:22:16 volumio volumio[1023]: info: CorePlayQueue::getTrack 0 Mar 24 13:22:17 volumio systemd[1]: apt-daily-upgrade.service: Deactivated successfully. Mar 24 13:22:17 volumio systemd[1]: Finished apt-daily-upgrade.service - Daily apt upgrade and clean activities. Mar 24 13:22:17 volumio volumio[1023]: info: MyVolumio login type: Token Mar 24 13:22:17 volumio volumio[1023]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Mar 24 13:22:18 volumio sudo[8804]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 24 13:22:18 volumio sudo[8804]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 13:22:18 volumio sudo[8806]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 24 13:22:18 volumio sudo[8806]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 13:22:18 volumio sudo[8804]: pam_unix(sudo:session): session closed for user root Mar 24 13:22:18 volumio sudo[8806]: pam_unix(sudo:session): session closed for user root Mar 24 13:22:18 volumio volumio[1023]: info: MyVolumio token set successfully Mar 24 13:22:18 volumio volumio[1023]: info: MYVOLUMIO: Adding device Mar 24 13:22:18 volumio volumio[1023]: info: MYVOLUMIO: Evaluating Server Mar 24 13:22:18 volumio volumio[1023]: verbose: New Socket.io Connection to 192.168.1.147 from 192.168.1.128 UA: Mozilla/5.0 (Linux; Android 12; Mi Note 10 Lite Build/SKQ1.210908.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/146.0.7680.119 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Mar 24 13:22:18 volumio volumio[1023]: info: MyVolumio status changed Mar 24 13:22:18 volumio volumio[1023]: info: Streaming services startup Mar 24 13:22:18 volumio volumio[1023]: info: Starting Streaming Daemon Mar 24 13:22:18 volumio volumio[1023]: info: Removing browser output: myVolumio user plan is not superstar Mar 24 13:22:18 volumio volumio[1023]: info: Removing audio output: Mar 24 13:22:18 volumio volumio[1023]: info: Stoppping Tunnel 1 Mar 24 13:22:18 volumio sudo[8833]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 24 13:22:18 volumio sudo[8833]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 13:22:18 volumio sudo[8835]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Mar 24 13:22:18 volumio sudo[8835]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 13:22:18 volumio sudo[8833]: pam_unix(sudo:session): session closed for user root Mar 24 13:22:18 volumio volumio[1023]: error: Cannot start Volumio Streaming Daemon Mar 24 13:22:18 volumio volumio[1023]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 24 13:22:18 volumio volumio[1023]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 24 13:22:18 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Mar 24 13:22:18 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Mar 24 13:22:18 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Mar 24 13:22:18 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Mar 24 13:22:18 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Mar 24 13:22:18 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Mar 24 13:22:18 volumio sudo[8835]: pam_unix(sudo:session): session closed for user root Mar 24 13:22:18 volumio volumio[1023]: info: Setting Geolocation for MyVolumio to eu3 Mar 24 13:22:18 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 13:22:18 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 13:22:18 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 13:22:18 volumio volumio[1023]: info: Remote SSH Stopped Mar 24 13:22:18 volumio sudo[8855]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 24 13:22:18 volumio sudo[8855]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 13:22:18 volumio sudo[8855]: pam_unix(sudo:session): session closed for user root Mar 24 13:22:18 volumio sudo[8857]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 24 13:22:18 volumio sudo[8857]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 13:22:18 volumio sudo[8857]: pam_unix(sudo:session): session closed for user root Mar 24 13:22:18 volumio volumio[1023]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Mar 24 13:22:19 volumio volumio[1023]: verbose: New Socket.io Connection to 192.168.1.147 from 192.168.1.128 UA: Mozilla/5.0 (Linux; Android 12; Mi Note 10 Lite Build/SKQ1.210908.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/146.0.7680.119 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Mar 24 13:22:19 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 24 13:22:19 volumio volumio[1023]: info: CoreCommandRouter::volumioGetVisibleSources Mar 24 13:22:19 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 24 13:22:19 volumio volumio[1023]: info: CoreCommandRouter::volumioGetState Mar 24 13:22:19 volumio volumio[1023]: info: CorePlayQueue::getTrack 0 Mar 24 13:22:19 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 24 13:22:19 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 24 13:22:19 volumio volumio[1023]: info: Received Get System Info Mar 24 13:22:19 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 13:22:19 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 13:22:19 volumio volumio[1023]: info: Discovery: Getting this device information Mar 24 13:22:19 volumio volumio[1023]: info: CoreCommandRouter::volumioGetState Mar 24 13:22:19 volumio volumio[1023]: info: CorePlayQueue::getTrack 0 Mar 24 13:22:19 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 13:22:19 volumio volumio[1023]: info: CoreCommandRouter::volumioGetState Mar 24 13:22:19 volumio volumio[1023]: info: CorePlayQueue::getTrack 0 Mar 24 13:22:19 volumio volumio[1023]: info: Listing playlists Mar 24 13:22:19 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 24 13:22:19 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 24 13:22:19 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 24 13:22:19 volumio volumio[1023]: info: Updating MyVolumio device info Mar 24 13:22:19 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 13:22:19 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 13:22:19 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 13:22:19 volumio volumio[1023]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Mar 24 13:22:20 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 13:22:20 volumio volumio[1023]: info: Received Get System Info Mar 24 13:22:20 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 13:22:20 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 13:22:20 volumio volumio[1023]: info: Discovery: Getting this device information Mar 24 13:22:20 volumio volumio[1023]: info: CoreCommandRouter::volumioGetState Mar 24 13:22:20 volumio volumio[1023]: info: CorePlayQueue::getTrack 0 Mar 24 13:22:20 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 13:22:20 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Mar 24 13:22:20 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 24 13:22:20 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 13:22:20 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 13:22:20 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Mar 24 13:22:21 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 24 13:22:21 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Mar 24 13:22:21 volumio volumio[1023]: info: CoreCommandRouter::volumioGetBrowseSources Mar 24 13:22:21 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 24 13:22:21 volumio volumio[1023]: info: CoreCommandRouter::volumioPlay Mar 24 13:22:21 volumio volumio[1023]: info: CoreStateMachine::play index undefined Mar 24 13:22:21 volumio volumio[1023]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 13:22:21 volumio volumio[1023]: info: CorePlayQueue::getTrack 0 Mar 24 13:22:21 volumio volumio[1023]: info: CoreStateMachine::startPlaybackTimer Mar 24 13:22:21 volumio volumio[1023]: info: CorePlayQueue::getTrack 0 Mar 24 13:22:21 volumio volumio[1023]: verbose: ControllerMpd::clearAddPlayTracks USB/Musica/Música/Otros/Living Colour - Stain/Living Colour - Ausl Nder.mp3 Mar 24 13:22:21 volumio volumio[1023]: verbose: ControllerMpd::sendMpdCommand stop Mar 24 13:22:21 volumio volumio[1023]: info: sendMpdCommand stop took 2 milliseconds Mar 24 13:22:21 volumio volumio[1023]: verbose: ControllerMpd::sendMpdCommand clear Mar 24 13:22:21 volumio volumio[1023]: info: Mar 24 13:22:21 volumio volumio[1023]: ---------------------------- MPD announces system playlist update Mar 24 13:22:21 volumio volumio[1023]: info: Ignoring MPD Status Update Mar 24 13:22:21 volumio volumio[1023]: info: sendMpdCommand clear took 4 milliseconds Mar 24 13:22:21 volumio volumio[1023]: verbose: ControllerMpd::sendMpdCommand add "USB/Musica/Música/Otros/Living Colour - Stain/Living Colour - Ausl Nder.mp3" Mar 24 13:22:21 volumio volumio[1023]: info: Mar 24 13:22:21 volumio volumio[1023]: ---------------------------- MPD announces system playlist update Mar 24 13:22:21 volumio volumio[1023]: info: Ignoring MPD Status Update Mar 24 13:22:21 volumio volumio[1023]: info: Mar 24 13:22:21 volumio volumio[1023]: ---------------------------- MPD announces system playlist update Mar 24 13:22:21 volumio volumio[1023]: info: Ignoring MPD Status Update Mar 24 13:22:21 volumio volumio[1023]: error: updateQueue error: null Mar 24 13:22:21 volumio volumio[1023]: info: Mar 24 13:22:21 volumio volumio[1023]: ---------------------------- MPD announces system playlist update Mar 24 13:22:21 volumio volumio[1023]: info: Ignoring MPD Status Update Mar 24 13:22:21 volumio volumio[1023]: info: ------------------------------ 22ms Mar 24 13:22:21 volumio volumio[1023]: info: sendMpdCommand add "USB/Musica/Música/Otros/Living Colour - Stain/Living Colour - Ausl Nder.mp3" took 18 milliseconds Mar 24 13:22:21 volumio volumio[1023]: info: ------------------------------ 14ms Mar 24 13:22:21 volumio volumio[1023]: info: ------------------------------ 11ms Mar 24 13:22:21 volumio volumio[1023]: verbose: ControllerMpd::sendMpdCommand play Mar 24 13:22:21 volumio volumio[1023]: info: Mar 24 13:22:21 volumio volumio[1023]: ---------------------------- MPD announces system playlist update Mar 24 13:22:21 volumio volumio[1023]: info: Ignoring MPD Status Update Mar 24 13:22:21 volumio volumio[1023]: info: Mar 24 13:22:21 volumio volumio[1023]: ---------------------------- MPD announces system playlist update Mar 24 13:22:21 volumio volumio[1023]: info: Ignoring MPD Status Update Mar 24 13:22:21 volumio volumio[1023]: info: ------------------------------ 18ms Mar 24 13:22:21 volumio volumio[1023]: info: sendMpdCommand play took 14 milliseconds Mar 24 13:22:21 volumio volumio[1023]: info: ------------------------------ 13ms Mar 24 13:22:21 volumio volumio[1023]: info: ------------------------------ 10ms Mar 24 13:22:21 volumio volumio[1023]: info: Mar 24 13:22:21 volumio volumio[1023]: ---------------------------- MPD announces state update: player Mar 24 13:22:21 volumio volumio[1023]: info: ControllerMpd::getState Mar 24 13:22:21 volumio volumio[1023]: verbose: ControllerMpd::sendMpdCommand status Mar 24 13:22:21 volumio volumio[1023]: info: Mar 24 13:22:21 volumio volumio[1023]: ---------------------------- MPD announces state update: player Mar 24 13:22:21 volumio volumio[1023]: info: ControllerMpd::getState Mar 24 13:22:21 volumio volumio[1023]: verbose: ControllerMpd::sendMpdCommand status Mar 24 13:22:21 volumio volumio[1023]: info: Mar 24 13:22:21 volumio volumio[1023]: ---------------------------- MPD announces state update: player Mar 24 13:22:21 volumio volumio[1023]: info: ControllerMpd::getState Mar 24 13:22:21 volumio volumio[1023]: verbose: ControllerMpd::sendMpdCommand status Mar 24 13:22:21 volumio volumio[1023]: info: sendMpdCommand status took 120 milliseconds Mar 24 13:22:21 volumio volumio[1023]: verbose: ControllerMpd::parseState Mar 24 13:22:21 volumio volumio[1023]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 13:22:21 volumio volumio[1023]: info: Mar 24 13:22:21 volumio volumio[1023]: ---------------------------- MPD announces state update: player Mar 24 13:22:21 volumio volumio[1023]: info: ControllerMpd::getState Mar 24 13:22:21 volumio volumio[1023]: verbose: ControllerMpd::sendMpdCommand status Mar 24 13:22:21 volumio volumio[1023]: info: Mar 24 13:22:21 volumio volumio[1023]: ---------------------------- MPD announces state update: player Mar 24 13:22:21 volumio volumio[1023]: info: ControllerMpd::getState Mar 24 13:22:21 volumio volumio[1023]: verbose: ControllerMpd::sendMpdCommand status Mar 24 13:22:21 volumio volumio[1023]: info: sendMpdCommand status took 127 milliseconds Mar 24 13:22:21 volumio volumio[1023]: info: sendMpdCommand status took 127 milliseconds Mar 24 13:22:21 volumio volumio[1023]: verbose: ControllerMpd::parseState Mar 24 13:22:21 volumio volumio[1023]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 13:22:21 volumio volumio[1023]: verbose: ControllerMpd::parseState Mar 24 13:22:21 volumio volumio[1023]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 13:22:21 volumio volumio[1023]: info: Mar 24 13:22:21 volumio volumio[1023]: ---------------------------- MPD announces state update: player Mar 24 13:22:21 volumio volumio[1023]: info: ControllerMpd::getState Mar 24 13:22:21 volumio volumio[1023]: verbose: ControllerMpd::sendMpdCommand status Mar 24 13:22:21 volumio volumio[1023]: info: sendMpdCommand playlistinfo took 14 milliseconds Mar 24 13:22:21 volumio volumio[1023]: info: sendMpdCommand status took 13 milliseconds Mar 24 13:22:21 volumio volumio[1023]: info: sendMpdCommand status took 10 milliseconds Mar 24 13:22:21 volumio volumio[1023]: info: sendMpdCommand playlistinfo took 8 milliseconds Mar 24 13:22:21 volumio volumio[1023]: info: sendMpdCommand playlistinfo took 7 milliseconds Mar 24 13:22:21 volumio volumio[1023]: verbose: ControllerMpd::parseTrackInfo Mar 24 13:22:21 volumio volumio[1023]: verbose: ControllerMpd::parseState Mar 24 13:22:21 volumio volumio[1023]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 13:22:21 volumio volumio[1023]: verbose: ControllerMpd::parseState Mar 24 13:22:21 volumio volumio[1023]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 13:22:21 volumio volumio[1023]: verbose: ControllerMpd::parseTrackInfo Mar 24 13:22:21 volumio volumio[1023]: verbose: ControllerMpd::parseTrackInfo Mar 24 13:22:21 volumio volumio[1023]: info: ControllerMpd::pushState Mar 24 13:22:21 volumio volumio[1023]: info: CoreCommandRouter::servicePushState Mar 24 13:22:21 volumio volumio[1023]: info: CorePlayQueue::getTrack 0 Mar 24 13:22:21 volumio volumio[1023]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":158,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Ausl nder","artist":"Living Colour","album":"Stain","uri":"USB/Musica/Música/Otros/Living Colour - Stain/Living Colour - Ausl Nder.mp3","trackType":"mp3"} Mar 24 13:22:21 volumio volumio[1023]: verbose: CURRENT POSITION 0 Mar 24 13:22:21 volumio volumio[1023]: info: CoreStateMachine::syncState stateService play Mar 24 13:22:21 volumio volumio[1023]: info: CoreStateMachine::syncState currentStatus stop Mar 24 13:22:21 volumio volumio[1023]: info: ControllerMpd::pushState Mar 24 13:22:21 volumio volumio[1023]: info: CoreCommandRouter::servicePushState Mar 24 13:22:21 volumio volumio[1023]: info: CorePlayQueue::getTrack 0 Mar 24 13:22:21 volumio volumio[1023]: verbose: STATE SERVICE {"status":"play","position":0,"seek":962,"duration":158,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Ausl nder","artist":"Living Colour","album":"Stain","uri":"USB/Musica/Música/Otros/Living Colour - Stain/Living Colour - Ausl Nder.mp3","trackType":"mp3"} Mar 24 13:22:21 volumio volumio[1023]: verbose: CURRENT POSITION 0 Mar 24 13:22:21 volumio volumio[1023]: info: CoreStateMachine::syncState stateService play Mar 24 13:22:21 volumio volumio[1023]: info: CoreStateMachine::syncState currentStatus play Mar 24 13:22:21 volumio volumio[1023]: info: Received an update from plugin. extracting info from payload Mar 24 13:22:21 volumio volumio[1023]: info: CoreStateMachine::pushState Mar 24 13:22:21 volumio volumio[1023]: info: CorePlayQueue::getTrack 0 Mar 24 13:22:21 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 13:22:21 volumio volumio[1023]: info: CoreCommandRouter::volumioPushState Mar 24 13:22:21 volumio volumio[1023]: info: CoreStateMachine::pushState Mar 24 13:22:21 volumio volumio[1023]: info: CorePlayQueue::getTrack 0 Mar 24 13:22:21 volumio volumio[1023]: info: CoreCommandRouter::volumioPushState Mar 24 13:22:21 volumio volumio[1023]: info: ControllerMpd::pushState Mar 24 13:22:21 volumio volumio[1023]: info: CoreCommandRouter::servicePushState Mar 24 13:22:21 volumio volumio[1023]: info: CorePlayQueue::getTrack 0 Mar 24 13:22:21 volumio volumio[1023]: verbose: STATE SERVICE {"status":"play","position":0,"seek":962,"duration":158,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Ausl nder","artist":"Living Colour","album":"Stain","uri":"USB/Musica/Música/Otros/Living Colour - Stain/Living Colour - Ausl Nder.mp3","trackType":"mp3"} Mar 24 13:22:21 volumio volumio[1023]: verbose: CURRENT POSITION 0 Mar 24 13:22:21 volumio volumio[1023]: info: CoreStateMachine::syncState stateService play Mar 24 13:22:21 volumio volumio[1023]: info: CoreStateMachine::syncState currentStatus play Mar 24 13:22:21 volumio volumio[1023]: info: Received an update from plugin. extracting info from payload Mar 24 13:22:21 volumio volumio[1023]: info: CoreStateMachine::pushState Mar 24 13:22:21 volumio volumio[1023]: info: CorePlayQueue::getTrack 0 Mar 24 13:22:21 volumio volumio[1023]: info: CoreCommandRouter::volumioPushState Mar 24 13:22:21 volumio volumio[1023]: info: CoreStateMachine::pushState Mar 24 13:22:21 volumio volumio[1023]: info: CorePlayQueue::getTrack 0 Mar 24 13:22:21 volumio volumio[1023]: info: CoreCommandRouter::volumioPushState Mar 24 13:22:21 volumio volumio[1023]: info: ------------------------------ 185ms Mar 24 13:22:21 volumio volumio[1023]: info: ------------------------------ 237ms Mar 24 13:22:21 volumio volumio[1023]: info: ------------------------------ 236ms Mar 24 13:22:21 volumio volumio[1023]: info: sendMpdCommand status took 107 milliseconds Mar 24 13:22:21 volumio volumio[1023]: info: sendMpdCommand playlistinfo took 99 milliseconds Mar 24 13:22:21 volumio volumio[1023]: info: sendMpdCommand playlistinfo took 99 milliseconds Mar 24 13:22:21 volumio volumio[1023]: verbose: ControllerMpd::parseState Mar 24 13:22:21 volumio volumio[1023]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 13:22:21 volumio volumio[1023]: verbose: ControllerMpd::parseTrackInfo Mar 24 13:22:21 volumio volumio[1023]: verbose: ControllerMpd::parseTrackInfo Mar 24 13:22:21 volumio volumio[1023]: info: ControllerMpd::pushState Mar 24 13:22:21 volumio volumio[1023]: info: CoreCommandRouter::servicePushState Mar 24 13:22:21 volumio volumio[1023]: info: CorePlayQueue::getTrack 0 Mar 24 13:22:21 volumio volumio[1023]: verbose: STATE SERVICE {"status":"play","position":0,"seek":962,"duration":158,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Ausl nder","artist":"Living Colour","album":"Stain","uri":"USB/Musica/Música/Otros/Living Colour - Stain/Living Colour - Ausl Nder.mp3","trackType":"mp3"} Mar 24 13:22:21 volumio volumio[1023]: verbose: CURRENT POSITION 0 Mar 24 13:22:21 volumio volumio[1023]: info: CoreStateMachine::syncState stateService play Mar 24 13:22:21 volumio volumio[1023]: info: CoreStateMachine::syncState currentStatus play Mar 24 13:22:21 volumio volumio[1023]: info: Received an update from plugin. extracting info from payload Mar 24 13:22:21 volumio volumio[1023]: info: CoreStateMachine::pushState Mar 24 13:22:21 volumio volumio[1023]: info: CorePlayQueue::getTrack 0 Mar 24 13:22:21 volumio volumio[1023]: info: CoreCommandRouter::volumioPushState Mar 24 13:22:21 volumio volumio[1023]: info: CoreStateMachine::pushState Mar 24 13:22:21 volumio volumio[1023]: info: CorePlayQueue::getTrack 0 Mar 24 13:22:21 volumio volumio[1023]: info: CoreCommandRouter::volumioPushState Mar 24 13:22:21 volumio volumio[1023]: info: ControllerMpd::pushState Mar 24 13:22:21 volumio volumio[1023]: info: CoreCommandRouter::servicePushState Mar 24 13:22:21 volumio volumio[1023]: info: CorePlayQueue::getTrack 0 Mar 24 13:22:21 volumio volumio[1023]: verbose: STATE SERVICE {"status":"play","position":0,"seek":962,"duration":158,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Ausl nder","artist":"Living Colour","album":"Stain","uri":"USB/Musica/Música/Otros/Living Colour - Stain/Living Colour - Ausl Nder.mp3","trackType":"mp3"} Mar 24 13:22:21 volumio volumio[1023]: verbose: CURRENT POSITION 0 Mar 24 13:22:21 volumio volumio[1023]: info: CoreStateMachine::syncState stateService play Mar 24 13:22:21 volumio volumio[1023]: info: CoreStateMachine::syncState currentStatus play Mar 24 13:22:21 volumio volumio[1023]: info: Received an update from plugin. extracting info from payload Mar 24 13:22:21 volumio volumio[1023]: info: CoreStateMachine::pushState Mar 24 13:22:21 volumio volumio[1023]: info: CorePlayQueue::getTrack 0 Mar 24 13:22:21 volumio volumio[1023]: info: CoreCommandRouter::volumioPushState Mar 24 13:22:21 volumio volumio[1023]: info: CoreStateMachine::pushState Mar 24 13:22:21 volumio volumio[1023]: info: CorePlayQueue::getTrack 0 Mar 24 13:22:21 volumio volumio[1023]: info: CoreCommandRouter::volumioPushState Mar 24 13:22:21 volumio volumio[1023]: info: ------------------------------ 202ms Mar 24 13:22:21 volumio volumio[1023]: info: ------------------------------ 200ms Mar 24 13:22:21 volumio volumio[1023]: info: sendMpdCommand playlistinfo took 123 milliseconds Mar 24 13:22:21 volumio volumio[1023]: verbose: ControllerMpd::parseTrackInfo Mar 24 13:22:21 volumio volumio[1023]: info: ControllerMpd::pushState Mar 24 13:22:21 volumio volumio[1023]: info: CoreCommandRouter::servicePushState Mar 24 13:22:21 volumio volumio[1023]: info: CorePlayQueue::getTrack 0 Mar 24 13:22:21 volumio volumio[1023]: verbose: STATE SERVICE {"status":"play","position":0,"seek":962,"duration":158,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Ausl nder","artist":"Living Colour","album":"Stain","uri":"USB/Musica/Música/Otros/Living Colour - Stain/Living Colour - Ausl Nder.mp3","trackType":"mp3"} Mar 24 13:22:21 volumio volumio[1023]: verbose: CURRENT POSITION 0 Mar 24 13:22:21 volumio volumio[1023]: info: CoreStateMachine::syncState stateService play Mar 24 13:22:21 volumio volumio[1023]: info: CoreStateMachine::syncState currentStatus play Mar 24 13:22:21 volumio volumio[1023]: info: Received an update from plugin. extracting info from payload Mar 24 13:22:21 volumio volumio[1023]: info: CoreStateMachine::pushState Mar 24 13:22:21 volumio volumio[1023]: info: CorePlayQueue::getTrack 0 Mar 24 13:22:21 volumio volumio[1023]: info: CoreCommandRouter::volumioPushState Mar 24 13:22:21 volumio volumio[1023]: info: CoreStateMachine::pushState Mar 24 13:22:21 volumio volumio[1023]: info: CorePlayQueue::getTrack 0 Mar 24 13:22:21 volumio volumio[1023]: info: CoreCommandRouter::volumioPushState Mar 24 13:22:21 volumio volumio[1023]: info: ------------------------------ 268ms Mar 24 13:22:22 volumio volumio[1023]: info: MYVOLUMIO: Adding device Mar 24 13:22:22 volumio volumio[1023]: info: MYVOLUMIO: Evaluating Server Mar 24 13:22:23 volumio volumio[1023]: info: CoreCommandRouter::volumioNext Mar 24 13:22:23 volumio volumio[1023]: info: CoreStateMachine::next Mar 24 13:22:23 volumio volumio[1023]: info: CoreStateMachine::stop Mar 24 13:22:23 volumio volumio[1023]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 13:22:23 volumio volumio[1023]: info: CoreStateMachine::stPlaybackTimer Mar 24 13:22:23 volumio volumio[1023]: info: CoreStateMachine::updateTrackBlock Mar 24 13:22:23 volumio volumio[1023]: info: CorePlayQueue::getTrackBlock Mar 24 13:22:23 volumio volumio[1023]: info: CoreStateMachine::pushState Mar 24 13:22:23 volumio volumio[1023]: info: CorePlayQueue::getTrack 0 Mar 24 13:22:23 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 13:22:23 volumio volumio[1023]: info: CoreCommandRouter::volumioPushState Mar 24 13:22:23 volumio volumio[1023]: info: CoreStateMachine::serviceStop Mar 24 13:22:23 volumio volumio[1023]: info: CorePlayQueue::getTrack 0 Mar 24 13:22:23 volumio volumio[1023]: info: CoreCommandRouter::serviceStop Mar 24 13:22:23 volumio volumio[1023]: info: ControllerMpd::stop Mar 24 13:22:23 volumio volumio[1023]: verbose: ControllerMpd::sendMpdCommand stop Mar 24 13:22:23 volumio volumio[1023]: info: Mar 24 13:22:23 volumio volumio[1023]: ---------------------------- MPD announces state update: player Mar 24 13:22:23 volumio volumio[1023]: info: sendMpdCommand stop took 17 milliseconds Mar 24 13:22:23 volumio volumio[1023]: info: ControllerMpd::getState Mar 24 13:22:23 volumio volumio[1023]: verbose: ControllerMpd::sendMpdCommand status Mar 24 13:22:23 volumio volumio[1023]: info: CoreStateMachine::play index undefined Mar 24 13:22:23 volumio volumio[1023]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 13:22:23 volumio volumio[1023]: info: CorePlayQueue::getTrack 9 Mar 24 13:22:23 volumio volumio[1023]: info: CoreStateMachine::startPlaybackTimer Mar 24 13:22:23 volumio volumio[1023]: info: CorePlayQueue::getTrack 9 Mar 24 13:22:23 volumio volumio[1023]: verbose: ControllerMpd::clearAddPlayTracks USB/Musica/Música/Otros/Living Colour - Stain/Living Colour - Nothingness.mp3 Mar 24 13:22:23 volumio volumio[1023]: verbose: ControllerMpd::sendMpdCommand stop Mar 24 13:22:23 volumio volumio[1023]: info: CoreStateMachine::updateTrackBlock Mar 24 13:22:23 volumio volumio[1023]: info: CorePlayQueue::getTrackBlock Mar 24 13:22:23 volumio volumio[1023]: info: sendMpdCommand status took 7 milliseconds Mar 24 13:22:23 volumio volumio[1023]: info: sendMpdCommand stop took 2 milliseconds Mar 24 13:22:23 volumio volumio[1023]: verbose: ControllerMpd::parseState Mar 24 13:22:23 volumio volumio[1023]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 13:22:23 volumio volumio[1023]: verbose: ControllerMpd::sendMpdCommand clear Mar 24 13:22:23 volumio volumio[1023]: info: Mar 24 13:22:23 volumio volumio[1023]: ---------------------------- MPD announces state update: player Mar 24 13:22:23 volumio volumio[1023]: info: ControllerMpd::getState Mar 24 13:22:23 volumio volumio[1023]: verbose: ControllerMpd::sendMpdCommand status Mar 24 13:22:23 volumio volumio[1023]: info: Mar 24 13:22:23 volumio volumio[1023]: ---------------------------- MPD announces state update: player Mar 24 13:22:23 volumio volumio[1023]: info: ControllerMpd::getState Mar 24 13:22:23 volumio volumio[1023]: verbose: ControllerMpd::sendMpdCommand status Mar 24 13:22:23 volumio volumio[1023]: info: Mar 24 13:22:23 volumio volumio[1023]: ---------------------------- MPD announces system playlist update Mar 24 13:22:23 volumio volumio[1023]: info: Ignoring MPD Status Update Mar 24 13:22:23 volumio volumio[1023]: info: sendMpdCommand playlistinfo took 9 milliseconds Mar 24 13:22:23 volumio volumio[1023]: info: sendMpdCommand clear took 8 milliseconds Mar 24 13:22:23 volumio volumio[1023]: info: sendMpdCommand status took 7 milliseconds Mar 24 13:22:23 volumio volumio[1023]: info: sendMpdCommand status took 6 milliseconds Mar 24 13:22:23 volumio volumio[1023]: verbose: ControllerMpd::parseTrackInfo Mar 24 13:22:23 volumio volumio[1023]: verbose: ControllerMpd::sendMpdCommand add "USB/Musica/Música/Otros/Living Colour - Stain/Living Colour - Nothingness.mp3" Mar 24 13:22:23 volumio volumio[1023]: verbose: ControllerMpd::parseState Mar 24 13:22:23 volumio volumio[1023]: verbose: ControllerMpd::parseState Mar 24 13:22:23 volumio volumio[1023]: info: ControllerMpd::pushState Mar 24 13:22:23 volumio volumio[1023]: info: CoreCommandRouter::servicePushState Mar 24 13:22:23 volumio volumio[1023]: info: CoreStateMachine::pushState Mar 24 13:22:23 volumio volumio[1023]: info: CorePlayQueue::getTrack 9 Mar 24 13:22:23 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 13:22:23 volumio volumio[1023]: info: CoreCommandRouter::volumioPushState Mar 24 13:22:23 volumio volumio[1023]: info: CorePlayQueue::getTrack 9 Mar 24 13:22:23 volumio volumio[1023]: 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":"Ausl nder","artist":"Living Colour","album":"Stain","uri":"USB/Musica/Música/Otros/Living Colour - Stain/Living Colour - Ausl Nder.mp3","trackType":"mp3"} Mar 24 13:22:23 volumio volumio[1023]: verbose: CURRENT POSITION 9 Mar 24 13:22:23 volumio volumio[1023]: info: CoreStateMachine::syncState stateService stop Mar 24 13:22:23 volumio volumio[1023]: info: CoreStateMachine::syncState currentStatus stop Mar 24 13:22:23 volumio volumio[1023]: info: CoreStateMachine::pushState Mar 24 13:22:23 volumio volumio[1023]: info: CorePlayQueue::getTrack 9 Mar 24 13:22:23 volumio volumio[1023]: info: CoreCommandRouter::volumioPushState Mar 24 13:22:23 volumio volumio[1023]: info: No code Mar 24 13:22:23 volumio volumio[1023]: info: CoreStateMachine::pushState Mar 24 13:22:23 volumio volumio[1023]: info: CorePlayQueue::getTrack 9 Mar 24 13:22:23 volumio volumio[1023]: info: CoreCommandRouter::volumioPushState Mar 24 13:22:23 volumio volumio[1023]: info: ControllerMpd::pushState Mar 24 13:22:23 volumio volumio[1023]: info: CoreCommandRouter::servicePushState Mar 24 13:22:23 volumio volumio[1023]: info: CoreStateMachine::pushState Mar 24 13:22:23 volumio volumio[1023]: info: CorePlayQueue::getTrack 9 Mar 24 13:22:23 volumio volumio[1023]: info: CoreCommandRouter::volumioPushState Mar 24 13:22:23 volumio volumio[1023]: info: CorePlayQueue::getTrack 9 Mar 24 13:22:23 volumio volumio[1023]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 24 13:22:23 volumio volumio[1023]: verbose: CURRENT POSITION 9 Mar 24 13:22:23 volumio volumio[1023]: info: CoreStateMachine::syncState stateService stop Mar 24 13:22:23 volumio volumio[1023]: info: CoreStateMachine::syncState currentStatus stop Mar 24 13:22:23 volumio volumio[1023]: info: CoreStateMachine::pushState Mar 24 13:22:23 volumio volumio[1023]: info: CorePlayQueue::getTrack 9 Mar 24 13:22:23 volumio volumio[1023]: info: CoreCommandRouter::volumioPushState Mar 24 13:22:23 volumio volumio[1023]: info: No code Mar 24 13:22:23 volumio volumio[1023]: info: CoreStateMachine::pushState Mar 24 13:22:23 volumio volumio[1023]: info: CorePlayQueue::getTrack 9 Mar 24 13:22:23 volumio volumio[1023]: info: CoreCommandRouter::volumioPushState Mar 24 13:22:23 volumio volumio[1023]: info: ControllerMpd::pushState Mar 24 13:22:23 volumio volumio[1023]: info: CoreCommandRouter::servicePushState Mar 24 13:22:23 volumio volumio[1023]: info: CoreStateMachine::pushState Mar 24 13:22:23 volumio volumio[1023]: info: CorePlayQueue::getTrack 9 Mar 24 13:22:23 volumio volumio[1023]: info: CoreCommandRouter::volumioPushState Mar 24 13:22:23 volumio volumio[1023]: info: CorePlayQueue::getTrack 9 Mar 24 13:22:23 volumio volumio[1023]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 24 13:22:23 volumio volumio[1023]: verbose: CURRENT POSITION 9 Mar 24 13:22:23 volumio volumio[1023]: info: CoreStateMachine::syncState stateService stop Mar 24 13:22:23 volumio volumio[1023]: info: CoreStateMachine::syncState currentStatus stop Mar 24 13:22:23 volumio volumio[1023]: info: CoreStateMachine::pushState Mar 24 13:22:23 volumio volumio[1023]: info: CorePlayQueue::getTrack 9 Mar 24 13:22:23 volumio volumio[1023]: info: CoreCommandRouter::volumioPushState Mar 24 13:22:23 volumio volumio[1023]: info: No code Mar 24 13:22:23 volumio volumio[1023]: info: CoreStateMachine::pushState Mar 24 13:22:23 volumio volumio[1023]: info: CorePlayQueue::getTrack 9 Mar 24 13:22:23 volumio volumio[1023]: info: CoreCommandRouter::volumioPushState Mar 24 13:22:23 volumio volumio[1023]: info: ------------------------------ 150ms Mar 24 13:22:23 volumio volumio[1023]: info: ------------------------------ 139ms Mar 24 13:22:23 volumio volumio[1023]: info: ------------------------------ 138ms Mar 24 13:22:23 volumio volumio[1023]: info: Mar 24 13:22:23 volumio volumio[1023]: ---------------------------- MPD announces system playlist update Mar 24 13:22:23 volumio volumio[1023]: info: Ignoring MPD Status Update Mar 24 13:22:23 volumio volumio[1023]: info: Mar 24 13:22:23 volumio volumio[1023]: ---------------------------- MPD announces system playlist update Mar 24 13:22:23 volumio volumio[1023]: info: Ignoring MPD Status Update Mar 24 13:22:23 volumio volumio[1023]: error: updateQueue error: null Mar 24 13:22:23 volumio volumio[1023]: info: Mar 24 13:22:23 volumio volumio[1023]: ---------------------------- MPD announces system playlist update Mar 24 13:22:23 volumio volumio[1023]: info: Ignoring MPD Status Update Mar 24 13:22:23 volumio volumio[1023]: info: ------------------------------ 143ms Mar 24 13:22:23 volumio volumio[1023]: info: sendMpdCommand add "USB/Musica/Música/Otros/Living Colour - Stain/Living Colour - Nothingness.mp3" took 140 milliseconds Mar 24 13:22:23 volumio volumio[1023]: info: ------------------------------ 8ms Mar 24 13:22:23 volumio volumio[1023]: info: ------------------------------ 7ms Mar 24 13:22:23 volumio volumio[1023]: verbose: ControllerMpd::sendMpdCommand play Mar 24 13:22:23 volumio volumio[1023]: info: Mar 24 13:22:23 volumio volumio[1023]: ---------------------------- MPD announces system playlist update Mar 24 13:22:23 volumio volumio[1023]: info: Ignoring MPD Status Update Mar 24 13:22:23 volumio volumio[1023]: info: Mar 24 13:22:23 volumio volumio[1023]: ---------------------------- MPD announces system playlist update Mar 24 13:22:23 volumio volumio[1023]: info: Ignoring MPD Status Update Mar 24 13:22:23 volumio volumio[1023]: info: Setting Geolocation for MyVolumio to eu3 Mar 24 13:22:23 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 13:22:23 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 13:22:23 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 13:22:23 volumio volumio[1023]: info: ------------------------------ 50ms Mar 24 13:22:23 volumio volumio[1023]: info: sendMpdCommand play took 46 milliseconds Mar 24 13:22:23 volumio volumio[1023]: info: ------------------------------ 45ms Mar 24 13:22:23 volumio volumio[1023]: info: ------------------------------ 44ms Mar 24 13:22:23 volumio volumio[1023]: info: Mar 24 13:22:23 volumio volumio[1023]: ---------------------------- MPD announces state update: player Mar 24 13:22:23 volumio volumio[1023]: info: ControllerMpd::getState Mar 24 13:22:23 volumio volumio[1023]: verbose: ControllerMpd::sendMpdCommand status Mar 24 13:22:23 volumio volumio[1023]: info: Mar 24 13:22:23 volumio volumio[1023]: ---------------------------- MPD announces state update: player Mar 24 13:22:23 volumio volumio[1023]: info: ControllerMpd::getState Mar 24 13:22:23 volumio volumio[1023]: verbose: ControllerMpd::sendMpdCommand status Mar 24 13:22:23 volumio volumio[1023]: info: Mar 24 13:22:23 volumio volumio[1023]: ---------------------------- MPD announces state update: player Mar 24 13:22:23 volumio volumio[1023]: info: ControllerMpd::getState Mar 24 13:22:23 volumio volumio[1023]: verbose: ControllerMpd::sendMpdCommand status Mar 24 13:22:23 volumio volumio[1023]: info: Mar 24 13:22:23 volumio volumio[1023]: ---------------------------- MPD announces state update: player Mar 24 13:22:23 volumio volumio[1023]: info: sendMpdCommand status took 5 milliseconds Mar 24 13:22:23 volumio volumio[1023]: info: ControllerMpd::getState Mar 24 13:22:23 volumio volumio[1023]: verbose: ControllerMpd::sendMpdCommand status Mar 24 13:22:23 volumio volumio[1023]: verbose: ControllerMpd::parseState Mar 24 13:22:23 volumio volumio[1023]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 13:22:23 volumio volumio[1023]: info: Mar 24 13:22:23 volumio volumio[1023]: ---------------------------- MPD announces state update: player Mar 24 13:22:23 volumio volumio[1023]: info: ControllerMpd::getState Mar 24 13:22:23 volumio volumio[1023]: verbose: ControllerMpd::sendMpdCommand status Mar 24 13:22:23 volumio volumio[1023]: info: Mar 24 13:22:23 volumio volumio[1023]: ---------------------------- MPD announces state update: player Mar 24 13:22:23 volumio volumio[1023]: info: ControllerMpd::getState Mar 24 13:22:23 volumio volumio[1023]: verbose: ControllerMpd::sendMpdCommand status Mar 24 13:22:23 volumio volumio[1023]: info: sendMpdCommand status took 10 milliseconds Mar 24 13:22:23 volumio volumio[1023]: info: sendMpdCommand status took 9 milliseconds Mar 24 13:22:23 volumio volumio[1023]: info: sendMpdCommand status took 8 milliseconds Mar 24 13:22:23 volumio volumio[1023]: info: sendMpdCommand playlistinfo took 7 milliseconds Mar 24 13:22:23 volumio volumio[1023]: info: sendMpdCommand status took 5 milliseconds Mar 24 13:22:23 volumio volumio[1023]: info: sendMpdCommand status took 4 milliseconds Mar 24 13:22:23 volumio volumio[1023]: verbose: ControllerMpd::parseState Mar 24 13:22:23 volumio volumio[1023]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 13:22:23 volumio volumio[1023]: verbose: ControllerMpd::parseState Mar 24 13:22:23 volumio volumio[1023]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 13:22:23 volumio volumio[1023]: verbose: ControllerMpd::parseState Mar 24 13:22:23 volumio volumio[1023]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 13:22:23 volumio volumio[1023]: verbose: ControllerMpd::parseTrackInfo Mar 24 13:22:23 volumio volumio[1023]: verbose: ControllerMpd::parseState Mar 24 13:22:23 volumio volumio[1023]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 13:22:23 volumio volumio[1023]: verbose: ControllerMpd::parseState Mar 24 13:22:23 volumio volumio[1023]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 13:22:23 volumio volumio[1023]: info: ControllerMpd::pushState Mar 24 13:22:23 volumio volumio[1023]: info: CoreCommandRouter::servicePushState Mar 24 13:22:23 volumio volumio[1023]: info: CorePlayQueue::getTrack 9 Mar 24 13:22:23 volumio volumio[1023]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":211,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Nothingness","artist":"Living Colour","album":"Stain","uri":"USB/Musica/Música/Otros/Living Colour - Stain/Living Colour - Nothingness.mp3","trackType":"mp3"} Mar 24 13:22:23 volumio volumio[1023]: verbose: CURRENT POSITION 9 Mar 24 13:22:23 volumio volumio[1023]: info: CoreStateMachine::syncState stateService play Mar 24 13:22:23 volumio volumio[1023]: info: CoreStateMachine::syncState currentStatus stop Mar 24 13:22:23 volumio volumio[1023]: info: ------------------------------ 24ms Mar 24 13:22:23 volumio volumio[1023]: info: sendMpdCommand playlistinfo took 9 milliseconds Mar 24 13:22:23 volumio volumio[1023]: info: sendMpdCommand playlistinfo took 8 milliseconds Mar 24 13:22:23 volumio volumio[1023]: info: sendMpdCommand playlistinfo took 8 milliseconds Mar 24 13:22:23 volumio volumio[1023]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 24 13:22:23 volumio volumio[1023]: info: sendMpdCommand playlistinfo took 7 milliseconds Mar 24 13:22:23 volumio volumio[1023]: verbose: ControllerMpd::parseTrackInfo Mar 24 13:22:23 volumio volumio[1023]: verbose: ControllerMpd::parseTrackInfo Mar 24 13:22:23 volumio volumio[1023]: verbose: ControllerMpd::parseTrackInfo Mar 24 13:22:23 volumio volumio[1023]: verbose: ControllerMpd::parseTrackInfo Mar 24 13:22:23 volumio volumio[1023]: verbose: ControllerMpd::parseTrackInfo Mar 24 13:22:23 volumio volumio[1023]: info: ControllerMpd::pushState Mar 24 13:22:23 volumio volumio[1023]: info: CoreCommandRouter::servicePushState Mar 24 13:22:23 volumio volumio[1023]: info: CorePlayQueue::getTrack 9 Mar 24 13:22:23 volumio volumio[1023]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":211,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Nothingness","artist":"Living Colour","album":"Stain","uri":"USB/Musica/Música/Otros/Living Colour - Stain/Living Colour - Nothingness.mp3","trackType":"mp3"} Mar 24 13:22:23 volumio volumio[1023]: verbose: CURRENT POSITION 9 Mar 24 13:22:23 volumio volumio[1023]: info: CoreStateMachine::syncState stateService play Mar 24 13:22:23 volumio volumio[1023]: info: CoreStateMachine::syncState currentStatus play Mar 24 13:22:23 volumio volumio[1023]: info: Received an update from plugin. extracting info from payload Mar 24 13:22:23 volumio volumio[1023]: info: CoreStateMachine::pushState Mar 24 13:22:23 volumio volumio[1023]: info: CorePlayQueue::getTrack 9 Mar 24 13:22:23 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 13:22:23 volumio volumio[1023]: info: CoreCommandRouter::volumioPushState Mar 24 13:22:23 volumio volumio[1023]: info: CoreStateMachine::pushState Mar 24 13:22:23 volumio volumio[1023]: info: CorePlayQueue::getTrack 9 Mar 24 13:22:23 volumio volumio[1023]: info: CoreCommandRouter::volumioPushState Mar 24 13:22:23 volumio volumio[1023]: info: ControllerMpd::pushState Mar 24 13:22:23 volumio volumio[1023]: info: CoreCommandRouter::servicePushState Mar 24 13:22:23 volumio volumio[1023]: info: CorePlayQueue::getTrack 9 Mar 24 13:22:23 volumio volumio[1023]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":211,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Nothingness","artist":"Living Colour","album":"Stain","uri":"USB/Musica/Música/Otros/Living Colour - Stain/Living Colour - Nothingness.mp3","trackType":"mp3"} Mar 24 13:22:23 volumio volumio[1023]: verbose: CURRENT POSITION 9 Mar 24 13:22:23 volumio volumio[1023]: info: CoreStateMachine::syncState stateService play Mar 24 13:22:23 volumio volumio[1023]: info: CoreStateMachine::syncState currentStatus play Mar 24 13:22:23 volumio volumio[1023]: info: Received an update from plugin. extracting info from payload Mar 24 13:22:23 volumio volumio[1023]: info: CoreStateMachine::pushState Mar 24 13:22:23 volumio volumio[1023]: info: CorePlayQueue::getTrack 9 Mar 24 13:22:23 volumio volumio[1023]: info: CoreCommandRouter::volumioPushState Mar 24 13:22:23 volumio volumio[1023]: info: CoreStateMachine::pushState Mar 24 13:22:23 volumio volumio[1023]: info: CorePlayQueue::getTrack 9 Mar 24 13:22:23 volumio volumio[1023]: info: CoreCommandRouter::volumioPushState Mar 24 13:22:23 volumio volumio[1023]: info: ControllerMpd::pushState Mar 24 13:22:23 volumio volumio[1023]: info: CoreCommandRouter::servicePushState Mar 24 13:22:23 volumio volumio[1023]: info: CorePlayQueue::getTrack 9 Mar 24 13:22:23 volumio volumio[1023]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":211,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Nothingness","artist":"Living Colour","album":"Stain","uri":"USB/Musica/Música/Otros/Living Colour - Stain/Living Colour - Nothingness.mp3","trackType":"mp3"} Mar 24 13:22:23 volumio volumio[1023]: verbose: CURRENT POSITION 9 Mar 24 13:22:23 volumio volumio[1023]: info: CoreStateMachine::syncState stateService play Mar 24 13:22:23 volumio volumio[1023]: info: CoreStateMachine::syncState currentStatus play Mar 24 13:22:23 volumio volumio[1023]: info: Received an update from plugin. extracting info from payload Mar 24 13:22:23 volumio volumio[1023]: info: CoreStateMachine::pushState Mar 24 13:22:23 volumio volumio[1023]: info: CorePlayQueue::getTrack 9 Mar 24 13:22:23 volumio volumio[1023]: info: CoreCommandRouter::volumioPushState Mar 24 13:22:23 volumio volumio[1023]: info: CoreStateMachine::pushState Mar 24 13:22:23 volumio volumio[1023]: info: CorePlayQueue::getTrack 9 Mar 24 13:22:23 volumio volumio[1023]: info: CoreCommandRouter::volumioPushState Mar 24 13:22:23 volumio volumio[1023]: info: ControllerMpd::pushState Mar 24 13:22:23 volumio volumio[1023]: info: CoreCommandRouter::servicePushState Mar 24 13:22:23 volumio volumio[1023]: info: CorePlayQueue::getTrack 9 Mar 24 13:22:23 volumio volumio[1023]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":211,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Nothingness","artist":"Living Colour","album":"Stain","uri":"USB/Musica/Música/Otros/Living Colour - Stain/Living Colour - Nothingness.mp3","trackType":"mp3"} Mar 24 13:22:23 volumio volumio[1023]: verbose: CURRENT POSITION 9 Mar 24 13:22:23 volumio volumio[1023]: info: CoreStateMachine::syncState stateService play Mar 24 13:22:23 volumio volumio[1023]: info: CoreStateMachine::syncState currentStatus play Mar 24 13:22:23 volumio volumio[1023]: info: Received an update from plugin. extracting info from payload Mar 24 13:22:23 volumio volumio[1023]: info: CoreStateMachine::pushState Mar 24 13:22:23 volumio volumio[1023]: info: CorePlayQueue::getTrack 9 Mar 24 13:22:23 volumio volumio[1023]: info: CoreCommandRouter::volumioPushState Mar 24 13:22:23 volumio volumio[1023]: info: CoreStateMachine::pushState Mar 24 13:22:23 volumio volumio[1023]: info: CorePlayQueue::getTrack 9 Mar 24 13:22:23 volumio volumio[1023]: info: CoreCommandRouter::volumioPushState Mar 24 13:22:23 volumio volumio[1023]: info: ControllerMpd::pushState Mar 24 13:22:23 volumio volumio[1023]: info: CoreCommandRouter::servicePushState Mar 24 13:22:23 volumio volumio[1023]: info: CorePlayQueue::getTrack 9 Mar 24 13:22:23 volumio volumio[1023]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":211,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Nothingness","artist":"Living Colour","album":"Stain","uri":"USB/Musica/Música/Otros/Living Colour - Stain/Living Colour - Nothingness.mp3","trackType":"mp3"} Mar 24 13:22:23 volumio volumio[1023]: verbose: CURRENT POSITION 9 Mar 24 13:22:23 volumio volumio[1023]: info: CoreStateMachine::syncState stateService play Mar 24 13:22:23 volumio volumio[1023]: info: CoreStateMachine::syncState currentStatus play Mar 24 13:22:23 volumio volumio[1023]: info: Received an update from plugin. extracting info from payload Mar 24 13:22:23 volumio volumio[1023]: info: CoreStateMachine::pushState Mar 24 13:22:23 volumio volumio[1023]: info: CorePlayQueue::getTrack 9 Mar 24 13:22:23 volumio volumio[1023]: info: CoreCommandRouter::volumioPushState Mar 24 13:22:23 volumio volumio[1023]: info: CoreStateMachine::pushState Mar 24 13:22:23 volumio volumio[1023]: info: CorePlayQueue::getTrack 9 Mar 24 13:22:23 volumio volumio[1023]: info: CoreCommandRouter::volumioPushState Mar 24 13:22:23 volumio volumio[1023]: info: ------------------------------ 189ms Mar 24 13:22:23 volumio volumio[1023]: info: ------------------------------ 188ms Mar 24 13:22:23 volumio volumio[1023]: info: ------------------------------ 188ms Mar 24 13:22:23 volumio volumio[1023]: info: ------------------------------ 186ms Mar 24 13:22:23 volumio volumio[1023]: info: ------------------------------ 185ms Mar 24 13:22:24 volumio volumio[1023]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Mar 24 13:22:24 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 13:22:24 volumio volumio[1023]: info: Received Get System Info Mar 24 13:22:24 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 13:22:24 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 13:22:24 volumio volumio[1023]: info: Discovery: Getting this device information Mar 24 13:22:24 volumio volumio[1023]: info: CoreCommandRouter::volumioGetState Mar 24 13:22:24 volumio volumio[1023]: info: CorePlayQueue::getTrack 9 Mar 24 13:22:24 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 13:22:24 volumio volumio[1023]: info: Updating MyVolumio device info Mar 24 13:22:24 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 13:22:24 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 13:22:24 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 13:22:24 volumio volumio[1023]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Mar 24 13:22:29 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 24 13:22:30 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 24 13:22:30 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 24 13:22:31 volumio sudo[8935]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 24 13:22:31 volumio sudo[8935]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 13:22:31 volumio sudo[8935]: pam_unix(sudo:session): session closed for user root Mar 24 13:22:31 volumio sudo[8937]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 24 13:22:31 volumio sudo[8937]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 13:22:31 volumio sudo[8937]: pam_unix(sudo:session): session closed for user root Mar 24 13:22:31 volumio volumio[1023]: verbose: New Socket.io Connection to 192.168.1.147 from 192.168.1.128 UA: Mozilla/5.0 (Linux; Android 12; Mi Note 10 Lite Build/SKQ1.210908.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/146.0.7680.119 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Mar 24 13:22:31 volumio sudo[8943]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 24 13:22:31 volumio sudo[8943]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 13:22:31 volumio sudo[8943]: pam_unix(sudo:session): session closed for user root Mar 24 13:22:31 volumio sudo[8945]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 24 13:22:31 volumio sudo[8945]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 13:22:31 volumio sudo[8945]: pam_unix(sudo:session): session closed for user root Mar 24 13:22:31 volumio volumio[1023]: verbose: New Socket.io Connection to 192.168.1.147 from 192.168.1.128 UA: Mozilla/5.0 (Linux; Android 12; Mi Note 10 Lite Build/SKQ1.210908.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/146.0.7680.119 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Mar 24 13:22:31 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 24 13:22:31 volumio volumio[1023]: info: CoreCommandRouter::volumioGetVisibleSources Mar 24 13:22:31 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 24 13:22:31 volumio volumio[1023]: info: CoreCommandRouter::volumioGetState Mar 24 13:22:31 volumio volumio[1023]: info: CorePlayQueue::getTrack 9 Mar 24 13:22:31 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 24 13:22:31 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 24 13:22:31 volumio volumio[1023]: info: Received Get System Info Mar 24 13:22:31 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 13:22:31 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 13:22:31 volumio volumio[1023]: info: Discovery: Getting this device information Mar 24 13:22:31 volumio volumio[1023]: info: CoreCommandRouter::volumioGetState Mar 24 13:22:31 volumio volumio[1023]: info: CorePlayQueue::getTrack 9 Mar 24 13:22:31 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 13:22:31 volumio volumio[1023]: info: CoreCommandRouter::volumioGetState Mar 24 13:22:31 volumio volumio[1023]: info: CorePlayQueue::getTrack 9 Mar 24 13:22:31 volumio volumio[1023]: info: Listing playlists Mar 24 13:22:31 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 24 13:22:31 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 24 13:22:31 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 24 13:22:33 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Mar 24 13:22:33 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 13:22:33 volumio volumio[1023]: info: Received Get System Info Mar 24 13:22:33 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 13:22:33 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 13:22:33 volumio volumio[1023]: info: Discovery: Getting this device information Mar 24 13:22:33 volumio volumio[1023]: info: CoreCommandRouter::volumioGetState Mar 24 13:22:33 volumio volumio[1023]: info: CorePlayQueue::getTrack 9 Mar 24 13:22:33 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 13:22:34 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 13:22:34 volumio volumio[1023]: info: Received Get System Info Mar 24 13:22:34 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 13:22:34 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 13:22:34 volumio volumio[1023]: info: Discovery: Getting this device information Mar 24 13:22:34 volumio volumio[1023]: info: CoreCommandRouter::volumioGetState Mar 24 13:22:34 volumio volumio[1023]: info: CorePlayQueue::getTrack 9 Mar 24 13:22:34 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 13:22:36 volumio upmpdcli[8964]: writing RSA key Mar 24 13:22:37 volumio volumio[1023]: info: CoreCommandRouter::volumioGetQueue Mar 24 13:22:37 volumio volumio[1023]: info: CoreStateMachine::getQueue Mar 24 13:22:37 volumio volumio[1023]: info: CorePlayQueue::getQueue Mar 24 13:22:38 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 24 13:22:38 volumio volumio[1023]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 24 13:22:38 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 24 13:22:38 volumio volumio[1023]: info: Received Get System Version Mar 24 13:22:38 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 24 13:22:38 volumio volumio[1023]: info: Received Get System Info Mar 24 13:22:38 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 13:22:38 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 13:22:38 volumio volumio[1023]: info: Discovery: Getting this device information Mar 24 13:22:38 volumio volumio[1023]: info: CoreCommandRouter::volumioGetState Mar 24 13:22:38 volumio volumio[1023]: info: CorePlayQueue::getTrack 9 Mar 24 13:22:38 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 13:22:41 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 24 13:22:41 volumio volumio[1023]: info: CURURI: music-library Mar 24 13:22:41 volumio volumio[1023]: info: Preload queue cleared Mar 24 13:22:41 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 24 13:22:42 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 24 13:22:42 volumio volumio[1023]: info: CURURI: music-library/USB Mar 24 13:22:42 volumio volumio[1023]: info: Preload queue cleared Mar 24 13:22:44 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 24 13:22:44 volumio volumio[1023]: info: CURURI: music-library/USB/Musica Mar 24 13:22:44 volumio volumio[1023]: info: Preload queue cleared Mar 24 13:22:45 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 24 13:22:45 volumio volumio[1023]: info: CURURI: music-library/USB/Musica/Música Mar 24 13:22:45 volumio volumio[1023]: info: Preload queue cleared Mar 24 13:22:45 volumio volumio[1023]: info: Preloading song: music-library/USB/Musica/Música/Wynton Marsalis & Eric Clapton - Play the Blues (2011) DVDRip.avi Mar 24 13:22:45 volumio volumio[1023]: info: Exploding uri music-library/USB/Musica/Música/Wynton Marsalis & Eric Clapton - Play the Blues (2011) DVDRip.avi in service mpd Mar 24 13:22:45 volumio volumio[1023]: info: ALBUMART /albumart?cacheid=973&web=//extralarge&path=%2Fmnt%2FUSB%2FMusica%2FM%C3%BAsica%2FWynton%20Marsalis%20%26%20Eric%20Clapton%20-%20Play%20the%20Blues%20(2011)%20DVDRip.avi&metadata=false Mar 24 13:22:45 volumio volumio[1023]: info: URI /mnt/USB/Musica/Música/Wynton Marsalis & Eric Clapton - Play the Blues (2011) DVDRip.avi Mar 24 13:22:49 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 24 13:22:49 volumio volumio[1023]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 24 13:22:49 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 24 13:22:49 volumio volumio[1023]: info: Received Get System Version Mar 24 13:22:49 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 24 13:22:49 volumio volumio[1023]: info: Received Get System Info Mar 24 13:22:49 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 13:22:49 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 13:22:49 volumio volumio[1023]: info: Discovery: Getting this device information Mar 24 13:22:49 volumio volumio[1023]: info: CoreCommandRouter::volumioGetState Mar 24 13:22:49 volumio volumio[1023]: info: CorePlayQueue::getTrack 9 Mar 24 13:22:49 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 13:22:49 volumio volumio[1023]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 24 13:22:49 volumio volumio[1023]: info: CoreStateMachine::ClearQueue Mar 24 13:22:49 volumio volumio[1023]: info: CoreStateMachine::stop Mar 24 13:22:49 volumio volumio[1023]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 13:22:49 volumio volumio[1023]: info: CoreStateMachine::stPlaybackTimer Mar 24 13:22:49 volumio volumio[1023]: info: CoreStateMachine::updateTrackBlock Mar 24 13:22:49 volumio volumio[1023]: info: CorePlayQueue::getTrackBlock Mar 24 13:22:49 volumio volumio[1023]: info: CoreStateMachine::pushState Mar 24 13:22:49 volumio volumio[1023]: info: CorePlayQueue::getTrack 9 Mar 24 13:22:49 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 13:22:49 volumio volumio[1023]: info: CoreCommandRouter::volumioPushState Mar 24 13:22:49 volumio volumio[1023]: info: CoreStateMachine::serviceStop Mar 24 13:22:49 volumio volumio[1023]: info: CorePlayQueue::getTrack 9 Mar 24 13:22:49 volumio volumio[1023]: info: CoreCommandRouter::serviceStop Mar 24 13:22:49 volumio volumio[1023]: info: ControllerMpd::stop Mar 24 13:22:49 volumio volumio[1023]: verbose: ControllerMpd::sendMpdCommand stop Mar 24 13:22:49 volumio volumio[1023]: info: CorePlayQueue::clearPlayQueue Mar 24 13:22:49 volumio volumio[1023]: info: CorePlayQueue::saveQueue Mar 24 13:22:49 volumio volumio[1023]: info: CoreCommandRouter::volumioPushQueue Mar 24 13:22:49 volumio volumio[1023]: info: CoreStateMachine::addQueueItems Mar 24 13:22:49 volumio volumio[1023]: info: CorePlayQueue::addQueueItems Mar 24 13:22:49 volumio volumio[1023]: info: Preload queue cleared Mar 24 13:22:49 volumio volumio[1023]: info: Adding Item to queue: music-library/USB/Musica/Música/Jazz Mar 24 13:22:49 volumio volumio[1023]: info: Exploding uri music-library/USB/Musica/Música/Jazz in service mpd Mar 24 13:22:58 volumio volumio[1023]: info: Mar 24 13:22:58 volumio volumio[1023]: ---------------------------- MPD announces state update: player Mar 24 13:22:58 volumio volumio[1023]: info: ALBUMART /albumart?cacheid=973&web=Cal%20Tjader/Cuban%20Fantasy/extralarge&path=%2Fmnt%2FUSB%2FMusica%2FM%C3%BAsica%2FJazz%2F1977.%20Cal%20Tjader%20-%20Cuban%20Fantasy%2F01.%20Cuban%20Fantasy.flac&metadata=false Mar 24 13:22:58 volumio volumio[1023]: info: URI /mnt/USB/Musica/Música/Jazz/1977. Cal Tjader - Cuban Fantasy/01. Cuban Fantasy.flac Mar 24 13:22:58 volumio volumio[1023]: info: ALBUMART /albumart?cacheid=973&web=Cal%20Tjader/Cuban%20Fantasy/extralarge&path=%2Fmnt%2FUSB%2FMusica%2FM%C3%BAsica%2FJazz%2F1977.%20Cal%20Tjader%20-%20Cuban%20Fantasy%2F02.%20Guarabe.flac&metadata=false Mar 24 13:22:58 volumio volumio[1023]: info: URI /mnt/USB/Musica/Música/Jazz/1977. Cal Tjader - Cuban Fantasy/02. Guarabe.flac Mar 24 13:22:58 volumio volumio[1023]: info: ALBUMART /albumart?cacheid=973&web=Cal%20Tjader/Cuban%20Fantasy/extralarge&path=%2Fmnt%2FUSB%2FMusica%2FM%C3%BAsica%2FJazz%2F1977.%20Cal%20Tjader%20-%20Cuban%20Fantasy%2F03.%20Tamanco%20No%20Samba%20(Samba%20Blim).flac&metadata=false Mar 24 13:22:58 volumio volumio[1023]: info: URI /mnt/USB/Musica/Música/Jazz/1977. Cal Tjader - Cuban Fantasy/03. Tamanco No Samba (Samba Blim).flac Mar 24 13:22:58 volumio volumio[1023]: info: ALBUMART /albumart?cacheid=973&web=Cal%20Tjader/Cuban%20Fantasy/extralarge&path=%2Fmnt%2FUSB%2FMusica%2FM%C3%BAsica%2FJazz%2F1977.%20Cal%20Tjader%20-%20Cuban%20Fantasy%2F04.%20Tu%20Crees%20Que.flac&metadata=false Mar 24 13:22:58 volumio volumio[1023]: info: URI /mnt/USB/Musica/Música/Jazz/1977. Cal Tjader - Cuban Fantasy/04. Tu Crees Que.flac Mar 24 13:22:58 volumio volumio[1023]: info: ALBUMART /albumart?cacheid=973&web=Cal%20Tjader/Cuban%20Fantasy/extralarge&path=%2Fmnt%2FUSB%2FMusica%2FM%C3%BAsica%2FJazz%2F1977.%20Cal%20Tjader%20-%20Cuban%20Fantasy%2F05.%20Silenciosa.flac&metadata=false Mar 24 13:22:58 volumio volumio[1023]: info: URI /mnt/USB/Musica/Música/Jazz/1977. Cal Tjader - Cuban Fantasy/05. Silenciosa.flac Mar 24 13:22:58 volumio volumio[1023]: info: ALBUMART /albumart?cacheid=973&web=Cal%20Tjader/Cuban%20Fantasy/extralarge&path=%2Fmnt%2FUSB%2FMusica%2FM%C3%BAsica%2FJazz%2F1977.%20Cal%20Tjader%20-%20Cuban%20Fantasy%2F06.%20Descarga%20Cachao.flac&metadata=false Mar 24 13:22:58 volumio volumio[1023]: info: URI /mnt/USB/Musica/Música/Jazz/1977. Cal Tjader - Cuban Fantasy/06. Descarga Cachao.flac Mar 24 13:22:58 volumio volumio[1023]: info: ALBUMART /albumart?cacheid=973&web=Cal%20Tjader/Cuban%20Fantasy/extralarge&path=%2Fmnt%2FUSB%2FMusica%2FM%C3%BAsica%2FJazz%2F1977.%20Cal%20Tjader%20-%20Cuban%20Fantasy%2F07.%20Manuel%20Deeghit.flac&metadata=false Mar 24 13:22:58 volumio volumio[1023]: info: URI /mnt/USB/Musica/Música/Jazz/1977. Cal Tjader - Cuban Fantasy/07. Manuel Deeghit.flac Mar 24 13:22:58 volumio volumio[1023]: info: ALBUMART /albumart?cacheid=973&web=Cal%20Tjader/Cuban%20Fantasy/extralarge&path=%2Fmnt%2FUSB%2FMusica%2FM%C3%BAsica%2FJazz%2F1977.%20Cal%20Tjader%20-%20Cuban%20Fantasy%2F08.%20Guachi%20Guara.flac&metadata=false Mar 24 13:22:58 volumio volumio[1023]: info: URI /mnt/USB/Musica/Música/Jazz/1977. Cal Tjader - Cuban Fantasy/08. Guachi Guara.flac Mar 24 13:22:58 volumio volumio[1023]: info: ALBUMART /albumart?cacheid=973&web=Cal%20Tjader/Cuban%20Fantasy/extralarge&path=%2Fmnt%2FUSB%2FMusica%2FM%C3%BAsica%2FJazz%2F1977.%20Cal%20Tjader%20-%20Cuban%20Fantasy%2Fedit.flac.cue&metadata=false Mar 24 13:22:58 volumio volumio[1023]: info: URI /mnt/USB/Musica/Música/Jazz/1977. Cal Tjader - Cuban Fantasy/edit.flac.cue Mar 24 13:22:58 volumio volumio[1023]: info: ALBUMART /albumart?cacheid=973&web=Cal%20Tjader/Cuban%20Fantasy/extralarge&path=%2Fmnt%2FUSB%2FMusica%2FM%C3%BAsica%2FJazz%2F1977.%20Cal%20Tjader%20-%20Cuban%20Fantasy%2Fedit.flac.cue&metadata=false Mar 24 13:22:58 volumio volumio[1023]: info: URI /mnt/USB/Musica/Música/Jazz/1977. Cal Tjader - Cuban Fantasy/edit.flac.cue Mar 24 13:22:58 volumio volumio[1023]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 24 13:22:58 volumio volumio[1023]: Error: Unable to resolve or reject the same promise twice Mar 24 13:22:58 volumio volumio[1023]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Mar 24 13:22:58 volumio volumio[1023]: at /volumio/app/plugins/music_service/mpd/index.js:2582:21 Mar 24 13:22:58 volumio volumio[1023]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Mar 24 13:22:58 volumio volumio[1023]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Mar 24 13:22:58 volumio volumio[1023]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Mar 24 13:22:58 volumio volumio[1023]: at Socket.emit (node:events:514:28) Mar 24 13:22:58 volumio volumio[1023]: at addChunk (node:internal/streams/readable:343:12) Mar 24 13:22:58 volumio volumio[1023]: at readableAddChunk (node:internal/streams/readable:312:11) Mar 24 13:22:58 volumio volumio[1023]: at Readable.push (node:internal/streams/readable:253:10) Mar 24 13:22:58 volumio volumio[1023]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Mar 24 13:22:58 volumio volumio[1023]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 24 13:22:59 volumio sudo[9054]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-24 13:21' Mar 24 13:22:59 volumio sudo[9054]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="dc61260dec5515dafd2b634881860b4c46c919ff" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Mar 6 16:46:58 UTC 2026" VOLUMIO_VERSION="4.103" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="aa741395b8bfc648ff5d04e312f53d2c"