Jun 06 16:16:02 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jun 06 16:16:02 volumio dbus-daemon[621]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.10359' (uid=0 pid=17699 comm="timedatectl show --property=NTPSynchronized --valu") Jun 06 16:16:02 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jun 06 16:16:02 volumio dbus-daemon[621]: [system] Successfully activated service 'org.freedesktop.timedate1' Jun 06 16:16:02 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Jun 06 16:16:02 volumio setdatetime-helper.sh[17698]: Time is already synchronized. Jun 06 16:16:02 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jun 06 16:16:02 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jun 06 16:16:06 volumio volumio[17309]: info: CoreCommandRouter::volumioGetState Jun 06 16:16:06 volumio volumio[17309]: info: Listing playlists Jun 06 16:16:06 volumio volumio[17309]: info: Listing playlists Jun 06 16:16:16 volumio volumio[17309]: info: CoreCommandRouter::volumioGetState Jun 06 16:16:26 volumio volumio[17309]: info: CoreCommandRouter::volumioGetState Jun 06 16:16:26 volumio volumio[17309]: info: Listing playlists Jun 06 16:16:26 volumio volumio[17309]: info: Listing playlists Jun 06 16:16:32 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. Jun 06 16:16:33 volumio sudo[17793]: volumio : TTY=pts/0 ; PWD=/home/volumio/volumio-plugins-sources-bookworm/keepclock ; USER=root ; COMMAND=/bin/systemctl restart volumio Jun 06 16:16:33 volumio sudo[17793]: pam_unix(sudo:session): session opened for user root(uid=0) by volumio(uid=1000) Jun 06 16:16:33 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Jun 06 16:16:33 volumio systemd[1]: Stopping volumio.service - Volumio Backend Module... Jun 06 16:16:33 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Jun 06 16:16:33 volumio volumio-remote-updater[649]: [2025-06-06 16:16:33] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jun 06 16:16:33 volumio volumio-remote-updater[649]: [2025-06-06 16:16:33] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jun 06 16:16:33 volumio go-librespot[17500]: time="2025-06-06T16:16:33+02:00" level=error msg="websocket connection errored" error="failed to get reader: failed to read frame header: EOF" Jun 06 16:16:33 volumio systemd[1]: volumio.service: Deactivated successfully. Jun 06 16:16:33 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Jun 06 16:16:33 volumio systemd[1]: volumio.service: Consumed 38.696s CPU time. Jun 06 16:16:33 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Jun 06 16:16:33 volumio sudo[17793]: pam_unix(sudo:session): session closed for user root Jun 06 16:16:34 volumio volumio[17804]: info: ------------------------------------------- Jun 06 16:16:34 volumio volumio[17804]: info: ----- Volumio3 ---- Jun 06 16:16:34 volumio volumio[17804]: info: ------------------------------------------- Jun 06 16:16:34 volumio volumio[17804]: info: ----- System startup ---- Jun 06 16:16:34 volumio volumio[17804]: info: ------------------------------------------- Jun 06 16:16:34 volumio volumio[17804]: info: MYVOLUMIO Environment detected Jun 06 16:16:35 volumio volumio[17804]: info: Plugin folders cleanup Jun 06 16:16:35 volumio volumio[17804]: info: Scanning into folder /volumio/app/plugins/ Jun 06 16:16:35 volumio volumio[17804]: info: Scanning category audio_interface Jun 06 16:16:35 volumio volumio[17804]: info: Scanning category miscellanea Jun 06 16:16:35 volumio volumio[17804]: info: Scanning category music_service Jun 06 16:16:35 volumio volumio[17804]: info: Scanning category plugins.json Jun 06 16:16:35 volumio volumio[17804]: info: Scanning category system_controller Jun 06 16:16:35 volumio volumio[17804]: info: Scanning category user_interface Jun 06 16:16:35 volumio volumio[17804]: info: Scanning into folder /data/plugins/ Jun 06 16:16:35 volumio volumio[17804]: info: Scanning category music_service Jun 06 16:16:35 volumio volumio[17804]: info: Plugin folders cleanup completed Jun 06 16:16:35 volumio volumio[17804]: info: ------------------------------------------- Jun 06 16:16:35 volumio volumio[17804]: info: ----- Core plugins startup ---- Jun 06 16:16:35 volumio volumio[17804]: info: ------------------------------------------- Jun 06 16:16:35 volumio volumio[17804]: info: Loading plugins from folder /volumio/app/plugins/ Jun 06 16:16:35 volumio volumio[17804]: info: Adding plugin upnp to MyMusic Plugins Jun 06 16:16:35 volumio volumio[17804]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 06 16:16:35 volumio volumio[17804]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 06 16:16:35 volumio volumio[17804]: info: Loading plugins from folder /data/plugins/ Jun 06 16:16:35 volumio volumio[17804]: info: Loading plugin "system"... Jun 06 16:16:35 volumio volumio[17804]: info: Loading plugin "appearance"... Jun 06 16:16:35 volumio volumio[17804]: info: Loading plugin "network"... Jun 06 16:16:35 volumio volumio[17804]: info: Refreshing Cached IP Addresses Jun 06 16:16:35 volumio sudo[17827]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 06 16:16:35 volumio sudo[17827]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 16:16:35 volumio sudo[17829]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 06 16:16:35 volumio sudo[17829]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 16:16:35 volumio sudo[17827]: pam_unix(sudo:session): session closed for user root Jun 06 16:16:35 volumio sudo[17829]: pam_unix(sudo:session): session closed for user root Jun 06 16:16:35 volumio volumio[17804]: info: Loading plugin "services"... Jun 06 16:16:35 volumio volumio[17804]: info: Loading plugin "alsa_controller"... Jun 06 16:16:35 volumio sudo[17842]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 06 16:16:35 volumio sudo[17842]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 16:16:35 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 06 16:16:35 volumio volumio[17804]: info: Loading plugin "wizard"... Jun 06 16:16:35 volumio volumio[17804]: info: Loading plugin "networkfs"... Jun 06 16:16:35 volumio volumio[17804]: info: Starting Udev Watcher for removable devices Jun 06 16:16:35 volumio volumio[17804]: info: Ignoring mount for partition: boot Jun 06 16:16:35 volumio volumio[17804]: info: Ignoring mount for partition: volumio Jun 06 16:16:35 volumio volumio[17804]: info: Ignoring mount for partition: volumio_data Jun 06 16:16:35 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 06 16:16:35 volumio volumio[17804]: info: Loading plugin "volumio_command_line_client"... Jun 06 16:16:35 volumio volumio[17804]: info: Loading plugin "upnp"... Jun 06 16:16:35 volumio volumio[17804]: info: [1749219395746] Starting Upmpd Daemon Jun 06 16:16:35 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 06 16:16:35 volumio volumio[17804]: info: Loading plugin "my_music"... Jun 06 16:16:35 volumio volumio[17804]: info: Loading plugin "mpd"... Jun 06 16:16:35 volumio volumio[17804]: info: Loading plugin "upnp_browser"... Jun 06 16:16:36 volumio volumio[17804]: info: Loading plugin "alarm-clock"... Jun 06 16:16:36 volumio volumio[17804]: info: Loading plugin "airplay_emulation"... Jun 06 16:16:36 volumio volumio[17804]: info: Starting Shairport Sync Jun 06 16:16:36 volumio volumio[17804]: info: Loading plugin "last_100"... Jun 06 16:16:36 volumio volumio[17804]: info: Loading plugin "webradio"... Jun 06 16:16:36 volumio volumio[17804]: info: Loading plugin "i2s_dacs"... Jun 06 16:16:36 volumio volumio[17804]: info: Loading plugin "volumiodiscovery"... Jun 06 16:16:36 volumio volumio[17804]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 06 16:16:36 volumio volumio[17804]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 06 16:16:36 volumio volumio[17804]: *** WARNING *** For more information see Jun 06 16:16:36 volumio volumio[17804]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 06 16:16:36 volumio volumio[17804]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 06 16:16:36 volumio node[17804]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 06 16:16:36 volumio volumio[17804]: *** WARNING *** For more information see Jun 06 16:16:36 volumio node[17804]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 06 16:16:36 volumio node[17804]: *** WARNING *** For more information see Jun 06 16:16:36 volumio node[17804]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 06 16:16:36 volumio node[17804]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 06 16:16:36 volumio node[17804]: *** WARNING *** For more information see Jun 06 16:16:36 volumio volumio[17804]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 06 16:16:36 volumio volumio[17804]: info: Discovery: Started advertising with name: Volumio Jun 06 16:16:36 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 06 16:16:36 volumio volumio[17804]: info: Loading plugin "bandcamp"... Jun 06 16:16:37 volumio volumio[17804]: info: Loading plugin "soundcloud"... Jun 06 16:16:37 volumio volumio[17804]: info: Loading plugin "spop"... Jun 06 16:16:38 volumio volumio[17804]: info: Loading plugin "youtube2"... Jun 06 16:16:38 volumio volumio-remote-updater[649]: [2025-06-06 16:16:38] [connect] Successful connection Jun 06 16:16:38 volumio sudo[17842]: pam_unix(sudo:session): session closed for user root Jun 06 16:16:39 volumio volumio[17804]: info: Loading plugin "ytcr"... Jun 06 16:16:41 volumio volumio[17804]: info: Loading plugin "ytmusic"... Jun 06 16:16:42 volumio volumio[17804]: info: Loading plugin "outputs"... Jun 06 16:16:42 volumio volumio[17804]: info: Loading plugin "albumart"... Jun 06 16:16:42 volumio volumio[17804]: info: Plugin example_plugin is not enabled Jun 06 16:16:42 volumio volumio[17804]: info: Loading plugin "inputs"... Jun 06 16:16:42 volumio volumio[17804]: info: Loading plugin "updater_comm"... Jun 06 16:16:42 volumio volumio[17804]: info: Plugin mpdemulation is not enabled Jun 06 16:16:42 volumio volumio[17804]: info: Loading plugin "rest_api"... Jun 06 16:16:42 volumio volumio[17804]: info: Loading plugin "websocket"... Jun 06 16:16:42 volumio volumio[17804]: info: Starting Socket.io Server version 1.7.4 Jun 06 16:16:42 volumio volumio[17804]: info: Loading plugin "keepclock"... Jun 06 16:16:43 volumio volumio[17804]: info: Applying required configuration parameters for plugin keepclock Jun 06 16:16:43 volumio volumio[17804]: info: Loading i18n strings for locale fr Jun 06 16:16:43 volumio volumio[17804]: Updating browse sources language Jun 06 16:16:43 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 06 16:16:43 volumio volumio[17860]: Forking 3 albumart workers Jun 06 16:16:43 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 06 16:16:43 volumio volumio[17804]: info: CoreCommandRouter::initPlayerControls Jun 06 16:16:43 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 16:16:43 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 16:16:43 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 16:16:43 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 16:16:43 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 16:16:43 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 16:16:43 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 16:16:43 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 16:16:43 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 06 16:16:43 volumio volumio[17804]: Express server listening on port 3000 Jun 06 16:16:43 volumio volumio[17804]: [Metrics] WebUI: 8s 887.49ms Jun 06 16:16:43 volumio volumio[17804]: info: CoreStateMachine::resetVolumioState Jun 06 16:16:43 volumio volumio[17804]: info: CoreStateMachine::getcurrentVolume Jun 06 16:16:43 volumio volumio[17804]: info: CoreCommandRouter::volumioRetrievevolume Jun 06 16:16:43 volumio volumio[17804]: info: Volumio Network Manager: Network status updated: 2 Jun 06 16:16:43 volumio volumio[17804]: verbose: New Socket.io Connection to 10.20.2.67 from 10.20.2.72 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Jun 06 16:16:43 volumio volumio[17804]: info: CoreStateMachine::pushState Jun 06 16:16:43 volumio volumio[17804]: info: CorePlayQueue::getTrack 0 Jun 06 16:16:43 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 06 16:16:43 volumio volumio[17804]: info: CoreCommandRouter::volumioPushState Jun 06 16:16:43 volumio volumio[17804]: info: CoreStateMachine::updateTrackBlock Jun 06 16:16:43 volumio volumio[17804]: info: CorePlayQueue::getTrackBlock Jun 06 16:16:43 volumio volumio[17804]: info: CoreCommandRouter::volumioRetrievevolume Jun 06 16:16:43 volumio volumio[17804]: verbose: New Socket.io Connection to 10.20.2.67 from 10.20.2.72 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Jun 06 16:16:43 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 06 16:16:43 volumio volumio[17804]: info: Reloading queue from file Jun 06 16:16:43 volumio volumio[17804]: info: CoreStateMachine::pushState Jun 06 16:16:43 volumio volumio[17804]: info: CorePlayQueue::getTrack 0 Jun 06 16:16:43 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 06 16:16:43 volumio volumio[17804]: info: CoreCommandRouter::volumioPushState Jun 06 16:16:43 volumio volumio[17804]: info: CoreStateMachine::setRepeat null single undefined Jun 06 16:16:43 volumio volumio[17804]: info: CoreStateMachine::pushState Jun 06 16:16:43 volumio volumio[17804]: info: CorePlayQueue::getTrack 0 Jun 06 16:16:43 volumio volumio[17804]: info: CoreCommandRouter::volumioPushState Jun 06 16:16:43 volumio volumio[17804]: info: CoreStateMachine::setRandom null Jun 06 16:16:43 volumio volumio[17804]: info: CoreStateMachine::pushState Jun 06 16:16:43 volumio volumio[17804]: info: CorePlayQueue::getTrack 0 Jun 06 16:16:43 volumio volumio[17804]: info: CoreCommandRouter::volumioPushState Jun 06 16:16:43 volumio volumio[17804]: info: CoreCommandRouter::volumioGetState Jun 06 16:16:43 volumio volumio[17804]: info: CorePlayQueue::getTrack 0 Jun 06 16:16:43 volumio volumio[17804]: info: Setting Device type: Raspberry PI Jun 06 16:16:43 volumio volumio[17804]: info: Completed loading Core Plugins Jun 06 16:16:43 volumio volumio[17804]: info: Preparing to generate the ALSA configuration file Jun 06 16:16:43 volumio volumio[17804]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jun 06 16:16:43 volumio volumio[17804]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03115 Jun 06 16:16:43 volumio volumio[17804]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jun 06 16:16:43 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 06 16:16:43 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 06 16:16:43 volumio volumio[17804]: info: Discovery: Getting this device information Jun 06 16:16:43 volumio volumio[17804]: info: CoreCommandRouter::volumioGetState Jun 06 16:16:43 volumio volumio[17804]: info: CorePlayQueue::getTrack 0 Jun 06 16:16:43 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 06 16:16:43 volumio volumio[17804]: info: Listing playlists Jun 06 16:16:43 volumio volumio[17804]: info: Listing playlists Jun 06 16:16:43 volumio volumio[17804]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jun 06 16:16:43 volumio volumio[17804]: info: Reading ALSA contributions from plugins. Jun 06 16:16:43 volumio volumio[17804]: verbose: New Socket.io Connection to 10.20.2.67 from 10.20.2.72 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Jun 06 16:16:43 volumio volumio[17804]: info: Discovery: adding a677849c-d0c1-472d-a87d-484d0d9963d0 Jun 06 16:16:43 volumio volumio[17804]: info: Discovery: Found device Volumio Jun 06 16:16:43 volumio volumio[17804]: info: CoreCommandRouter::volumioGetState Jun 06 16:16:43 volumio volumio[17804]: info: CorePlayQueue::getTrack 0 Jun 06 16:16:43 volumio volumio[17804]: info: Asound.conf file unchanged, so no further update is needed Jun 06 16:16:43 volumio volumio[17804]: info: Output device has changed, restarting MPD Jun 06 16:16:43 volumio volumio[17804]: info: Output device has changed, restarting Shairport Sync Jun 06 16:16:43 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 16:16:43 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 06 16:16:43 volumio sudo[17925]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 06 16:16:43 volumio volumio[17804]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 06 16:16:43 volumio volumio[17804]: info: ___________ START PLUGINS ___________ Jun 06 16:16:43 volumio sudo[17925]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 16:16:43 volumio sudo[17928]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 06 16:16:43 volumio sudo[17928]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 16:16:43 volumio sudo[17925]: pam_unix(sudo:session): session closed for user root Jun 06 16:16:43 volumio volumio[17804]: info: ControllerMpd::onStart: Initializing MPD Jun 06 16:16:43 volumio volumio[17804]: info: Creating MPD Configuration file Jun 06 16:16:43 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Jun 06 16:16:43 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 06 16:16:43 volumio volumio[17804]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 06 16:16:43 volumio sudo[17934]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 06 16:16:43 volumio volumio[17870]: Starting albumart workers Jun 06 16:16:43 volumio volumio[17804]: info: [1749219403890] CoreMusicLibrary::Adding element Serveurs Média Jun 06 16:16:43 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 06 16:16:43 volumio sudo[17934]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 16:16:43 volumio sudo[17936]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 06 16:16:43 volumio sudo[17936]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 16:16:43 volumio sudo[17934]: pam_unix(sudo:session): session closed for user root Jun 06 16:16:43 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 16:16:43 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 06 16:16:43 volumio systemd[1]: mpd.service: Deactivated successfully. Jun 06 16:16:43 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Jun 06 16:16:43 volumio systemd[1]: mpd.service: Consumed 3.151s CPU time. Jun 06 16:16:43 volumio systemd[1]: mpd.socket: Deactivated successfully. Jun 06 16:16:43 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jun 06 16:16:43 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jun 06 16:16:43 volumio volumio[17804]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 06 16:16:43 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 16:16:43 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 06 16:16:43 volumio volumio[17804]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 06 16:16:43 volumio volumio[17804]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 06 16:16:43 volumio volumio[17804]: info: [1749219403986] CoreMusicLibrary::Adding element Last_100 Jun 06 16:16:43 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 06 16:16:43 volumio volumio[17804]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 06 16:16:43 volumio volumio[17804]: info: [1749219403989] CoreMusicLibrary::Adding element Webradio Jun 06 16:16:43 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 06 16:16:43 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 06 16:16:44 volumio volumio[17872]: Starting albumart workers Jun 06 16:16:44 volumio volumio[17804]: info: Initializing BBC Radios Jun 06 16:16:44 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 06 16:16:44 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Jun 06 16:16:44 volumio volumio[17871]: Starting albumart workers Jun 06 16:16:44 volumio systemd[1]: mpd.service: Deactivated successfully. Jun 06 16:16:44 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Jun 06 16:16:44 volumio systemd[1]: mpd.socket: Deactivated successfully. Jun 06 16:16:44 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jun 06 16:16:44 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jun 06 16:16:44 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 06 16:16:44 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Jun 06 16:16:44 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 06 16:16:44 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 06 16:16:44 volumio volumio[17804]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 06 16:16:44 volumio volumio[17804]: info: [1749219404130] CoreMusicLibrary::Adding element Bandcamp Discover Jun 06 16:16:44 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 06 16:16:44 volumio volumio[17804]: Cannot find translation for source Bandcamp Discover Jun 06 16:16:44 volumio volumio[17804]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 06 16:16:44 volumio volumio[17804]: info: [1749219404138] CoreMusicLibrary::Adding element SoundCloud Jun 06 16:16:44 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 06 16:16:44 volumio volumio[17804]: Cannot find translation for source Bandcamp Discover Jun 06 16:16:44 volumio volumio[17804]: Cannot find translation for source SoundCloud Jun 06 16:16:44 volumio volumio[17804]: info: Creating Spotify config file Jun 06 16:16:44 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 16:16:44 volumio volumio[17804]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 06 16:16:44 volumio volumio[17804]: info: [1749219404167] CoreMusicLibrary::Adding element YouTube2 Jun 06 16:16:44 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 06 16:16:44 volumio volumio[17804]: Cannot find translation for source Bandcamp Discover Jun 06 16:16:44 volumio volumio[17804]: Cannot find translation for source SoundCloud Jun 06 16:16:44 volumio volumio[17804]: Cannot find translation for source YouTube2 Jun 06 16:16:44 volumio volumio[17804]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 06 16:16:44 volumio volumio[17804]: info: [1749219404226] CoreMusicLibrary::Adding element YouTube Music Jun 06 16:16:44 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 06 16:16:44 volumio volumio[17804]: Cannot find translation for source Bandcamp Discover Jun 06 16:16:44 volumio volumio[17804]: Cannot find translation for source SoundCloud Jun 06 16:16:44 volumio volumio[17804]: Cannot find translation for source YouTube2 Jun 06 16:16:44 volumio volumio[17804]: Cannot find translation for source YouTube Music Jun 06 16:16:44 volumio volumio[17804]: info: [KeepClock] Starting ... Jun 06 16:16:44 volumio volumio[17804]: info: Volumio Calling Home Jun 06 16:16:44 volumio sudo[17958]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 06 16:16:44 volumio sudo[17958]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 06 16:16:44 volumio sudo[17958]: pam_unix(sudo:session): session closed for user root Jun 06 16:16:44 volumio volumio[17804]: info: Discovery: this is already registered, a677849c-d0c1-472d-a87d-484d0d9963d0 Jun 06 16:16:44 volumio volumio[17804]: info: Discovery: Found device Volumio Jun 06 16:16:44 volumio volumio[17804]: info: CoreCommandRouter::volumioGetState Jun 06 16:16:44 volumio volumio[17804]: info: CorePlayQueue::getTrack 0 Jun 06 16:16:44 volumio volumio[17804]: info: MPD Permissions set Jun 06 16:16:44 volumio volumio[17804]: info: MPD Permissions set Jun 06 16:16:44 volumio volumio[17804]: verbose: New Socket.io Connection to 10.20.2.67:3000 from 10.20.2.68 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 4 Jun 06 16:16:44 volumio volumio[17804]: info: Volumio called home Jun 06 16:16:44 volumio volumio[17804]: info: Spotify config file written Jun 06 16:16:44 volumio sudo[17988]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jun 06 16:16:44 volumio sudo[17988]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 16:16:44 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Jun 06 16:16:44 volumio systemd[1]: go-librespot-daemon.service: Killing process 17503 (go-librespot) with signal SIGKILL. Jun 06 16:16:44 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Jun 06 16:16:44 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jun 06 16:16:44 volumio volumio[17804]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jun 06 16:16:44 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 16:16:44 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 16:16:44 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 16:16:44 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 16:16:44 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 16:16:44 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 16:16:44 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 16:16:44 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 16:16:44 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 06 16:16:44 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 16:16:44 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 16:16:44 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 16:16:44 volumio go-librespot[17990]: go-librespot daemon starting... Jun 06 16:16:44 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jun 06 16:16:44 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 16:16:44 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 16:16:44 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 16:16:44 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 16:16:44 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 16:16:44 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 16:16:44 volumio sudo[17988]: pam_unix(sudo:session): session closed for user root Jun 06 16:16:44 volumio go-librespot[17996]: time="2025-06-06T16:16:44+02:00" level=info msg="running go-librespot 0.2.0" Jun 06 16:16:44 volumio go-librespot[17996]: time="2025-06-06T16:16:44+02:00" level=debug msg="app state loaded" Jun 06 16:16:44 volumio go-librespot[17996]: time="2025-06-06T16:16:44+02:00" level=debug msg="stored credentials not found" Jun 06 16:16:44 volumio go-librespot[17996]: time="2025-06-06T16:16:44+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 06 16:16:44 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 06 16:16:44 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 16:16:44 volumio volumio[17804]: info: No need to fix Spotify hosts Jun 06 16:16:45 volumio volumio[17804]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Jun 06 16:16:45 volumio go-librespot[17996]: time="2025-06-06T16:16:45+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Jun 06 16:16:45 volumio go-librespot[17996]: time="2025-06-06T16:16:45+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Jun 06 16:16:45 volumio go-librespot[17996]: time="2025-06-06T16:16:45+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Jun 06 16:16:45 volumio go-librespot[17996]: time="2025-06-06T16:16:45+02:00" level=info msg="zeroconf server listening on port 44037" Jun 06 16:16:45 volumio volumio[17804]: info: CoreCommandRouter::volumioGetState Jun 06 16:16:45 volumio volumio[17804]: info: CorePlayQueue::getTrack 0 Jun 06 16:16:45 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 06 16:16:45 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 06 16:16:45 volumio volumio[17804]: info: Starting Shairport Sync Jun 06 16:16:45 volumio volumio[17804]: info: Starting Shairport Sync Jun 06 16:16:45 volumio volumio[17804]: info: Starting Shairport Sync Jun 06 16:16:45 volumio sudo[18010]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 06 16:16:45 volumio sudo[18010]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 16:16:45 volumio sudo[18012]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 06 16:16:45 volumio sudo[18012]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 16:16:45 volumio sudo[18015]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 06 16:16:45 volumio sudo[18015]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 16:16:45 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jun 06 16:16:45 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Jun 06 16:16:45 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 06 16:16:45 volumio systemd[1]: shairport-sync.service: Consumed 1.874s CPU time. Jun 06 16:16:45 volumio volumio[17804]: info: [KeepClock] socket.io connecté Jun 06 16:16:45 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 06 16:16:45 volumio sudo[18010]: pam_unix(sudo:session): session closed for user root Jun 06 16:16:45 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jun 06 16:16:45 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Jun 06 16:16:45 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 06 16:16:45 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 06 16:16:45 volumio sudo[18015]: pam_unix(sudo:session): session closed for user root Jun 06 16:16:45 volumio sudo[18012]: pam_unix(sudo:session): session closed for user root Jun 06 16:16:45 volumio volumio[17804]: info: Shairport-Sync Started Jun 06 16:16:45 volumio volumio[17804]: Error adding Membership: Error: addMembership EINVAL Jun 06 16:16:45 volumio volumio[17804]: info: CoreCommandRouter::volumioGetState Jun 06 16:16:45 volumio volumio[17804]: info: CorePlayQueue::getTrack 0 Jun 06 16:16:45 volumio volumio[17804]: info: Shairport-Sync Started Jun 06 16:16:45 volumio volumio[17804]: info: Shairport-Sync Started Jun 06 16:16:45 volumio volumio[17804]: info: [KeepClock] Status: stop Jun 06 16:16:45 volumio volumio[17804]: info: [KeepClock] oldStatus: null Jun 06 16:16:45 volumio volumio[17804]: info: CoreCommandRouter::volumioGetState Jun 06 16:16:45 volumio volumio[17804]: info: CorePlayQueue::getTrack 0 Jun 06 16:16:45 volumio sudo[18049]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 06 16:16:45 volumio sudo[18051]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 06 16:16:45 volumio sudo[18051]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 16:16:45 volumio sudo[18049]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 16:16:45 volumio sudo[18051]: pam_unix(sudo:session): session closed for user root Jun 06 16:16:45 volumio sudo[18049]: pam_unix(sudo:session): session closed for user root Jun 06 16:16:45 volumio sudo[18055]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jun 06 16:16:45 volumio sudo[18055]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 16:16:45 volumio sudo[18055]: pam_unix(sudo:session): session closed for user root Jun 06 16:16:45 volumio volumio[17804]: info: Upmpdcli Daemon Started Jun 06 16:16:46 volumio mpd[17968]: 2025-06-06T16:16:46 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 06 16:16:46 volumio systemd[1]: Started mpd.service - Music Player Daemon. Jun 06 16:16:46 volumio sudo[17928]: pam_unix(sudo:session): session closed for user root Jun 06 16:16:46 volumio sudo[17936]: pam_unix(sudo:session): session closed for user root Jun 06 16:16:46 volumio volumio[17804]: error: MPD error: The expression evaluated to a falsy value: Jun 06 16:16:46 volumio volumio[17804]: assert.ok(self.idling) Jun 06 16:16:46 volumio volumio[17804]: error: The expression evaluated to a falsy value: Jun 06 16:16:46 volumio volumio[17804]: assert.ok(self.idling) Jun 06 16:16:46 volumio volumio[17804]: info: MPD running with PID17968 Jun 06 16:16:46 volumio volumio[17804]: ,establishing connection Jun 06 16:16:46 volumio volumio[17804]: error: updateQueue error: null Jun 06 16:16:46 volumio volumio[17804]: info: CoreCommandRouter::volumioGetState Jun 06 16:16:46 volumio volumio[17804]: info: CorePlayQueue::getTrack 0 Jun 06 16:16:46 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 06 16:16:46 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 06 16:16:46 volumio volumio[17804]: info: Discovery: Getting this device information Jun 06 16:16:46 volumio volumio[17804]: info: CoreCommandRouter::volumioGetState Jun 06 16:16:46 volumio volumio[17804]: info: CorePlayQueue::getTrack 0 Jun 06 16:16:46 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 06 16:16:46 volumio volumio[17804]: error: updateQueue error: null Jun 06 16:16:46 volumio volumio[17804]: verbose: New Socket.io Connection to 10.20.2.67 from 10.20.2.72 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Jun 06 16:16:46 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 06 16:16:46 volumio volumio[17804]: info: CoreCommandRouter::volumioGetVisibleSources Jun 06 16:16:46 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 06 16:16:46 volumio volumio[17804]: info: CoreCommandRouter::volumioGetState Jun 06 16:16:46 volumio volumio[17804]: info: CorePlayQueue::getTrack 0 Jun 06 16:16:46 volumio volumio[17804]: info: CoreCommandRouter::volumioGetState Jun 06 16:16:46 volumio volumio[17804]: info: CorePlayQueue::getTrack 0 Jun 06 16:16:46 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 06 16:16:46 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 06 16:16:46 volumio volumio[17804]: info: Received Get System Info Jun 06 16:16:46 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 06 16:16:46 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 06 16:16:46 volumio volumio[17804]: info: Discovery: Getting this device information Jun 06 16:16:46 volumio volumio[17804]: info: CoreCommandRouter::volumioGetState Jun 06 16:16:46 volumio volumio[17804]: info: CorePlayQueue::getTrack 0 Jun 06 16:16:46 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 06 16:16:46 volumio volumio[17804]: info: CoreCommandRouter::volumioGetState Jun 06 16:16:46 volumio volumio[17804]: info: CorePlayQueue::getTrack 0 Jun 06 16:16:46 volumio volumio[17804]: info: Listing playlists Jun 06 16:16:46 volumio volumio[17804]: info: CoreCommandRouter::volumioGetQueue Jun 06 16:16:46 volumio volumio[17804]: info: CoreStateMachine::getQueue Jun 06 16:16:46 volumio volumio[17804]: info: CorePlayQueue::getQueue Jun 06 16:16:48 volumio volumio[17804]: info: go-librespot daemon successfully initialized Jun 06 16:16:49 volumio volumio[17804]: info: [yt-cast-receiver] DIAL server listening on port 8098 Jun 06 16:16:49 volumio volumio[17804]: info: CoreCommandRouter::volumioRetrievevolume Jun 06 16:16:49 volumio volumio[17804]: info: CoreCommandRouter::volumioGetState Jun 06 16:16:49 volumio volumio[17804]: info: CorePlayQueue::getTrack 0 Jun 06 16:16:49 volumio volumio[17804]: info: CoreStateMachine::pushState Jun 06 16:16:49 volumio volumio[17804]: info: CorePlayQueue::getTrack 0 Jun 06 16:16:49 volumio volumio[17804]: info: CoreCommandRouter::volumioPushState Jun 06 16:16:50 volumio volumio[17804]: info: Completed starting Core Plugins Jun 06 16:16:50 volumio volumio[17804]: info: ------------------------------------------- Jun 06 16:16:50 volumio volumio[17804]: info: ----- MyVolumio plugins startup ---- Jun 06 16:16:50 volumio volumio[17804]: info: ------------------------------------------- Jun 06 16:16:50 volumio volumio[17804]: info: [MyVolumio PluginManager] Fetching plans data.... Jun 06 16:16:50 volumio volumio[17804]: info: CoreCommandRouter::volumioPlay Jun 06 16:16:50 volumio volumio[17804]: info: CoreStateMachine::play index 1 Jun 06 16:16:50 volumio volumio[17804]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 06 16:16:50 volumio volumio[17804]: info: CoreStateMachine::stop Jun 06 16:16:50 volumio volumio[17804]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 06 16:16:50 volumio volumio[17804]: info: CoreStateMachine::play index undefined Jun 06 16:16:50 volumio volumio[17804]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 06 16:16:50 volumio volumio[17804]: info: CorePlayQueue::getTrack 1 Jun 06 16:16:50 volumio volumio[17804]: info: CoreStateMachine::startPlaybackTimer Jun 06 16:16:50 volumio volumio[17804]: info: CorePlayQueue::getTrack 1 Jun 06 16:16:50 volumio volumio[17804]: info: [ytmusic-play] clearAddPlayTrack: ytmusic/video@explodeTrackData:o=%7B%22type%22%3A%22video%22%2C%22title%22%3A%22Faith%20Move%20Mountain%22%2C%22artist%22%3A%22King%20Kong%20et%20Little%20Lion%20Sound%22%2C%22album%22%3A%22Faith%20Move%20Mountain%22%2C%22albumart%22%3A%22https%3A%2F%2Flh3.googleusercontent.com%2FjZCnuGH-skNyOCdCbDCl1IgvQ6b9_YP_fsIXjMf36FpRNWEGenKe-Z9uvUmXz6o_BKD1nSmybfwDH0I%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22cOd0JK6HzZM%22%2C%22playlistId%22%3A%22OLAK5uy_mxAVRAA1BnfCNVvKs3CqVWC2YjSlMs8KA%22%2C%22params%22%3A%22OAHyAQIIAZIEI1FQTTY5TXJUbmFlb0dPUlBaNTdQUHpLUDVMcWtROVM5RER5%22%2C%22index%22%3A0%2C%22playlistSetVideoId%22%3A%229F3E08FCD6FABA75%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22playlistId%22%3A%22OLAK5uy_mxAVRAA1BnfCNVvKs3CqVWC2YjSlMs8KA%22%2C%22params%22%3A%22OAHyAQIIAZIEI1FQTTY5TXJUbmFlb0dPUlBaNTdQUHpLUDVMcWtROVM5RER5%22%2C%22videoId%22%3A%22cOd0JK6HzZM%22%7D%7D%7D%7D Jun 06 16:16:50 volumio volumio[17804]: info: [ytmusic] InnertubeLoader: creating Innertube instance... Jun 06 16:16:51 volumio volumio-remote-updater[649]: [2025-06-06 16:16:51] [connect] Successful connection Jun 06 16:16:51 volumio volumio-remote-updater[649]: [2025-06-06 16:16:51] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1749219411 101 Jun 06 16:16:51 volumio volumio[17804]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 8 Jun 06 16:16:51 volumio volumio[17804]: info: Initializing connection to go-librespot Websocket Jun 06 16:16:51 volumio go-librespot[17996]: time="2025-06-06T16:16:51+02:00" level=debug msg="new websocket client" Jun 06 16:16:51 volumio volumio[17804]: info: Connection to go-librespot Websocket established Jun 06 16:16:54 volumio volumio[17804]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jun 06 16:16:54 volumio volumio[17804]: info: Getting Spotify volume Jun 06 16:16:54 volumio volumio[17804]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Jun 06 16:16:54 volumio volumio[17804]: info: [ytmusic] InnertubeLoader: obtaining po_token by datasyncIdToken... Jun 06 16:16:54 volumio volumio[17804]: info: CoreCommandRouter::volumioGetState Jun 06 16:16:54 volumio volumio[17804]: info: CorePlayQueue::getTrack 1 Jun 06 16:16:54 volumio volumio[17804]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Jun 06 16:16:54 volumio volumio[17804]: SPOTIFY: SPOTIFY VOLUME undefined Jun 06 16:16:54 volumio volumio[17804]: SPOTIFY: VOLUMIO VOLUME 5 Jun 06 16:16:54 volumio volumio[17804]: info: Aligning Spotify Volume to Volumio Volume Jun 06 16:16:54 volumio volumio[17804]: info: CoreCommandRouter::volumioGetState Jun 06 16:16:54 volumio volumio[17804]: info: CorePlayQueue::getTrack 1 Jun 06 16:16:54 volumio volumio[17804]: info: Setting Spotify Volume from Volumio: 5 Jun 06 16:16:54 volumio volumio[17804]: info: [KeepClock] Starting silence playback Jun 06 16:16:54 volumio kernel: bcm2835-i2s fe203000.i2s: I2S SYNC error! Jun 06 16:16:54 volumio volumio[17804]: info: [ytmusic] InnertubeLoader: obtained po_token (expires in 43200 seconds) Jun 06 16:16:54 volumio volumio[17804]: info: [ytmusic] InnertubeLoader: re-create Innertube instance with po_token Jun 06 16:16:54 volumio volumio[17804]: info: [ytmusic] InnertubeLoader: creating Innertube instance with po_token... Jun 06 16:16:57 volumio volumio[17804]: info: [ytmusic] InnertubeLoader: going to refresh po_token in 43100 seconds Jun 06 16:16:57 volumio volumio[17804]: SPOTIFY: SETTING SPOTIFY VOLUME 5 Jun 06 16:16:57 volumio volumio[17804]: info: Sending Spotify command with payload to local API: /player/volume Jun 06 16:16:57 volumio volumio[17804]: error: Failed to send command to Spotify local API: /player/volume: Error: Bad Request Jun 06 16:16:57 volumio volumio[17804]: info: CoreCommandRouter::volumioGetState Jun 06 16:16:57 volumio volumio[17804]: info: CorePlayQueue::getTrack 1 Jun 06 16:16:57 volumio volumio[17804]: info: CorePlayQueue::getTrack 1 Jun 06 16:16:57 volumio volumio[17804]: verbose: ControllerMpd::sendMpdCommand stop Jun 06 16:16:57 volumio volumio[17804]: info: sendMpdCommand stop took 3 milliseconds Jun 06 16:16:57 volumio volumio[17804]: verbose: ControllerMpd::sendMpdCommand clear Jun 06 16:16:57 volumio volumio[17804]: info: Jun 06 16:16:57 volumio volumio[17804]: ---------------------------- MPD announces system playlist update Jun 06 16:16:57 volumio volumio[17804]: info: Ignoring MPD Status Update Jun 06 16:16:57 volumio volumio[17804]: info: sendMpdCommand clear took 3 milliseconds Jun 06 16:16:57 volumio volumio[17804]: verbose: ControllerMpd::sendMpdCommand addid "https://rr3---sn-25glenlk.googlevideo.com/videoplayback?expire=1749241017&ei=WfhCaI6HH5qIvdIP88-BmAI&ip=212.129.2.121&id=o-AE_X5c-ZurbhUTWDiRGuKTi8VUpxt24Z3OJ4z-2iveoY&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1749219417%2C&mh=QA&mm=31%2C26&mn=sn-25glenlk%2Csn-5hne6nz6&ms=au%2Conr&mv=m&mvi=3&pl=24&rms=au%2Cau&ctier=A&pfa=5&pcm2=no&gcr=fr&initcwndbps=1355000&hightc=yes&siu=1&bui=AY1jyLM5wDh519MjLeHnQmgHKbBwvfhEtXc9mDRkH5GujDPZs_KYFBjgtJnOeyvb4Ez03OEYTQ&spc=l3OVKW3t1wcTysX8MYPdd7BK6z-rBcjJhTGH_J9GTL5z6ijGFeydHnhqm8WB2VHrIeCt2zNk2Ff2W5k&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=KrIAkUbXtmjKrvyywCjaHSkQ&rqh=1&gir=yes&clen=5844721&dur=172.321&lmt=1746536788030650&mt=1749218954&fvip=1&keepalive=yes&fexp=51466698&c=WEB_REMIX&sefc=1&txp=2318224&n=y7t4Tp02_eBiDA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cpcm2%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhAIJ9R6On_pQ3rUdGBVxms5i0QlrvGQAHkOIFzE2snU7BAiEAyusljML6X2Zki1v_gq3zNSolnlo39DeoQzsCG9kqplc%3D&sig=AJfQdSswRQIgRZo6zzq3wKi4nu_wWxTvAmXAb-NuNRB-QlyVxwnxCKsCIQCkjdmdXyyHXchuMtVTeUornieGk_rY1fWX3LT0P2hgdw%3D%3D&pot=Ml-J20Fb7ru8aInnHutyPWyEYrW46w-vcidDnH0Q4psQKWIO5DC_piSuip7XLxrkJucgaKRPh4ZDExt5TtkmaFkUeghQ0UpC4Jt1wKbWoynovf99uZF-1qNg9QwWIIXG6Q%3D%3D&cver=1.20250219.01.00&t.YouTube" Jun 06 16:16:57 volumio volumio[17804]: info: Jun 06 16:16:57 volumio volumio[17804]: ---------------------------- MPD announces system playlist update Jun 06 16:16:57 volumio volumio[17804]: info: Ignoring MPD Status Update Jun 06 16:16:57 volumio volumio[17804]: info: Jun 06 16:16:57 volumio volumio[17804]: ---------------------------- MPD announces system playlist update Jun 06 16:16:57 volumio volumio[17804]: info: Ignoring MPD Status Update Jun 06 16:16:57 volumio volumio[17804]: error: updateQueue error: null Jun 06 16:16:57 volumio volumio[17804]: info: Jun 06 16:16:57 volumio volumio[17804]: ---------------------------- MPD announces system playlist update Jun 06 16:16:57 volumio volumio[17804]: info: Ignoring MPD Status Update Jun 06 16:16:57 volumio volumio[17804]: info: ------------------------------ 9ms Jun 06 16:16:57 volumio volumio[17804]: info: sendMpdCommand addid "https://rr3---sn-25glenlk.googlevideo.com/videoplayback?expire=1749241017&ei=WfhCaI6HH5qIvdIP88-BmAI&ip=212.129.2.121&id=o-AE_X5c-ZurbhUTWDiRGuKTi8VUpxt24Z3OJ4z-2iveoY&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1749219417%2C&mh=QA&mm=31%2C26&mn=sn-25glenlk%2Csn-5hne6nz6&ms=au%2Conr&mv=m&mvi=3&pl=24&rms=au%2Cau&ctier=A&pfa=5&pcm2=no&gcr=fr&initcwndbps=1355000&hightc=yes&siu=1&bui=AY1jyLM5wDh519MjLeHnQmgHKbBwvfhEtXc9mDRkH5GujDPZs_KYFBjgtJnOeyvb4Ez03OEYTQ&spc=l3OVKW3t1wcTysX8MYPdd7BK6z-rBcjJhTGH_J9GTL5z6ijGFeydHnhqm8WB2VHrIeCt2zNk2Ff2W5k&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=KrIAkUbXtmjKrvyywCjaHSkQ&rqh=1&gir=yes&clen=5844721&dur=172.321&lmt=1746536788030650&mt=1749218954&fvip=1&keepalive=yes&fexp=51466698&c=WEB_REMIX&sefc=1&txp=2318224&n=y7t4Tp02_eBiDA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cpcm2%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhAIJ9R6On_pQ3rUdGBVxms5i0QlrvGQAHkOIFzE2snU7BAiEAyusljML6X2Zki1v_gq3zNSolnlo39DeoQzsCG9kqplc%3D&sig=AJfQdSswRQIgRZo6zzq3wKi4nu_wWxTvAmXAb-NuNRB-QlyVxwnxCKsCIQCkjdmdXyyHXchuMtVTeUornieGk_rY1fWX3LT0P2hgdw%3D%3D&pot=Ml-J20Fb7ru8aInnHutyPWyEYrW46w-vcidDnH0Q4psQKWIO5DC_piSuip7XLxrkJucgaKRPh4ZDExt5TtkmaFkUeghQ0UpC4Jt1wKbWoynovf99uZF-1qNg9QwWIIXG6Q%3D%3D&cver=1.20250219.01.00&t.YouTube" took 7 milliseconds Jun 06 16:16:57 volumio volumio[17804]: verbose: MPD COMMAND [object Object] Jun 06 16:16:57 volumio volumio[17804]: verbose: MPD COMMAND [object Object] Jun 06 16:16:57 volumio volumio[17804]: verbose: MPD COMMAND [object Object] Jun 06 16:16:57 volumio volumio[17804]: info: Jun 06 16:16:57 volumio volumio[17804]: ---------------------------- MPD announces system playlist update Jun 06 16:16:57 volumio volumio[17804]: info: Ignoring MPD Status Update Jun 06 16:16:57 volumio volumio[17804]: info: Jun 06 16:16:57 volumio volumio[17804]: ---------------------------- MPD announces system playlist update Jun 06 16:16:57 volumio volumio[17804]: info: Ignoring MPD Status Update Jun 06 16:16:57 volumio volumio[17804]: info: ------------------------------ 11ms Jun 06 16:16:57 volumio volumio[17804]: info: ------------------------------ 9ms Jun 06 16:16:57 volumio volumio[17804]: info: ------------------------------ 7ms Jun 06 16:16:57 volumio volumio[17804]: info: ------------------------------ 4ms Jun 06 16:16:57 volumio volumio[17804]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 06 16:16:57 volumio volumio[17804]: verbose: ControllerMpd::sendMpdCommand play Jun 06 16:16:57 volumio volumio[17804]: info: Jun 06 16:16:57 volumio volumio[17804]: ---------------------------- MPD announces system playlist update Jun 06 16:16:57 volumio volumio[17804]: info: Ignoring MPD Status Update Jun 06 16:16:57 volumio volumio[17804]: info: Jun 06 16:16:57 volumio volumio[17804]: ---------------------------- MPD announces system playlist update Jun 06 16:16:57 volumio volumio[17804]: info: Ignoring MPD Status Update Jun 06 16:16:57 volumio volumio[17804]: info: ------------------------------ 6ms Jun 06 16:16:57 volumio volumio[17804]: info: sendMpdCommand play took 5 milliseconds Jun 06 16:16:57 volumio volumio[17804]: info: ------------------------------ 4ms Jun 06 16:16:57 volumio volumio[17804]: info: ------------------------------ 13ms Jun 06 16:16:57 volumio volumio[17804]: info: Jun 06 16:16:57 volumio volumio[17804]: ---------------------------- MPD announces system playlist update Jun 06 16:16:57 volumio volumio[17804]: info: Ignoring MPD Status Update Jun 06 16:16:57 volumio volumio[17804]: info: ------------------------------ 2ms Jun 06 16:16:58 volumio volumio[17804]: info: Jun 06 16:16:58 volumio volumio[17804]: ---------------------------- MPD announces state update: player Jun 06 16:16:58 volumio volumio[17804]: info: ControllerMpd::getState Jun 06 16:16:58 volumio volumio[17804]: verbose: ControllerMpd::sendMpdCommand status Jun 06 16:16:58 volumio volumio[17804]: info: Jun 06 16:16:58 volumio volumio[17804]: ---------------------------- MPD announces state update: player Jun 06 16:16:58 volumio volumio[17804]: info: ControllerMpd::getState Jun 06 16:16:58 volumio volumio[17804]: verbose: ControllerMpd::sendMpdCommand status Jun 06 16:16:58 volumio volumio[17804]: info: ControllerMpd::getState Jun 06 16:16:58 volumio volumio[17804]: verbose: ControllerMpd::sendMpdCommand status Jun 06 16:16:58 volumio volumio[17804]: info: Jun 06 16:16:58 volumio volumio[17804]: ---------------------------- MPD announces state update: player Jun 06 16:16:58 volumio volumio[17804]: info: ControllerMpd::getState Jun 06 16:16:58 volumio volumio[17804]: verbose: ControllerMpd::sendMpdCommand status Jun 06 16:16:58 volumio volumio[17804]: info: ControllerMpd::getState Jun 06 16:16:58 volumio volumio[17804]: verbose: ControllerMpd::sendMpdCommand status Jun 06 16:16:58 volumio volumio[17804]: info: Jun 06 16:16:58 volumio volumio[17804]: ---------------------------- MPD announces state update: player Jun 06 16:16:58 volumio volumio[17804]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 06 16:16:58 volumio volumio[17804]: info: sendMpdCommand status took 18 milliseconds Jun 06 16:16:58 volumio volumio[17804]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 06 16:16:58 volumio volumio[17804]: info: sendMpdCommand status took 17 milliseconds Jun 06 16:16:58 volumio volumio[17804]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 06 16:16:58 volumio volumio[17804]: info: sendMpdCommand status took 17 milliseconds Jun 06 16:16:58 volumio volumio[17804]: info: ControllerMpd::getState Jun 06 16:16:58 volumio volumio[17804]: verbose: ControllerMpd::sendMpdCommand status Jun 06 16:16:58 volumio volumio[17804]: verbose: ControllerMpd::parseState Jun 06 16:16:58 volumio volumio[17804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 06 16:16:58 volumio volumio[17804]: verbose: ControllerMpd::parseState Jun 06 16:16:58 volumio volumio[17804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 06 16:16:58 volumio volumio[17804]: verbose: ControllerMpd::parseState Jun 06 16:16:58 volumio volumio[17804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 06 16:16:58 volumio volumio[17804]: info: Jun 06 16:16:58 volumio volumio[17804]: ---------------------------- MPD announces state update: player Jun 06 16:16:58 volumio volumio[17804]: info: ControllerMpd::getState Jun 06 16:16:58 volumio volumio[17804]: verbose: ControllerMpd::sendMpdCommand status Jun 06 16:16:58 volumio volumio[17804]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 06 16:16:58 volumio volumio[17804]: info: sendMpdCommand status took 36 milliseconds Jun 06 16:16:58 volumio volumio[17804]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 06 16:16:58 volumio volumio[17804]: info: sendMpdCommand status took 31 milliseconds Jun 06 16:16:58 volumio volumio[17804]: info: sendMpdCommand clearerror took 24 milliseconds Jun 06 16:16:58 volumio volumio[17804]: info: sendMpdCommand clearerror took 23 milliseconds Jun 06 16:16:58 volumio volumio[17804]: info: sendMpdCommand clearerror took 23 milliseconds Jun 06 16:16:58 volumio volumio[17804]: info: sendMpdCommand status took 22 milliseconds Jun 06 16:16:58 volumio volumio[17804]: info: sendMpdCommand playlistinfo took 14 milliseconds Jun 06 16:16:58 volumio volumio[17804]: info: sendMpdCommand playlistinfo took 15 milliseconds Jun 06 16:16:58 volumio volumio[17804]: info: sendMpdCommand playlistinfo took 14 milliseconds Jun 06 16:16:58 volumio volumio[17804]: info: sendMpdCommand status took 12 milliseconds Jun 06 16:16:58 volumio volumio[17804]: verbose: ControllerMpd::parseState Jun 06 16:16:58 volumio volumio[17804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 06 16:16:58 volumio volumio[17804]: verbose: ControllerMpd::parseState Jun 06 16:16:58 volumio volumio[17804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 06 16:16:58 volumio volumio[17804]: verbose: ControllerMpd::parseState Jun 06 16:16:58 volumio volumio[17804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 06 16:16:58 volumio volumio[17804]: verbose: ControllerMpd::parseTrackInfo Jun 06 16:16:58 volumio volumio[17804]: verbose: ControllerMpd::parseTrackInfo Jun 06 16:16:58 volumio volumio[17804]: verbose: ControllerMpd::parseTrackInfo Jun 06 16:16:58 volumio volumio[17804]: verbose: ControllerMpd::parseState Jun 06 16:16:58 volumio volumio[17804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 06 16:16:58 volumio volumio[17804]: info: ControllerMpd::pushState Jun 06 16:16:58 volumio volumio[17804]: info: CoreCommandRouter::servicePushState Jun 06 16:16:58 volumio volumio[17804]: info: CorePlayQueue::getTrack 1 Jun 06 16:16:58 volumio volumio[17804]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":172,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Faith Move Mountain","artist":"King Kong et Little Lion Sound","album":"Faith Move Mountain","uri":"https://rr3---sn-25glenlk.googlevideo.com/videoplayback?expire=1749241017&ei=WfhCaI6HH5qIvdIP88-BmAI&ip=212.129.2.121&id=o-AE_X5c-ZurbhUTWDiRGuKTi8VUpxt24Z3OJ4z-2iveoY&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1749219417%2C&mh=QA&mm=31%2C26&mn=sn-25glenlk%2Csn-5hne6nz6&ms=au%2Conr&mv=m&mvi=3&pl=24&rms=au%2Cau&ctier=A&pfa=5&pcm2=no&gcr=fr&initcwndbps=1355000&hightc=yes&siu=1&bui=AY1jyLM5wDh519MjLeHnQmgHKbBwvfhEtXc9mDRkH5GujDPZs_KYFBjgtJnOeyvb4Ez03OEYTQ&spc=l3OVKW3t1wcTysX8MYPdd7BK6z-rBcjJhTGH_J9GTL5z6ijGFeydHnhqm8WB2VHrIeCt2zNk2Ff2W5k&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=KrIAkUbXtmjKrvyywCjaHSkQ&rqh=1&gir=yes&clen=5844721&dur=172.321&lmt=1746536788030650&mt=1749218954&fvip=1&keepalive=yes&fexp=51466698&c=WEB_REMIX&sefc=1&txp=2318224&n=y7t4Tp02_eBiDA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cpcm2%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhAIJ9R6On_pQ3rUdGBVxms5i0QlrvGQAHkOIFzE2snU7BAiEAyusljML6X2Zki1v_gq3zNSolnlo39DeoQzsCG9kqplc%3D&sig=AJfQdSswRQIgRZo6zzq3wKi4nu_wWxTvAmXAb-NuNRB-QlyVxwnxCKsCIQCkjdmdXyyHXchuMtVTeUornieGk_rY1fWX3LT0P2hgdw%3D%3D&pot=Ml-J20Fb7ru8aInnHutyPWyEYrW46w-vcidDnH0Q4psQKWIO5DC_piSuip7XLxrkJucgaKRPh4ZDExt5TtkmaFkUeghQ0UpC4Jt1wKbWoynovf99uZF-1qNg9QwWIIXG6Q%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Jun 06 16:16:58 volumio volumio[17804]: verbose: CURRENT POSITION 1 Jun 06 16:16:58 volumio volumio[17804]: info: CoreStateMachine::syncState stateService pause Jun 06 16:16:58 volumio volumio[17804]: info: CoreStateMachine::syncState currentStatus stop Jun 06 16:16:58 volumio volumio[17804]: info: CoreStateMachine::pushState Jun 06 16:16:58 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 06 16:16:58 volumio volumio[17804]: info: CoreCommandRouter::volumioPushState Jun 06 16:16:58 volumio volumio[17804]: info: ControllerMpd::pushState Jun 06 16:16:58 volumio volumio[17804]: info: CoreCommandRouter::servicePushState Jun 06 16:16:58 volumio volumio[17804]: info: CorePlayQueue::getTrack 1 Jun 06 16:16:58 volumio volumio[17804]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":172,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Faith Move Mountain","artist":"King Kong et Little Lion Sound","album":"Faith Move Mountain","uri":"https://rr3---sn-25glenlk.googlevideo.com/videoplayback?expire=1749241017&ei=WfhCaI6HH5qIvdIP88-BmAI&ip=212.129.2.121&id=o-AE_X5c-ZurbhUTWDiRGuKTi8VUpxt24Z3OJ4z-2iveoY&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1749219417%2C&mh=QA&mm=31%2C26&mn=sn-25glenlk%2Csn-5hne6nz6&ms=au%2Conr&mv=m&mvi=3&pl=24&rms=au%2Cau&ctier=A&pfa=5&pcm2=no&gcr=fr&initcwndbps=1355000&hightc=yes&siu=1&bui=AY1jyLM5wDh519MjLeHnQmgHKbBwvfhEtXc9mDRkH5GujDPZs_KYFBjgtJnOeyvb4Ez03OEYTQ&spc=l3OVKW3t1wcTysX8MYPdd7BK6z-rBcjJhTGH_J9GTL5z6ijGFeydHnhqm8WB2VHrIeCt2zNk2Ff2W5k&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=KrIAkUbXtmjKrvyywCjaHSkQ&rqh=1&gir=yes&clen=5844721&dur=172.321&lmt=1746536788030650&mt=1749218954&fvip=1&keepalive=yes&fexp=51466698&c=WEB_REMIX&sefc=1&txp=2318224&n=y7t4Tp02_eBiDA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cpcm2%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhAIJ9R6On_pQ3rUdGBVxms5i0QlrvGQAHkOIFzE2snU7BAiEAyusljML6X2Zki1v_gq3zNSolnlo39DeoQzsCG9kqplc%3D&sig=AJfQdSswRQIgRZo6zzq3wKi4nu_wWxTvAmXAb-NuNRB-QlyVxwnxCKsCIQCkjdmdXyyHXchuMtVTeUornieGk_rY1fWX3LT0P2hgdw%3D%3D&pot=Ml-J20Fb7ru8aInnHutyPWyEYrW46w-vcidDnH0Q4psQKWIO5DC_piSuip7XLxrkJucgaKRPh4ZDExt5TtkmaFkUeghQ0UpC4Jt1wKbWoynovf99uZF-1qNg9QwWIIXG6Q%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Jun 06 16:16:58 volumio volumio[17804]: verbose: CURRENT POSITION 1 Jun 06 16:16:58 volumio volumio[17804]: info: CoreStateMachine::syncState stateService pause Jun 06 16:16:58 volumio volumio[17804]: info: CoreStateMachine::syncState currentStatus stop Jun 06 16:16:58 volumio volumio[17804]: info: CoreStateMachine::pushState Jun 06 16:16:58 volumio volumio[17804]: info: CoreCommandRouter::volumioPushState Jun 06 16:16:58 volumio volumio[17804]: info: ------------------------------ 77ms Jun 06 16:16:58 volumio volumio[17804]: info: ------------------------------ 76ms Jun 06 16:16:58 volumio volumio[17804]: info: Jun 06 16:16:58 volumio volumio[17804]: ---------------------------- MPD announces state update: player Jun 06 16:16:58 volumio volumio[17804]: info: ControllerMpd::getState Jun 06 16:16:58 volumio volumio[17804]: verbose: ControllerMpd::sendMpdCommand status Jun 06 16:16:58 volumio volumio[17804]: info: [KeepClock] Status: Jun 06 16:16:58 volumio volumio[17804]: info: [KeepClock] oldStatus: stop Jun 06 16:16:58 volumio volumio[17804]: info: [KeepClock] Status: undefined Jun 06 16:16:58 volumio volumio[17804]: info: [KeepClock] oldStatus: Jun 06 16:16:58 volumio volumio[17804]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Jun 06 16:16:58 volumio volumio[17804]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Jun 06 16:16:58 volumio volumio[17804]: info: sendMpdCommand clearerror took 64 milliseconds Jun 06 16:16:58 volumio volumio[17804]: info: sendMpdCommand clearerror took 63 milliseconds Jun 06 16:16:58 volumio volumio[17804]: info: sendMpdCommand playlistinfo took 56 milliseconds Jun 06 16:16:58 volumio volumio[17804]: info: sendMpdCommand playlistinfo took 55 milliseconds Jun 06 16:16:58 volumio volumio[17804]: info: sendMpdCommand playlistinfo took 55 milliseconds Jun 06 16:16:58 volumio volumio[17804]: info: sendMpdCommand playlistinfo took 53 milliseconds Jun 06 16:16:58 volumio volumio[17804]: info: sendMpdCommand status took 28 milliseconds Jun 06 16:16:58 volumio volumio[17804]: verbose: ControllerMpd::parseTrackInfo Jun 06 16:16:58 volumio volumio[17804]: verbose: ControllerMpd::parseTrackInfo Jun 06 16:16:58 volumio volumio[17804]: verbose: ControllerMpd::parseTrackInfo Jun 06 16:16:58 volumio volumio[17804]: verbose: ControllerMpd::parseTrackInfo Jun 06 16:16:58 volumio volumio[17804]: verbose: ControllerMpd::parseState Jun 06 16:16:58 volumio volumio[17804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 06 16:16:58 volumio volumio[17804]: info: ControllerMpd::pushState Jun 06 16:16:58 volumio volumio[17804]: info: CoreCommandRouter::servicePushState Jun 06 16:16:58 volumio volumio[17804]: info: CorePlayQueue::getTrack 1 Jun 06 16:16:58 volumio volumio[17804]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":172,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Faith Move Mountain","artist":"King Kong et Little Lion Sound","album":"Faith Move Mountain","uri":"https://rr3---sn-25glenlk.googlevideo.com/videoplayback?expire=1749241017&ei=WfhCaI6HH5qIvdIP88-BmAI&ip=212.129.2.121&id=o-AE_X5c-ZurbhUTWDiRGuKTi8VUpxt24Z3OJ4z-2iveoY&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1749219417%2C&mh=QA&mm=31%2C26&mn=sn-25glenlk%2Csn-5hne6nz6&ms=au%2Conr&mv=m&mvi=3&pl=24&rms=au%2Cau&ctier=A&pfa=5&pcm2=no&gcr=fr&initcwndbps=1355000&hightc=yes&siu=1&bui=AY1jyLM5wDh519MjLeHnQmgHKbBwvfhEtXc9mDRkH5GujDPZs_KYFBjgtJnOeyvb4Ez03OEYTQ&spc=l3OVKW3t1wcTysX8MYPdd7BK6z-rBcjJhTGH_J9GTL5z6ijGFeydHnhqm8WB2VHrIeCt2zNk2Ff2W5k&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=KrIAkUbXtmjKrvyywCjaHSkQ&rqh=1&gir=yes&clen=5844721&dur=172.321&lmt=1746536788030650&mt=1749218954&fvip=1&keepalive=yes&fexp=51466698&c=WEB_REMIX&sefc=1&txp=2318224&n=y7t4Tp02_eBiDA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cpcm2%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhAIJ9R6On_pQ3rUdGBVxms5i0QlrvGQAHkOIFzE2snU7BAiEAyusljML6X2Zki1v_gq3zNSolnlo39DeoQzsCG9kqplc%3D&sig=AJfQdSswRQIgRZo6zzq3wKi4nu_wWxTvAmXAb-NuNRB-QlyVxwnxCKsCIQCkjdmdXyyHXchuMtVTeUornieGk_rY1fWX3LT0P2hgdw%3D%3D&pot=Ml-J20Fb7ru8aInnHutyPWyEYrW46w-vcidDnH0Q4psQKWIO5DC_piSuip7XLxrkJucgaKRPh4ZDExt5TtkmaFkUeghQ0UpC4Jt1wKbWoynovf99uZF-1qNg9QwWIIXG6Q%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Jun 06 16:16:58 volumio volumio[17804]: verbose: CURRENT POSITION 1 Jun 06 16:16:58 volumio volumio[17804]: info: CoreStateMachine::syncState stateService pause Jun 06 16:16:58 volumio volumio[17804]: info: CoreStateMachine::syncState currentStatus stop Jun 06 16:16:58 volumio volumio[17804]: info: CoreStateMachine::pushState Jun 06 16:16:58 volumio volumio[17804]: info: CoreCommandRouter::volumioPushState Jun 06 16:16:58 volumio volumio[17804]: info: ControllerMpd::pushState Jun 06 16:16:58 volumio volumio[17804]: info: CoreCommandRouter::servicePushState Jun 06 16:16:58 volumio volumio[17804]: info: CorePlayQueue::getTrack 1 Jun 06 16:16:58 volumio volumio[17804]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":172,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Faith Move Mountain","artist":"King Kong et Little Lion Sound","album":"Faith Move Mountain","uri":"https://rr3---sn-25glenlk.googlevideo.com/videoplayback?expire=1749241017&ei=WfhCaI6HH5qIvdIP88-BmAI&ip=212.129.2.121&id=o-AE_X5c-ZurbhUTWDiRGuKTi8VUpxt24Z3OJ4z-2iveoY&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1749219417%2C&mh=QA&mm=31%2C26&mn=sn-25glenlk%2Csn-5hne6nz6&ms=au%2Conr&mv=m&mvi=3&pl=24&rms=au%2Cau&ctier=A&pfa=5&pcm2=no&gcr=fr&initcwndbps=1355000&hightc=yes&siu=1&bui=AY1jyLM5wDh519MjLeHnQmgHKbBwvfhEtXc9mDRkH5GujDPZs_KYFBjgtJnOeyvb4Ez03OEYTQ&spc=l3OVKW3t1wcTysX8MYPdd7BK6z-rBcjJhTGH_J9GTL5z6ijGFeydHnhqm8WB2VHrIeCt2zNk2Ff2W5k&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=KrIAkUbXtmjKrvyywCjaHSkQ&rqh=1&gir=yes&clen=5844721&dur=172.321&lmt=1746536788030650&mt=1749218954&fvip=1&keepalive=yes&fexp=51466698&c=WEB_REMIX&sefc=1&txp=2318224&n=y7t4Tp02_eBiDA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cpcm2%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhAIJ9R6On_pQ3rUdGBVxms5i0QlrvGQAHkOIFzE2snU7BAiEAyusljML6X2Zki1v_gq3zNSolnlo39DeoQzsCG9kqplc%3D&sig=AJfQdSswRQIgRZo6zzq3wKi4nu_wWxTvAmXAb-NuNRB-QlyVxwnxCKsCIQCkjdmdXyyHXchuMtVTeUornieGk_rY1fWX3LT0P2hgdw%3D%3D&pot=Ml-J20Fb7ru8aInnHutyPWyEYrW46w-vcidDnH0Q4psQKWIO5DC_piSuip7XLxrkJucgaKRPh4ZDExt5TtkmaFkUeghQ0UpC4Jt1wKbWoynovf99uZF-1qNg9QwWIIXG6Q%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Jun 06 16:16:58 volumio volumio[17804]: verbose: CURRENT POSITION 1 Jun 06 16:16:58 volumio volumio[17804]: info: CoreStateMachine::syncState stateService pause Jun 06 16:16:58 volumio volumio[17804]: info: CoreStateMachine::syncState currentStatus stop Jun 06 16:16:58 volumio volumio[17804]: info: CoreStateMachine::pushState Jun 06 16:16:58 volumio volumio[17804]: info: CoreCommandRouter::volumioPushState Jun 06 16:16:58 volumio volumio[17804]: info: ControllerMpd::pushState Jun 06 16:16:58 volumio volumio[17804]: info: CoreCommandRouter::servicePushState Jun 06 16:16:58 volumio volumio[17804]: info: CorePlayQueue::getTrack 1 Jun 06 16:16:58 volumio volumio[17804]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":172,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Faith Move Mountain","artist":"King Kong et Little Lion Sound","album":"Faith Move Mountain","uri":"https://rr3---sn-25glenlk.googlevideo.com/videoplayback?expire=1749241017&ei=WfhCaI6HH5qIvdIP88-BmAI&ip=212.129.2.121&id=o-AE_X5c-ZurbhUTWDiRGuKTi8VUpxt24Z3OJ4z-2iveoY&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1749219417%2C&mh=QA&mm=31%2C26&mn=sn-25glenlk%2Csn-5hne6nz6&ms=au%2Conr&mv=m&mvi=3&pl=24&rms=au%2Cau&ctier=A&pfa=5&pcm2=no&gcr=fr&initcwndbps=1355000&hightc=yes&siu=1&bui=AY1jyLM5wDh519MjLeHnQmgHKbBwvfhEtXc9mDRkH5GujDPZs_KYFBjgtJnOeyvb4Ez03OEYTQ&spc=l3OVKW3t1wcTysX8MYPdd7BK6z-rBcjJhTGH_J9GTL5z6ijGFeydHnhqm8WB2VHrIeCt2zNk2Ff2W5k&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=KrIAkUbXtmjKrvyywCjaHSkQ&rqh=1&gir=yes&clen=5844721&dur=172.321&lmt=1746536788030650&mt=1749218954&fvip=1&keepalive=yes&fexp=51466698&c=WEB_REMIX&sefc=1&txp=2318224&n=y7t4Tp02_eBiDA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cpcm2%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhAIJ9R6On_pQ3rUdGBVxms5i0QlrvGQAHkOIFzE2snU7BAiEAyusljML6X2Zki1v_gq3zNSolnlo39DeoQzsCG9kqplc%3D&sig=AJfQdSswRQIgRZo6zzq3wKi4nu_wWxTvAmXAb-NuNRB-QlyVxwnxCKsCIQCkjdmdXyyHXchuMtVTeUornieGk_rY1fWX3LT0P2hgdw%3D%3D&pot=Ml-J20Fb7ru8aInnHutyPWyEYrW46w-vcidDnH0Q4psQKWIO5DC_piSuip7XLxrkJucgaKRPh4ZDExt5TtkmaFkUeghQ0UpC4Jt1wKbWoynovf99uZF-1qNg9QwWIIXG6Q%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Jun 06 16:16:58 volumio volumio[17804]: verbose: CURRENT POSITION 1 Jun 06 16:16:58 volumio volumio[17804]: info: CoreStateMachine::syncState stateService pause Jun 06 16:16:58 volumio volumio[17804]: info: CoreStateMachine::syncState currentStatus stop Jun 06 16:16:58 volumio volumio[17804]: info: CoreStateMachine::pushState Jun 06 16:16:58 volumio volumio[17804]: info: CoreCommandRouter::volumioPushState Jun 06 16:16:58 volumio volumio[17804]: info: ------------------------------ 134ms Jun 06 16:16:58 volumio volumio[17804]: info: ------------------------------ 127ms Jun 06 16:16:58 volumio volumio[17804]: info: ------------------------------ 107ms Jun 06 16:16:58 volumio volumio[17804]: info: sendMpdCommand playlistinfo took 34 milliseconds Jun 06 16:16:58 volumio volumio[17804]: verbose: ControllerMpd::parseTrackInfo Jun 06 16:16:58 volumio volumio[17804]: info: ControllerMpd::pushState Jun 06 16:16:58 volumio volumio[17804]: info: CoreCommandRouter::servicePushState Jun 06 16:16:58 volumio volumio[17804]: info: CorePlayQueue::getTrack 1 Jun 06 16:16:58 volumio volumio[17804]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":172,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Faith Move Mountain","artist":"King Kong et Little Lion Sound","album":"Faith Move Mountain","uri":"https://rr3---sn-25glenlk.googlevideo.com/videoplayback?expire=1749241017&ei=WfhCaI6HH5qIvdIP88-BmAI&ip=212.129.2.121&id=o-AE_X5c-ZurbhUTWDiRGuKTi8VUpxt24Z3OJ4z-2iveoY&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1749219417%2C&mh=QA&mm=31%2C26&mn=sn-25glenlk%2Csn-5hne6nz6&ms=au%2Conr&mv=m&mvi=3&pl=24&rms=au%2Cau&ctier=A&pfa=5&pcm2=no&gcr=fr&initcwndbps=1355000&hightc=yes&siu=1&bui=AY1jyLM5wDh519MjLeHnQmgHKbBwvfhEtXc9mDRkH5GujDPZs_KYFBjgtJnOeyvb4Ez03OEYTQ&spc=l3OVKW3t1wcTysX8MYPdd7BK6z-rBcjJhTGH_J9GTL5z6ijGFeydHnhqm8WB2VHrIeCt2zNk2Ff2W5k&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=KrIAkUbXtmjKrvyywCjaHSkQ&rqh=1&gir=yes&clen=5844721&dur=172.321&lmt=1746536788030650&mt=1749218954&fvip=1&keepalive=yes&fexp=51466698&c=WEB_REMIX&sefc=1&txp=2318224&n=y7t4Tp02_eBiDA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cpcm2%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhAIJ9R6On_pQ3rUdGBVxms5i0QlrvGQAHkOIFzE2snU7BAiEAyusljML6X2Zki1v_gq3zNSolnlo39DeoQzsCG9kqplc%3D&sig=AJfQdSswRQIgRZo6zzq3wKi4nu_wWxTvAmXAb-NuNRB-QlyVxwnxCKsCIQCkjdmdXyyHXchuMtVTeUornieGk_rY1fWX3LT0P2hgdw%3D%3D&pot=Ml-J20Fb7ru8aInnHutyPWyEYrW46w-vcidDnH0Q4psQKWIO5DC_piSuip7XLxrkJucgaKRPh4ZDExt5TtkmaFkUeghQ0UpC4Jt1wKbWoynovf99uZF-1qNg9QwWIIXG6Q%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Jun 06 16:16:58 volumio volumio[17804]: verbose: CURRENT POSITION 1 Jun 06 16:16:58 volumio volumio[17804]: info: CoreStateMachine::syncState stateService pause Jun 06 16:16:58 volumio volumio[17804]: info: CoreStateMachine::syncState currentStatus stop Jun 06 16:16:58 volumio volumio[17804]: info: CoreStateMachine::pushState Jun 06 16:16:58 volumio volumio[17804]: info: CoreCommandRouter::volumioPushState Jun 06 16:16:58 volumio volumio[17804]: info: ------------------------------ 76ms Jun 06 16:16:58 volumio volumio[17804]: info: CoreCommandRouter::volumioGetState Jun 06 16:16:58 volumio volumio[17804]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Jun 06 16:16:58 volumio volumio[17804]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Jun 06 16:16:58 volumio volumio[17804]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Jun 06 16:16:58 volumio volumio[17804]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Jun 06 16:16:58 volumio volumio[17804]: info: CoreCommandRouter::volumioGetState Jun 06 16:16:58 volumio volumio[17804]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jun 06 16:16:58 volumio volumio[17804]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jun 06 16:16:58 volumio volumio[17804]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jun 06 16:16:58 volumio volumio[17804]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jun 06 16:16:58 volumio volumio[17804]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jun 06 16:16:58 volumio volumio[17804]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jun 06 16:16:58 volumio volumio[17804]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jun 06 16:16:58 volumio volumio[17804]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jun 06 16:16:58 volumio volumio[17804]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jun 06 16:16:58 volumio volumio[17804]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jun 06 16:16:58 volumio volumio[17804]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jun 06 16:16:58 volumio volumio[17804]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jun 06 16:16:58 volumio volumio[17804]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jun 06 16:16:58 volumio volumio[17804]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jun 06 16:16:58 volumio volumio[17804]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jun 06 16:16:58 volumio volumio[17804]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jun 06 16:16:58 volumio volumio[17804]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jun 06 16:16:58 volumio volumio[17804]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jun 06 16:16:58 volumio volumio[17804]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jun 06 16:16:58 volumio volumio[17804]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jun 06 16:16:58 volumio volumio[17804]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jun 06 16:16:58 volumio volumio[17804]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jun 06 16:16:58 volumio volumio[17804]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jun 06 16:16:58 volumio volumio[17804]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jun 06 16:16:58 volumio volumio[17804]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jun 06 16:16:58 volumio volumio[17804]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jun 06 16:16:58 volumio volumio[17804]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jun 06 16:16:58 volumio volumio[17804]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jun 06 16:16:58 volumio volumio[17804]: info: Adding plugin bluetooth to MyMusic Plugins Jun 06 16:16:58 volumio volumio[17804]: info: Adding plugin multiroom to MyMusic Plugins Jun 06 16:16:58 volumio volumio[17804]: info: Adding plugin metavolumio to MyMusic Plugins Jun 06 16:16:58 volumio volumio[17804]: info: Adding plugin cd_controller to MyMusic Plugins Jun 06 16:16:58 volumio volumio[17804]: info: Adding plugin smart_inputs to MyMusic Plugins Jun 06 16:16:58 volumio volumio[17804]: info: Adding plugin tidalconnect to MyMusic Plugins Jun 06 16:16:58 volumio volumio[17804]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jun 06 16:16:58 volumio volumio[17804]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jun 06 16:16:58 volumio volumio[17804]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jun 06 16:16:58 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 06 16:16:58 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 06 16:16:58 volumio volumio[17804]: info: Starting MyVolumio Remote Streaming Endpoints Jun 06 16:16:58 volumio volumio[17804]: info: MyVolumio login type: Token Jun 06 16:16:58 volumio volumio[17804]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jun 06 16:16:58 volumio volumio[17804]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jun 06 16:16:59 volumio volumio[17804]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jun 06 16:16:59 volumio volumio[17804]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jun 06 16:16:59 volumio volumio[17804]: info: Streaming services startup Jun 06 16:16:59 volumio volumio[17804]: info: Starting Streaming Daemon Jun 06 16:16:59 volumio sudo[18091]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 06 16:16:59 volumio sudo[18091]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 16:16:59 volumio volumio[17804]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jun 06 16:16:59 volumio sudo[18091]: pam_unix(sudo:session): session closed for user root Jun 06 16:16:59 volumio volumio[17804]: error: Cannot start Volumio Streaming Daemon Jun 06 16:16:59 volumio volumio[17804]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 06 16:16:59 volumio volumio[17804]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 06 16:16:59 volumio volumio[17804]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jun 06 16:17:00 volumio volumio[17804]: info: MyVolumio token set successfully Jun 06 16:17:00 volumio volumio[17804]: info: MYVOLUMIO: Adding device Jun 06 16:17:00 volumio volumio[17804]: info: MYVOLUMIO: Evaluating Server Jun 06 16:17:00 volumio volumio[17804]: info: MyVolumio status changed Jun 06 16:17:00 volumio volumio[17804]: info: Streaming services startup Jun 06 16:17:00 volumio volumio[17804]: info: Starting Streaming Daemon Jun 06 16:17:00 volumio volumio[17804]: info: Removing browser output: myVolumio user plan is not superstar Jun 06 16:17:00 volumio volumio[17804]: info: Removing audio output: Jun 06 16:17:00 volumio volumio[17804]: info: Stoppping Tunnel 1 Jun 06 16:17:00 volumio sudo[18116]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 06 16:17:00 volumio sudo[18116]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 16:17:00 volumio sudo[18118]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jun 06 16:17:00 volumio sudo[18118]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 16:17:00 volumio sudo[18116]: pam_unix(sudo:session): session closed for user root Jun 06 16:17:00 volumio volumio[17804]: error: Cannot start Volumio Streaming Daemon Jun 06 16:17:00 volumio volumio[17804]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 06 16:17:00 volumio volumio[17804]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 06 16:17:00 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. Jun 06 16:17:00 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. Jun 06 16:17:00 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. Jun 06 16:17:00 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. Jun 06 16:17:00 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. Jun 06 16:17:00 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. Jun 06 16:17:00 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. Jun 06 16:17:00 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. Jun 06 16:17:00 volumio sudo[18118]: pam_unix(sudo:session): session closed for user root Jun 06 16:17:00 volumio volumio[17804]: info: Remote SSH Stopped Jun 06 16:17:00 volumio volumio[17804]: info: Setting Geolocation for MyVolumio to eu5 Jun 06 16:17:00 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 06 16:17:00 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 06 16:17:00 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 06 16:17:01 volumio volumio[17804]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jun 06 16:17:01 volumio volumio[17804]: info: Updating MyVolumio device info Jun 06 16:17:01 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 06 16:17:01 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 06 16:17:01 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 06 16:17:01 volumio volumio[17804]: info: CoreCommandRouter::volumioPlay Jun 06 16:17:01 volumio volumio[17804]: info: CoreStateMachine::play index 1 Jun 06 16:17:01 volumio volumio[17804]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 06 16:17:01 volumio volumio[17804]: info: CoreStateMachine::stop Jun 06 16:17:01 volumio volumio[17804]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 06 16:17:01 volumio volumio[17804]: info: CoreStateMachine::play index undefined Jun 06 16:17:01 volumio volumio[17804]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 06 16:17:01 volumio volumio[17804]: info: CorePlayQueue::getTrack 1 Jun 06 16:17:01 volumio volumio[17804]: info: CoreStateMachine::startPlaybackTimer Jun 06 16:17:01 volumio volumio[17804]: info: CorePlayQueue::getTrack 1 Jun 06 16:17:01 volumio volumio[17804]: info: [ytmusic-play] clearAddPlayTrack: ytmusic/video@explodeTrackData:o=%7B%22type%22%3A%22video%22%2C%22title%22%3A%22Faith%20Move%20Mountain%22%2C%22artist%22%3A%22King%20Kong%20et%20Little%20Lion%20Sound%22%2C%22album%22%3A%22Faith%20Move%20Mountain%22%2C%22albumart%22%3A%22https%3A%2F%2Flh3.googleusercontent.com%2FjZCnuGH-skNyOCdCbDCl1IgvQ6b9_YP_fsIXjMf36FpRNWEGenKe-Z9uvUmXz6o_BKD1nSmybfwDH0I%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22cOd0JK6HzZM%22%2C%22playlistId%22%3A%22OLAK5uy_mxAVRAA1BnfCNVvKs3CqVWC2YjSlMs8KA%22%2C%22params%22%3A%22OAHyAQIIAZIEI1FQTTY5TXJUbmFlb0dPUlBaNTdQUHpLUDVMcWtROVM5RER5%22%2C%22index%22%3A0%2C%22playlistSetVideoId%22%3A%229F3E08FCD6FABA75%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22playlistId%22%3A%22OLAK5uy_mxAVRAA1BnfCNVvKs3CqVWC2YjSlMs8KA%22%2C%22params%22%3A%22OAHyAQIIAZIEI1FQTTY5TXJUbmFlb0dPUlBaNTdQUHpLUDVMcWtROVM5RER5%22%2C%22videoId%22%3A%22cOd0JK6HzZM%22%7D%7D%7D%7D Jun 06 16:17:02 volumio volumio[17804]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jun 06 16:17:02 volumio volumio[17804]: info: CorePlayQueue::getTrack 1 Jun 06 16:17:02 volumio volumio[17804]: verbose: ControllerMpd::sendMpdCommand stop Jun 06 16:17:02 volumio volumio[17804]: info: ControllerMpd::getState Jun 06 16:17:02 volumio volumio[17804]: verbose: ControllerMpd::sendMpdCommand status Jun 06 16:17:02 volumio volumio[17804]: info: Jun 06 16:17:02 volumio volumio[17804]: ---------------------------- MPD announces state update: player Jun 06 16:17:02 volumio volumio[17804]: info: sendMpdCommand stop took 2 milliseconds Jun 06 16:17:02 volumio volumio[17804]: info: ControllerMpd::getState Jun 06 16:17:02 volumio volumio[17804]: verbose: ControllerMpd::sendMpdCommand status Jun 06 16:17:02 volumio volumio[17804]: verbose: ControllerMpd::sendMpdCommand clear Jun 06 16:17:02 volumio volumio[17804]: info: Jun 06 16:17:02 volumio volumio[17804]: ---------------------------- MPD announces state update: player Jun 06 16:17:02 volumio volumio[17804]: info: ControllerMpd::getState Jun 06 16:17:02 volumio volumio[17804]: verbose: ControllerMpd::sendMpdCommand status Jun 06 16:17:02 volumio volumio[17804]: info: Jun 06 16:17:02 volumio volumio[17804]: ---------------------------- MPD announces system playlist update Jun 06 16:17:02 volumio volumio[17804]: info: Ignoring MPD Status Update Jun 06 16:17:02 volumio volumio[17804]: info: sendMpdCommand status took 4 milliseconds Jun 06 16:17:02 volumio volumio[17804]: info: sendMpdCommand status took 3 milliseconds Jun 06 16:17:02 volumio volumio[17804]: info: sendMpdCommand clear took 3 milliseconds Jun 06 16:17:02 volumio volumio[17804]: info: sendMpdCommand status took 2 milliseconds Jun 06 16:17:02 volumio volumio[17804]: verbose: ControllerMpd::parseState Jun 06 16:17:02 volumio volumio[17804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 06 16:17:02 volumio volumio[17804]: verbose: ControllerMpd::parseState Jun 06 16:17:02 volumio volumio[17804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 06 16:17:02 volumio volumio[17804]: verbose: ControllerMpd::sendMpdCommand addid "https://rr3---sn-25glenlk.googlevideo.com/videoplayback?expire=1749241021&ei=XfhCaKPMNP_2xN8Pq-rIyQ0&ip=212.129.2.121&id=o-AAYsBiwh8OzEwkyxTszwasdjJtfbNv9WZathJ9oAS_Ns&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1749219421%2C&mh=QA&mm=31%2C29&mn=sn-25glenlk%2Csn-25ge7nzr&ms=au%2Crdu&mv=m&mvi=3&pl=24&rms=au%2Cau&ctier=A&pfa=5&pcm2=no&gcr=fr&initcwndbps=1355000&hightc=yes&siu=1&bui=AY1jyLO6Kf7WCOobqO8_YXxCzGolA-2GGzDL8PBqBw3TQN6Pt8O7zb-zVE9FCp_1fZrFjFtgAA&spc=l3OVKS6bCrY1X__EM-jMSeBARURj_8owH3yJFr1AfFqzHk1Oqqwfx1of0DHe92_BTKBqXCg7cNu8uxw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=U2JMusqDe1x7NV3hR_VQ2X4Q&rqh=1&gir=yes&clen=5844721&dur=172.321&lmt=1746536788030650&mt=1749218954&fvip=3&keepalive=yes&fexp=51466698&c=WEB_REMIX&sefc=1&txp=2318224&n=sXBkSANQm-t5Sw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cpcm2%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgUzVj0vZaUAnIOf4-pJlDGY6rEYoUFv_E9EJqIDQuWz4CIQDf963u_4vF0-YF8YRPigNdOBIwGSCewmTDqrVzlwdxaA%3D%3D&sig=AJfQdSswRQIhAK2QL3knV1OOFX5DEEfwYxN5944qKHwPlJ6g_GUQQ7xpAiAA5CK9Q4Rg1vK2_ONIvWqe-8h9MSl6eUTyWLRqk3aOSg%3D%3D&pot=Ml-J20Fb7ru8aInnHutyPWyEYrW46w-vcidDnH0Q4psQKWIO5DC_piSuip7XLxrkJucgaKRPh4ZDExt5TtkmaFkUeghQ0UpC4Jt1wKbWoynovf99uZF-1qNg9QwWIIXG6Q%3D%3D&cver=1.20250219.01.00&t.YouTube" Jun 06 16:17:02 volumio volumio[17804]: verbose: ControllerMpd::parseState Jun 06 16:17:02 volumio volumio[17804]: info: ControllerMpd::pushState Jun 06 16:17:02 volumio volumio[17804]: info: CoreCommandRouter::servicePushState Jun 06 16:17:02 volumio volumio[17804]: info: CoreStateMachine::pushState Jun 06 16:17:02 volumio volumio[17804]: info: CorePlayQueue::getTrack 1 Jun 06 16:17:02 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 06 16:17:02 volumio volumio[17804]: info: CoreCommandRouter::volumioPushState Jun 06 16:17:02 volumio volumio[17804]: info: CorePlayQueue::getTrack 1 Jun 06 16:17:02 volumio volumio[17804]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current ytmusic Received mpd Jun 06 16:17:02 volumio volumio[17804]: info: ------------------------------ 12ms Jun 06 16:17:02 volumio volumio[17804]: info: Jun 06 16:17:02 volumio volumio[17804]: ---------------------------- MPD announces system playlist update Jun 06 16:17:02 volumio volumio[17804]: info: Ignoring MPD Status Update Jun 06 16:17:02 volumio volumio[17804]: info: Jun 06 16:17:02 volumio volumio[17804]: ---------------------------- MPD announces state update: player Jun 06 16:17:02 volumio volumio[17804]: info: ControllerMpd::getState Jun 06 16:17:02 volumio volumio[17804]: verbose: ControllerMpd::sendMpdCommand status Jun 06 16:17:02 volumio volumio[17804]: error: updateQueue error: null Jun 06 16:17:02 volumio volumio[17804]: info: Jun 06 16:17:02 volumio volumio[17804]: ---------------------------- MPD announces system playlist update Jun 06 16:17:02 volumio volumio[17804]: info: Ignoring MPD Status Update Jun 06 16:17:02 volumio volumio[17804]: info: ------------------------------ 15ms Jun 06 16:17:02 volumio volumio[17804]: info: sendMpdCommand playlistinfo took 13 milliseconds Jun 06 16:17:02 volumio volumio[17804]: info: sendMpdCommand playlistinfo took 13 milliseconds Jun 06 16:17:02 volumio volumio[17804]: info: sendMpdCommand addid "https://rr3---sn-25glenlk.googlevideo.com/videoplayback?expire=1749241021&ei=XfhCaKPMNP_2xN8Pq-rIyQ0&ip=212.129.2.121&id=o-AAYsBiwh8OzEwkyxTszwasdjJtfbNv9WZathJ9oAS_Ns&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1749219421%2C&mh=QA&mm=31%2C29&mn=sn-25glenlk%2Csn-25ge7nzr&ms=au%2Crdu&mv=m&mvi=3&pl=24&rms=au%2Cau&ctier=A&pfa=5&pcm2=no&gcr=fr&initcwndbps=1355000&hightc=yes&siu=1&bui=AY1jyLO6Kf7WCOobqO8_YXxCzGolA-2GGzDL8PBqBw3TQN6Pt8O7zb-zVE9FCp_1fZrFjFtgAA&spc=l3OVKS6bCrY1X__EM-jMSeBARURj_8owH3yJFr1AfFqzHk1Oqqwfx1of0DHe92_BTKBqXCg7cNu8uxw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=U2JMusqDe1x7NV3hR_VQ2X4Q&rqh=1&gir=yes&clen=5844721&dur=172.321&lmt=1746536788030650&mt=1749218954&fvip=3&keepalive=yes&fexp=51466698&c=WEB_REMIX&sefc=1&txp=2318224&n=sXBkSANQm-t5Sw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cpcm2%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgUzVj0vZaUAnIOf4-pJlDGY6rEYoUFv_E9EJqIDQuWz4CIQDf963u_4vF0-YF8YRPigNdOBIwGSCewmTDqrVzlwdxaA%3D%3D&sig=AJfQdSswRQIhAK2QL3knV1OOFX5DEEfwYxN5944qKHwPlJ6g_GUQQ7xpAiAA5CK9Q4Rg1vK2_ONIvWqe-8h9MSl6eUTyWLRqk3aOSg%3D%3D&pot=Ml-J20Fb7ru8aInnHutyPWyEYrW46w-vcidDnH0Q4psQKWIO5DC_piSuip7XLxrkJucgaKRPh4ZDExt5TtkmaFkUeghQ0UpC4Jt1wKbWoynovf99uZF-1qNg9QwWIIXG6Q%3D%3D&cver=1.20250219.01.00&t.YouTube" took 13 milliseconds Jun 06 16:17:02 volumio volumio[17804]: info: ------------------------------ 3ms Jun 06 16:17:02 volumio volumio[17804]: info: sendMpdCommand status took 2 milliseconds Jun 06 16:17:02 volumio volumio[17804]: verbose: ControllerMpd::parseTrackInfo Jun 06 16:17:02 volumio volumio[17804]: verbose: ControllerMpd::parseTrackInfo Jun 06 16:17:02 volumio volumio[17804]: verbose: ControllerMpd::parseState Jun 06 16:17:02 volumio volumio[17804]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Jun 06 16:17:02 volumio volumio[17804]: verbose: MPD COMMAND [object Object] Jun 06 16:17:02 volumio volumio[17804]: verbose: MPD COMMAND [object Object] Jun 06 16:17:02 volumio volumio[17804]: verbose: MPD COMMAND [object Object] Jun 06 16:17:02 volumio volumio[17804]: info: ControllerMpd::pushState Jun 06 16:17:02 volumio volumio[17804]: info: CoreCommandRouter::servicePushState Jun 06 16:17:02 volumio volumio[17804]: info: CoreStateMachine::pushState Jun 06 16:17:02 volumio volumio[17804]: info: CorePlayQueue::getTrack 1 Jun 06 16:17:02 volumio volumio[17804]: info: CoreCommandRouter::volumioPushState Jun 06 16:17:02 volumio volumio[17804]: info: CorePlayQueue::getTrack 1 Jun 06 16:17:02 volumio volumio[17804]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current ytmusic Received mpd Jun 06 16:17:02 volumio volumio[17804]: info: ------------------------------ 25ms Jun 06 16:17:02 volumio volumio[17804]: info: ------------------------------ 13ms Jun 06 16:17:02 volumio volumio[17804]: info: Jun 06 16:17:02 volumio volumio[17804]: ---------------------------- MPD announces system playlist update Jun 06 16:17:02 volumio volumio[17804]: info: Ignoring MPD Status Update Jun 06 16:17:02 volumio volumio[17804]: info: Jun 06 16:17:02 volumio volumio[17804]: ---------------------------- MPD announces system playlist update Jun 06 16:17:02 volumio volumio[17804]: info: Ignoring MPD Status Update Jun 06 16:17:02 volumio volumio[17804]: info: [KeepClock] Status: stop Jun 06 16:17:02 volumio volumio[17804]: info: [KeepClock] oldStatus: undefined Jun 06 16:17:02 volumio volumio[17804]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Jun 06 16:17:02 volumio volumio[17804]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Jun 06 16:17:02 volumio volumio[17804]: info: Jun 06 16:17:02 volumio volumio[17804]: ---------------------------- MPD announces system playlist update Jun 06 16:17:02 volumio volumio[17804]: info: Ignoring MPD Status Update Jun 06 16:17:02 volumio volumio[17804]: info: ------------------------------ 19ms Jun 06 16:17:02 volumio volumio[17804]: info: ------------------------------ 7ms Jun 06 16:17:02 volumio volumio[17804]: info: ------------------------------ 8ms Jun 06 16:17:02 volumio volumio[17804]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 06 16:17:02 volumio volumio[17804]: verbose: ControllerMpd::sendMpdCommand play Jun 06 16:17:02 volumio volumio[17804]: info: Jun 06 16:17:02 volumio volumio[17804]: ---------------------------- MPD announces system playlist update Jun 06 16:17:02 volumio volumio[17804]: info: Ignoring MPD Status Update Jun 06 16:17:02 volumio volumio[17804]: info: Jun 06 16:17:02 volumio volumio[17804]: ---------------------------- MPD announces system playlist update Jun 06 16:17:02 volumio volumio[17804]: info: Ignoring MPD Status Update Jun 06 16:17:02 volumio volumio[17804]: info: ------------------------------ 5ms Jun 06 16:17:02 volumio volumio[17804]: info: sendMpdCommand play took 3 milliseconds Jun 06 16:17:02 volumio volumio[17804]: info: ------------------------------ 3ms Jun 06 16:17:02 volumio volumio[17804]: info: ------------------------------ 2ms Jun 06 16:17:02 volumio volumio[17804]: info: Jun 06 16:17:02 volumio volumio[17804]: ---------------------------- MPD announces state update: player Jun 06 16:17:02 volumio volumio[17804]: info: ControllerMpd::getState Jun 06 16:17:02 volumio volumio[17804]: verbose: ControllerMpd::sendMpdCommand status Jun 06 16:17:02 volumio volumio[17804]: info: Jun 06 16:17:02 volumio volumio[17804]: ---------------------------- MPD announces state update: player Jun 06 16:17:02 volumio volumio[17804]: info: ControllerMpd::getState Jun 06 16:17:02 volumio volumio[17804]: verbose: ControllerMpd::sendMpdCommand status Jun 06 16:17:02 volumio volumio[17804]: info: ControllerMpd::getState Jun 06 16:17:02 volumio volumio[17804]: verbose: ControllerMpd::sendMpdCommand status Jun 06 16:17:02 volumio volumio[17804]: info: Jun 06 16:17:02 volumio volumio[17804]: ---------------------------- MPD announces state update: player Jun 06 16:17:02 volumio volumio[17804]: info: ControllerMpd::getState Jun 06 16:17:02 volumio volumio[17804]: verbose: ControllerMpd::sendMpdCommand status Jun 06 16:17:02 volumio volumio[17804]: info: Jun 06 16:17:02 volumio volumio[17804]: ---------------------------- MPD announces state update: player Jun 06 16:17:02 volumio volumio[17804]: info: ControllerMpd::getState Jun 06 16:17:02 volumio volumio[17804]: verbose: ControllerMpd::sendMpdCommand status Jun 06 16:17:02 volumio volumio[17804]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 06 16:17:02 volumio volumio[17804]: info: sendMpdCommand status took 9 milliseconds Jun 06 16:17:02 volumio volumio[17804]: info: ControllerMpd::getState Jun 06 16:17:02 volumio volumio[17804]: verbose: ControllerMpd::sendMpdCommand status Jun 06 16:17:02 volumio volumio[17804]: verbose: ControllerMpd::parseState Jun 06 16:17:02 volumio volumio[17804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 06 16:17:02 volumio volumio[17804]: info: Jun 06 16:17:02 volumio volumio[17804]: ---------------------------- MPD announces state update: player Jun 06 16:17:02 volumio volumio[17804]: info: ControllerMpd::getState Jun 06 16:17:02 volumio volumio[17804]: verbose: ControllerMpd::sendMpdCommand status Jun 06 16:17:02 volumio volumio[17804]: info: Jun 06 16:17:02 volumio volumio[17804]: ---------------------------- MPD announces state update: player Jun 06 16:17:02 volumio volumio[17804]: info: ControllerMpd::getState Jun 06 16:17:02 volumio volumio[17804]: verbose: ControllerMpd::sendMpdCommand status Jun 06 16:17:02 volumio volumio[17804]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 06 16:17:02 volumio volumio[17804]: info: sendMpdCommand status took 20 milliseconds Jun 06 16:17:02 volumio volumio[17804]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 06 16:17:02 volumio volumio[17804]: info: sendMpdCommand status took 20 milliseconds Jun 06 16:17:02 volumio volumio[17804]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 06 16:17:02 volumio volumio[17804]: info: sendMpdCommand status took 21 milliseconds Jun 06 16:17:02 volumio volumio[17804]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 06 16:17:02 volumio volumio[17804]: info: sendMpdCommand status took 22 milliseconds Jun 06 16:17:02 volumio volumio[17804]: info: sendMpdCommand clearerror took 16 milliseconds Jun 06 16:17:02 volumio volumio[17804]: info: sendMpdCommand status took 15 milliseconds Jun 06 16:17:02 volumio volumio[17804]: info: sendMpdCommand playlistinfo took 13 milliseconds Jun 06 16:17:02 volumio volumio[17804]: info: sendMpdCommand status took 12 milliseconds Jun 06 16:17:02 volumio volumio[17804]: verbose: ControllerMpd::parseState Jun 06 16:17:02 volumio volumio[17804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 06 16:17:02 volumio volumio[17804]: verbose: ControllerMpd::parseState Jun 06 16:17:02 volumio volumio[17804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 06 16:17:02 volumio volumio[17804]: verbose: ControllerMpd::parseState Jun 06 16:17:02 volumio volumio[17804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 06 16:17:02 volumio volumio[17804]: verbose: ControllerMpd::parseState Jun 06 16:17:02 volumio volumio[17804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 06 16:17:02 volumio volumio[17804]: verbose: ControllerMpd::parseState Jun 06 16:17:02 volumio volumio[17804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 06 16:17:02 volumio volumio[17804]: verbose: ControllerMpd::parseTrackInfo Jun 06 16:17:02 volumio volumio[17804]: verbose: ControllerMpd::parseState Jun 06 16:17:02 volumio volumio[17804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 06 16:17:02 volumio volumio[17804]: info: ControllerMpd::pushState Jun 06 16:17:02 volumio volumio[17804]: info: CoreCommandRouter::servicePushState Jun 06 16:17:02 volumio volumio[17804]: info: CorePlayQueue::getTrack 1 Jun 06 16:17:02 volumio volumio[17804]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":172,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Faith Move Mountain","artist":"King Kong et Little Lion Sound","album":"Faith Move Mountain","uri":"https://rr3---sn-25glenlk.googlevideo.com/videoplayback?expire=1749241021&ei=XfhCaKPMNP_2xN8Pq-rIyQ0&ip=212.129.2.121&id=o-AAYsBiwh8OzEwkyxTszwasdjJtfbNv9WZathJ9oAS_Ns&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1749219421%2C&mh=QA&mm=31%2C29&mn=sn-25glenlk%2Csn-25ge7nzr&ms=au%2Crdu&mv=m&mvi=3&pl=24&rms=au%2Cau&ctier=A&pfa=5&pcm2=no&gcr=fr&initcwndbps=1355000&hightc=yes&siu=1&bui=AY1jyLO6Kf7WCOobqO8_YXxCzGolA-2GGzDL8PBqBw3TQN6Pt8O7zb-zVE9FCp_1fZrFjFtgAA&spc=l3OVKS6bCrY1X__EM-jMSeBARURj_8owH3yJFr1AfFqzHk1Oqqwfx1of0DHe92_BTKBqXCg7cNu8uxw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=U2JMusqDe1x7NV3hR_VQ2X4Q&rqh=1&gir=yes&clen=5844721&dur=172.321&lmt=1746536788030650&mt=1749218954&fvip=3&keepalive=yes&fexp=51466698&c=WEB_REMIX&sefc=1&txp=2318224&n=sXBkSANQm-t5Sw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cpcm2%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgUzVj0vZaUAnIOf4-pJlDGY6rEYoUFv_E9EJqIDQuWz4CIQDf963u_4vF0-YF8YRPigNdOBIwGSCewmTDqrVzlwdxaA%3D%3D&sig=AJfQdSswRQIhAK2QL3knV1OOFX5DEEfwYxN5944qKHwPlJ6g_GUQQ7xpAiAA5CK9Q4Rg1vK2_ONIvWqe-8h9MSl6eUTyWLRqk3aOSg%3D%3D&pot=Ml-J20Fb7ru8aInnHutyPWyEYrW46w-vcidDnH0Q4psQKWIO5DC_piSuip7XLxrkJucgaKRPh4ZDExt5TtkmaFkUeghQ0UpC4Jt1wKbWoynovf99uZF-1qNg9QwWIIXG6Q%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Jun 06 16:17:02 volumio volumio[17804]: verbose: CURRENT POSITION 1 Jun 06 16:17:02 volumio volumio[17804]: info: CoreStateMachine::syncState stateService pause Jun 06 16:17:02 volumio volumio[17804]: info: CoreStateMachine::syncState currentStatus stop Jun 06 16:17:02 volumio volumio[17804]: info: CoreStateMachine::pushState Jun 06 16:17:02 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 06 16:17:02 volumio volumio[17804]: info: CoreCommandRouter::volumioPushState Jun 06 16:17:02 volumio volumio[17804]: info: ------------------------------ 56ms Jun 06 16:17:02 volumio volumio[17804]: info: [KeepClock] Status: Jun 06 16:17:02 volumio volumio[17804]: info: [KeepClock] oldStatus: stop Jun 06 16:17:02 volumio volumio[17804]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Jun 06 16:17:02 volumio volumio[17804]: info: sendMpdCommand status took 52 milliseconds Jun 06 16:17:02 volumio volumio[17804]: info: sendMpdCommand clearerror took 47 milliseconds Jun 06 16:17:02 volumio volumio[17804]: info: sendMpdCommand clearerror took 46 milliseconds Jun 06 16:17:02 volumio volumio[17804]: info: sendMpdCommand clearerror took 45 milliseconds Jun 06 16:17:02 volumio volumio[17804]: info: sendMpdCommand clearerror took 44 milliseconds Jun 06 16:17:02 volumio volumio[17804]: info: sendMpdCommand playlistinfo took 29 milliseconds Jun 06 16:17:02 volumio volumio[17804]: info: sendMpdCommand playlistinfo took 29 milliseconds Jun 06 16:17:02 volumio volumio[17804]: info: sendMpdCommand playlistinfo took 29 milliseconds Jun 06 16:17:02 volumio volumio[17804]: info: sendMpdCommand playlistinfo took 29 milliseconds Jun 06 16:17:02 volumio volumio[17804]: info: sendMpdCommand playlistinfo took 28 milliseconds Jun 06 16:17:02 volumio volumio[17804]: info: sendMpdCommand playlistinfo took 27 milliseconds Jun 06 16:17:02 volumio volumio[17804]: verbose: ControllerMpd::parseState Jun 06 16:17:02 volumio volumio[17804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 06 16:17:02 volumio volumio[17804]: verbose: ControllerMpd::parseTrackInfo Jun 06 16:17:02 volumio volumio[17804]: verbose: ControllerMpd::parseTrackInfo Jun 06 16:17:02 volumio volumio[17804]: verbose: ControllerMpd::parseTrackInfo Jun 06 16:17:02 volumio volumio[17804]: verbose: ControllerMpd::parseTrackInfo Jun 06 16:17:02 volumio volumio[17804]: verbose: ControllerMpd::parseTrackInfo Jun 06 16:17:02 volumio volumio[17804]: verbose: ControllerMpd::parseTrackInfo Jun 06 16:17:02 volumio volumio[17804]: info: ControllerMpd::pushState Jun 06 16:17:02 volumio volumio[17804]: info: CoreCommandRouter::servicePushState Jun 06 16:17:02 volumio volumio[17804]: info: CorePlayQueue::getTrack 1 Jun 06 16:17:02 volumio volumio[17804]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":172,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Faith Move Mountain","artist":"King Kong et Little Lion Sound","album":"Faith Move Mountain","uri":"https://rr3---sn-25glenlk.googlevideo.com/videoplayback?expire=1749241021&ei=XfhCaKPMNP_2xN8Pq-rIyQ0&ip=212.129.2.121&id=o-AAYsBiwh8OzEwkyxTszwasdjJtfbNv9WZathJ9oAS_Ns&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1749219421%2C&mh=QA&mm=31%2C29&mn=sn-25glenlk%2Csn-25ge7nzr&ms=au%2Crdu&mv=m&mvi=3&pl=24&rms=au%2Cau&ctier=A&pfa=5&pcm2=no&gcr=fr&initcwndbps=1355000&hightc=yes&siu=1&bui=AY1jyLO6Kf7WCOobqO8_YXxCzGolA-2GGzDL8PBqBw3TQN6Pt8O7zb-zVE9FCp_1fZrFjFtgAA&spc=l3OVKS6bCrY1X__EM-jMSeBARURj_8owH3yJFr1AfFqzHk1Oqqwfx1of0DHe92_BTKBqXCg7cNu8uxw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=U2JMusqDe1x7NV3hR_VQ2X4Q&rqh=1&gir=yes&clen=5844721&dur=172.321&lmt=1746536788030650&mt=1749218954&fvip=3&keepalive=yes&fexp=51466698&c=WEB_REMIX&sefc=1&txp=2318224&n=sXBkSANQm-t5Sw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cpcm2%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgUzVj0vZaUAnIOf4-pJlDGY6rEYoUFv_E9EJqIDQuWz4CIQDf963u_4vF0-YF8YRPigNdOBIwGSCewmTDqrVzlwdxaA%3D%3D&sig=AJfQdSswRQIhAK2QL3knV1OOFX5DEEfwYxN5944qKHwPlJ6g_GUQQ7xpAiAA5CK9Q4Rg1vK2_ONIvWqe-8h9MSl6eUTyWLRqk3aOSg%3D%3D&pot=Ml-J20Fb7ru8aInnHutyPWyEYrW46w-vcidDnH0Q4psQKWIO5DC_piSuip7XLxrkJucgaKRPh4ZDExt5TtkmaFkUeghQ0UpC4Jt1wKbWoynovf99uZF-1qNg9QwWIIXG6Q%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Jun 06 16:17:02 volumio volumio[17804]: verbose: CURRENT POSITION 1 Jun 06 16:17:02 volumio volumio[17804]: info: CoreStateMachine::syncState stateService pause Jun 06 16:17:02 volumio volumio[17804]: info: CoreStateMachine::syncState currentStatus stop Jun 06 16:17:02 volumio volumio[17804]: info: CoreStateMachine::pushState Jun 06 16:17:02 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 06 16:17:02 volumio volumio[17804]: info: CoreCommandRouter::volumioPushState Jun 06 16:17:02 volumio volumio[17804]: info: ControllerMpd::pushState Jun 06 16:17:02 volumio volumio[17804]: info: CoreCommandRouter::servicePushState Jun 06 16:17:02 volumio volumio[17804]: info: CorePlayQueue::getTrack 1 Jun 06 16:17:02 volumio volumio[17804]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":172,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Faith Move Mountain","artist":"King Kong et Little Lion Sound","album":"Faith Move Mountain","uri":"https://rr3---sn-25glenlk.googlevideo.com/videoplayback?expire=1749241021&ei=XfhCaKPMNP_2xN8Pq-rIyQ0&ip=212.129.2.121&id=o-AAYsBiwh8OzEwkyxTszwasdjJtfbNv9WZathJ9oAS_Ns&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1749219421%2C&mh=QA&mm=31%2C29&mn=sn-25glenlk%2Csn-25ge7nzr&ms=au%2Crdu&mv=m&mvi=3&pl=24&rms=au%2Cau&ctier=A&pfa=5&pcm2=no&gcr=fr&initcwndbps=1355000&hightc=yes&siu=1&bui=AY1jyLO6Kf7WCOobqO8_YXxCzGolA-2GGzDL8PBqBw3TQN6Pt8O7zb-zVE9FCp_1fZrFjFtgAA&spc=l3OVKS6bCrY1X__EM-jMSeBARURj_8owH3yJFr1AfFqzHk1Oqqwfx1of0DHe92_BTKBqXCg7cNu8uxw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=U2JMusqDe1x7NV3hR_VQ2X4Q&rqh=1&gir=yes&clen=5844721&dur=172.321&lmt=1746536788030650&mt=1749218954&fvip=3&keepalive=yes&fexp=51466698&c=WEB_REMIX&sefc=1&txp=2318224&n=sXBkSANQm-t5Sw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cpcm2%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgUzVj0vZaUAnIOf4-pJlDGY6rEYoUFv_E9EJqIDQuWz4CIQDf963u_4vF0-YF8YRPigNdOBIwGSCewmTDqrVzlwdxaA%3D%3D&sig=AJfQdSswRQIhAK2QL3knV1OOFX5DEEfwYxN5944qKHwPlJ6g_GUQQ7xpAiAA5CK9Q4Rg1vK2_ONIvWqe-8h9MSl6eUTyWLRqk3aOSg%3D%3D&pot=Ml-J20Fb7ru8aInnHutyPWyEYrW46w-vcidDnH0Q4psQKWIO5DC_piSuip7XLxrkJucgaKRPh4ZDExt5TtkmaFkUeghQ0UpC4Jt1wKbWoynovf99uZF-1qNg9QwWIIXG6Q%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Jun 06 16:17:02 volumio volumio[17804]: verbose: CURRENT POSITION 1 Jun 06 16:17:02 volumio volumio[17804]: info: CoreStateMachine::syncState stateService pause Jun 06 16:17:02 volumio volumio[17804]: info: CoreStateMachine::syncState currentStatus stop Jun 06 16:17:02 volumio volumio[17804]: info: CoreStateMachine::pushState Jun 06 16:17:02 volumio volumio[17804]: info: CoreCommandRouter::volumioPushState Jun 06 16:17:02 volumio volumio[17804]: info: ControllerMpd::pushState Jun 06 16:17:02 volumio volumio[17804]: info: CoreCommandRouter::servicePushState Jun 06 16:17:02 volumio volumio[17804]: info: CorePlayQueue::getTrack 1 Jun 06 16:17:02 volumio volumio[17804]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":172,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Faith Move Mountain","artist":"King Kong et Little Lion Sound","album":"Faith Move Mountain","uri":"https://rr3---sn-25glenlk.googlevideo.com/videoplayback?expire=1749241021&ei=XfhCaKPMNP_2xN8Pq-rIyQ0&ip=212.129.2.121&id=o-AAYsBiwh8OzEwkyxTszwasdjJtfbNv9WZathJ9oAS_Ns&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1749219421%2C&mh=QA&mm=31%2C29&mn=sn-25glenlk%2Csn-25ge7nzr&ms=au%2Crdu&mv=m&mvi=3&pl=24&rms=au%2Cau&ctier=A&pfa=5&pcm2=no&gcr=fr&initcwndbps=1355000&hightc=yes&siu=1&bui=AY1jyLO6Kf7WCOobqO8_YXxCzGolA-2GGzDL8PBqBw3TQN6Pt8O7zb-zVE9FCp_1fZrFjFtgAA&spc=l3OVKS6bCrY1X__EM-jMSeBARURj_8owH3yJFr1AfFqzHk1Oqqwfx1of0DHe92_BTKBqXCg7cNu8uxw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=U2JMusqDe1x7NV3hR_VQ2X4Q&rqh=1&gir=yes&clen=5844721&dur=172.321&lmt=1746536788030650&mt=1749218954&fvip=3&keepalive=yes&fexp=51466698&c=WEB_REMIX&sefc=1&txp=2318224&n=sXBkSANQm-t5Sw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cpcm2%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgUzVj0vZaUAnIOf4-pJlDGY6rEYoUFv_E9EJqIDQuWz4CIQDf963u_4vF0-YF8YRPigNdOBIwGSCewmTDqrVzlwdxaA%3D%3D&sig=AJfQdSswRQIhAK2QL3knV1OOFX5DEEfwYxN5944qKHwPlJ6g_GUQQ7xpAiAA5CK9Q4Rg1vK2_ONIvWqe-8h9MSl6eUTyWLRqk3aOSg%3D%3D&pot=Ml-J20Fb7ru8aInnHutyPWyEYrW46w-vcidDnH0Q4psQKWIO5DC_piSuip7XLxrkJucgaKRPh4ZDExt5TtkmaFkUeghQ0UpC4Jt1wKbWoynovf99uZF-1qNg9QwWIIXG6Q%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Jun 06 16:17:02 volumio volumio[17804]: verbose: CURRENT POSITION 1 Jun 06 16:17:02 volumio volumio[17804]: info: CoreStateMachine::syncState stateService pause Jun 06 16:17:02 volumio volumio[17804]: info: CoreStateMachine::syncState currentStatus stop Jun 06 16:17:02 volumio volumio[17804]: info: CoreStateMachine::pushState Jun 06 16:17:02 volumio volumio[17804]: info: CoreCommandRouter::volumioPushState Jun 06 16:17:02 volumio volumio[17804]: info: ControllerMpd::pushState Jun 06 16:17:02 volumio volumio[17804]: info: CoreCommandRouter::servicePushState Jun 06 16:17:02 volumio volumio[17804]: info: CorePlayQueue::getTrack 1 Jun 06 16:17:02 volumio volumio[17804]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":172,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Faith Move Mountain","artist":"King Kong et Little Lion Sound","album":"Faith Move Mountain","uri":"https://rr3---sn-25glenlk.googlevideo.com/videoplayback?expire=1749241021&ei=XfhCaKPMNP_2xN8Pq-rIyQ0&ip=212.129.2.121&id=o-AAYsBiwh8OzEwkyxTszwasdjJtfbNv9WZathJ9oAS_Ns&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1749219421%2C&mh=QA&mm=31%2C29&mn=sn-25glenlk%2Csn-25ge7nzr&ms=au%2Crdu&mv=m&mvi=3&pl=24&rms=au%2Cau&ctier=A&pfa=5&pcm2=no&gcr=fr&initcwndbps=1355000&hightc=yes&siu=1&bui=AY1jyLO6Kf7WCOobqO8_YXxCzGolA-2GGzDL8PBqBw3TQN6Pt8O7zb-zVE9FCp_1fZrFjFtgAA&spc=l3OVKS6bCrY1X__EM-jMSeBARURj_8owH3yJFr1AfFqzHk1Oqqwfx1of0DHe92_BTKBqXCg7cNu8uxw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=U2JMusqDe1x7NV3hR_VQ2X4Q&rqh=1&gir=yes&clen=5844721&dur=172.321&lmt=1746536788030650&mt=1749218954&fvip=3&keepalive=yes&fexp=51466698&c=WEB_REMIX&sefc=1&txp=2318224&n=sXBkSANQm-t5Sw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cpcm2%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgUzVj0vZaUAnIOf4-pJlDGY6rEYoUFv_E9EJqIDQuWz4CIQDf963u_4vF0-YF8YRPigNdOBIwGSCewmTDqrVzlwdxaA%3D%3D&sig=AJfQdSswRQIhAK2QL3knV1OOFX5DEEfwYxN5944qKHwPlJ6g_GUQQ7xpAiAA5CK9Q4Rg1vK2_ONIvWqe-8h9MSl6eUTyWLRqk3aOSg%3D%3D&pot=Ml-J20Fb7ru8aInnHutyPWyEYrW46w-vcidDnH0Q4psQKWIO5DC_piSuip7XLxrkJucgaKRPh4ZDExt5TtkmaFkUeghQ0UpC4Jt1wKbWoynovf99uZF-1qNg9QwWIIXG6Q%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Jun 06 16:17:02 volumio volumio[17804]: verbose: CURRENT POSITION 1 Jun 06 16:17:02 volumio volumio[17804]: info: CoreStateMachine::syncState stateService pause Jun 06 16:17:02 volumio volumio[17804]: info: CoreStateMachine::syncState currentStatus stop Jun 06 16:17:02 volumio volumio[17804]: info: CoreStateMachine::pushState Jun 06 16:17:02 volumio volumio[17804]: info: CoreCommandRouter::volumioPushState Jun 06 16:17:02 volumio volumio[17804]: info: ------------------------------ 113ms Jun 06 16:17:02 volumio volumio[17804]: info: ------------------------------ 112ms Jun 06 16:17:02 volumio volumio[17804]: info: ------------------------------ 113ms Jun 06 16:17:02 volumio volumio[17804]: info: ------------------------------ 102ms Jun 06 16:17:02 volumio volumio[17804]: info: sendMpdCommand playlistinfo took 47 milliseconds Jun 06 16:17:02 volumio volumio[17804]: verbose: ControllerMpd::parseTrackInfo Jun 06 16:17:02 volumio volumio[17804]: info: ControllerMpd::pushState Jun 06 16:17:02 volumio volumio[17804]: info: CoreCommandRouter::servicePushState Jun 06 16:17:02 volumio volumio[17804]: info: CorePlayQueue::getTrack 1 Jun 06 16:17:02 volumio volumio[17804]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":172,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Faith Move Mountain","artist":"King Kong et Little Lion Sound","album":"Faith Move Mountain","uri":"https://rr3---sn-25glenlk.googlevideo.com/videoplayback?expire=1749241021&ei=XfhCaKPMNP_2xN8Pq-rIyQ0&ip=212.129.2.121&id=o-AAYsBiwh8OzEwkyxTszwasdjJtfbNv9WZathJ9oAS_Ns&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1749219421%2C&mh=QA&mm=31%2C29&mn=sn-25glenlk%2Csn-25ge7nzr&ms=au%2Crdu&mv=m&mvi=3&pl=24&rms=au%2Cau&ctier=A&pfa=5&pcm2=no&gcr=fr&initcwndbps=1355000&hightc=yes&siu=1&bui=AY1jyLO6Kf7WCOobqO8_YXxCzGolA-2GGzDL8PBqBw3TQN6Pt8O7zb-zVE9FCp_1fZrFjFtgAA&spc=l3OVKS6bCrY1X__EM-jMSeBARURj_8owH3yJFr1AfFqzHk1Oqqwfx1of0DHe92_BTKBqXCg7cNu8uxw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=U2JMusqDe1x7NV3hR_VQ2X4Q&rqh=1&gir=yes&clen=5844721&dur=172.321&lmt=1746536788030650&mt=1749218954&fvip=3&keepalive=yes&fexp=51466698&c=WEB_REMIX&sefc=1&txp=2318224&n=sXBkSANQm-t5Sw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cpcm2%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgUzVj0vZaUAnIOf4-pJlDGY6rEYoUFv_E9EJqIDQuWz4CIQDf963u_4vF0-YF8YRPigNdOBIwGSCewmTDqrVzlwdxaA%3D%3D&sig=AJfQdSswRQIhAK2QL3knV1OOFX5DEEfwYxN5944qKHwPlJ6g_GUQQ7xpAiAA5CK9Q4Rg1vK2_ONIvWqe-8h9MSl6eUTyWLRqk3aOSg%3D%3D&pot=Ml-J20Fb7ru8aInnHutyPWyEYrW46w-vcidDnH0Q4psQKWIO5DC_piSuip7XLxrkJucgaKRPh4ZDExt5TtkmaFkUeghQ0UpC4Jt1wKbWoynovf99uZF-1qNg9QwWIIXG6Q%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Jun 06 16:17:02 volumio volumio[17804]: verbose: CURRENT POSITION 1 Jun 06 16:17:02 volumio volumio[17804]: info: CoreStateMachine::syncState stateService pause Jun 06 16:17:02 volumio volumio[17804]: info: CoreStateMachine::syncState currentStatus stop Jun 06 16:17:02 volumio volumio[17804]: info: CoreStateMachine::pushState Jun 06 16:17:02 volumio volumio[17804]: info: CoreCommandRouter::volumioPushState Jun 06 16:17:02 volumio volumio[17804]: info: ------------------------------ 114ms Jun 06 16:17:02 volumio volumio[17804]: info: [KeepClock] Status: undefined Jun 06 16:17:02 volumio volumio[17804]: info: [KeepClock] oldStatus: Jun 06 16:17:02 volumio volumio[17804]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Jun 06 16:17:02 volumio volumio[17804]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Jun 06 16:17:02 volumio volumio[17804]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Jun 06 16:17:02 volumio volumio[17804]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Jun 06 16:17:02 volumio volumio[17804]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Jun 06 16:17:05 volumio volumio[17804]: info: MYVOLUMIO: Adding device Jun 06 16:17:05 volumio volumio[17804]: info: MYVOLUMIO: Evaluating Server Jun 06 16:17:05 volumio volumio[17804]: info: Setting Geolocation for MyVolumio to eu2 Jun 06 16:17:05 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 06 16:17:05 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 06 16:17:05 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 06 16:17:05 volumio volumio[17804]: info: CoreCommandRouter::volumioPlay Jun 06 16:17:05 volumio volumio[17804]: info: CoreStateMachine::play index 1 Jun 06 16:17:05 volumio volumio[17804]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 06 16:17:05 volumio volumio[17804]: info: CoreStateMachine::stop Jun 06 16:17:05 volumio volumio[17804]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 06 16:17:05 volumio volumio[17804]: info: CoreStateMachine::play index undefined Jun 06 16:17:05 volumio volumio[17804]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 06 16:17:05 volumio volumio[17804]: info: CorePlayQueue::getTrack 1 Jun 06 16:17:05 volumio volumio[17804]: info: CoreStateMachine::startPlaybackTimer Jun 06 16:17:05 volumio volumio[17804]: info: CorePlayQueue::getTrack 1 Jun 06 16:17:05 volumio volumio[17804]: info: [ytmusic-play] clearAddPlayTrack: ytmusic/video@explodeTrackData:o=%7B%22type%22%3A%22video%22%2C%22title%22%3A%22Faith%20Move%20Mountain%22%2C%22artist%22%3A%22King%20Kong%20et%20Little%20Lion%20Sound%22%2C%22album%22%3A%22Faith%20Move%20Mountain%22%2C%22albumart%22%3A%22https%3A%2F%2Flh3.googleusercontent.com%2FjZCnuGH-skNyOCdCbDCl1IgvQ6b9_YP_fsIXjMf36FpRNWEGenKe-Z9uvUmXz6o_BKD1nSmybfwDH0I%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22cOd0JK6HzZM%22%2C%22playlistId%22%3A%22OLAK5uy_mxAVRAA1BnfCNVvKs3CqVWC2YjSlMs8KA%22%2C%22params%22%3A%22OAHyAQIIAZIEI1FQTTY5TXJUbmFlb0dPUlBaNTdQUHpLUDVMcWtROVM5RER5%22%2C%22index%22%3A0%2C%22playlistSetVideoId%22%3A%229F3E08FCD6FABA75%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22playlistId%22%3A%22OLAK5uy_mxAVRAA1BnfCNVvKs3CqVWC2YjSlMs8KA%22%2C%22params%22%3A%22OAHyAQIIAZIEI1FQTTY5TXJUbmFlb0dPUlBaNTdQUHpLUDVMcWtROVM5RER5%22%2C%22videoId%22%3A%22cOd0JK6HzZM%22%7D%7D%7D%7D Jun 06 16:17:06 volumio volumio[17804]: info: CorePlayQueue::getTrack 1 Jun 06 16:17:06 volumio volumio[17804]: verbose: ControllerMpd::sendMpdCommand stop Jun 06 16:17:06 volumio volumio[17804]: info: ControllerMpd::getState Jun 06 16:17:06 volumio volumio[17804]: verbose: ControllerMpd::sendMpdCommand status Jun 06 16:17:06 volumio volumio[17804]: info: Jun 06 16:17:06 volumio volumio[17804]: ---------------------------- MPD announces state update: player Jun 06 16:17:06 volumio volumio[17804]: info: sendMpdCommand stop took 4 milliseconds Jun 06 16:17:06 volumio volumio[17804]: info: ControllerMpd::getState Jun 06 16:17:06 volumio volumio[17804]: verbose: ControllerMpd::sendMpdCommand status Jun 06 16:17:06 volumio volumio[17804]: verbose: ControllerMpd::sendMpdCommand clear Jun 06 16:17:06 volumio volumio[17804]: info: Jun 06 16:17:06 volumio volumio[17804]: ---------------------------- MPD announces state update: player Jun 06 16:17:06 volumio volumio[17804]: info: ControllerMpd::getState Jun 06 16:17:06 volumio volumio[17804]: verbose: ControllerMpd::sendMpdCommand status Jun 06 16:17:06 volumio volumio[17804]: info: Jun 06 16:17:06 volumio volumio[17804]: ---------------------------- MPD announces state update: player Jun 06 16:17:06 volumio volumio[17804]: info: ControllerMpd::getState Jun 06 16:17:06 volumio volumio[17804]: verbose: ControllerMpd::sendMpdCommand status Jun 06 16:17:06 volumio volumio[17804]: info: Jun 06 16:17:06 volumio volumio[17804]: ---------------------------- MPD announces system playlist update Jun 06 16:17:06 volumio volumio[17804]: info: Ignoring MPD Status Update Jun 06 16:17:06 volumio volumio[17804]: info: sendMpdCommand status took 6 milliseconds Jun 06 16:17:06 volumio volumio[17804]: info: sendMpdCommand status took 5 milliseconds Jun 06 16:17:06 volumio volumio[17804]: info: sendMpdCommand clear took 5 milliseconds Jun 06 16:17:06 volumio volumio[17804]: info: sendMpdCommand status took 4 milliseconds Jun 06 16:17:06 volumio volumio[17804]: info: sendMpdCommand status took 3 milliseconds Jun 06 16:17:06 volumio volumio[17804]: verbose: ControllerMpd::parseState Jun 06 16:17:06 volumio volumio[17804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 06 16:17:06 volumio volumio[17804]: verbose: ControllerMpd::parseState Jun 06 16:17:06 volumio volumio[17804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 06 16:17:06 volumio volumio[17804]: verbose: ControllerMpd::sendMpdCommand addid "https://rr3---sn-25glenlk.googlevideo.com/videoplayback?expire=1749241025&ei=YfhCaPTYLcblxN8Pnqz88A4&ip=212.129.2.121&id=o-ANaHq7QhuDlpUcLBAkGDjfvWGq5ySQ8Oi8owQM20R_m0&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1749219425%2C&mh=QA&mm=31%2C26&mn=sn-25glenlk%2Csn-5hne6nz6&ms=au%2Conr&mv=m&mvi=3&pl=24&rms=au%2Cau&ctier=A&pfa=5&pcm2=no&gcr=fr&initcwndbps=1316250&hightc=yes&siu=1&bui=AY1jyLNt0OgXF-LvyBMOyxfrx6xuoWF_8XsJITRYI5B5wK0DmeAH8kgx9_ky9ELXcHRwKeeoyg&spc=l3OVKdPM6-BeRJ0b1vy5WU141e_ZNyrN1YbzqCA5kaDcjYUk4kZFGUlOOxHl8v0wDjqgzU-GX4bAI30&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=B2KXzcCcis9tsEyc5W_pLRoQ&rqh=1&gir=yes&clen=5844721&dur=172.321&lmt=1746536788030650&mt=1749218954&fvip=1&keepalive=yes&fexp=51466698&c=WEB_REMIX&sefc=1&txp=2318224&n=tTq92600hL5k8w&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cpcm2%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgbm-teTCK_u7pAQw0aI3CncOGQDJws9LeH0PywcDvDZoCIQDH6SNRYzDYoHxWhZcYmrLKVZdLo_O6cjaLPW1pVuCbgg%3D%3D&sig=AJfQdSswRQIhAIzp9aD3dS2B4Ce46EgVG4l78FpTp31N1j6SlCfgT89nAiBbfMKII0lJ6jyDJzjTlNEniIQDzRrOmTDZpyGj2GlE-w%3D%3D&pot=Ml-J20Fb7ru8aInnHutyPWyEYrW46w-vcidDnH0Q4psQKWIO5DC_piSuip7XLxrkJucgaKRPh4ZDExt5TtkmaFkUeghQ0UpC4Jt1wKbWoynovf99uZF-1qNg9QwWIIXG6Q%3D%3D&cver=1.20250219.01.00&t.YouTube" Jun 06 16:17:06 volumio volumio[17804]: verbose: ControllerMpd::parseState Jun 06 16:17:06 volumio volumio[17804]: verbose: ControllerMpd::parseState Jun 06 16:17:06 volumio volumio[17804]: info: ControllerMpd::pushState Jun 06 16:17:06 volumio volumio[17804]: info: CoreCommandRouter::servicePushState Jun 06 16:17:06 volumio volumio[17804]: info: CoreStateMachine::pushState Jun 06 16:17:06 volumio volumio[17804]: info: CorePlayQueue::getTrack 1 Jun 06 16:17:06 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 06 16:17:06 volumio volumio[17804]: info: CoreCommandRouter::volumioPushState Jun 06 16:17:06 volumio volumio[17804]: info: CorePlayQueue::getTrack 1 Jun 06 16:17:06 volumio volumio[17804]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current ytmusic Received mpd Jun 06 16:17:06 volumio volumio[17804]: info: ControllerMpd::pushState Jun 06 16:17:06 volumio volumio[17804]: info: CoreCommandRouter::servicePushState Jun 06 16:17:06 volumio volumio[17804]: info: CoreStateMachine::pushState Jun 06 16:17:06 volumio volumio[17804]: info: CorePlayQueue::getTrack 1 Jun 06 16:17:06 volumio volumio[17804]: info: CoreCommandRouter::volumioPushState Jun 06 16:17:06 volumio volumio[17804]: info: CorePlayQueue::getTrack 1 Jun 06 16:17:06 volumio volumio[17804]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current ytmusic Received mpd Jun 06 16:17:06 volumio volumio[17804]: info: ------------------------------ 20ms Jun 06 16:17:06 volumio volumio[17804]: info: ------------------------------ 19ms Jun 06 16:17:06 volumio volumio[17804]: info: Jun 06 16:17:06 volumio volumio[17804]: ---------------------------- MPD announces system playlist update Jun 06 16:17:06 volumio volumio[17804]: info: Ignoring MPD Status Update Jun 06 16:17:06 volumio volumio[17804]: info: Jun 06 16:17:06 volumio volumio[17804]: ---------------------------- MPD announces system playlist update Jun 06 16:17:06 volumio volumio[17804]: info: Ignoring MPD Status Update Jun 06 16:17:06 volumio volumio[17804]: error: updateQueue error: null Jun 06 16:17:06 volumio volumio[17804]: info: Jun 06 16:17:06 volumio volumio[17804]: ---------------------------- MPD announces system playlist update Jun 06 16:17:06 volumio volumio[17804]: info: Ignoring MPD Status Update Jun 06 16:17:06 volumio volumio[17804]: info: ------------------------------ 20ms Jun 06 16:17:06 volumio volumio[17804]: info: sendMpdCommand playlistinfo took 18 milliseconds Jun 06 16:17:06 volumio volumio[17804]: info: sendMpdCommand playlistinfo took 18 milliseconds Jun 06 16:17:06 volumio volumio[17804]: info: sendMpdCommand addid "https://rr3---sn-25glenlk.googlevideo.com/videoplayback?expire=1749241025&ei=YfhCaPTYLcblxN8Pnqz88A4&ip=212.129.2.121&id=o-ANaHq7QhuDlpUcLBAkGDjfvWGq5ySQ8Oi8owQM20R_m0&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1749219425%2C&mh=QA&mm=31%2C26&mn=sn-25glenlk%2Csn-5hne6nz6&ms=au%2Conr&mv=m&mvi=3&pl=24&rms=au%2Cau&ctier=A&pfa=5&pcm2=no&gcr=fr&initcwndbps=1316250&hightc=yes&siu=1&bui=AY1jyLNt0OgXF-LvyBMOyxfrx6xuoWF_8XsJITRYI5B5wK0DmeAH8kgx9_ky9ELXcHRwKeeoyg&spc=l3OVKdPM6-BeRJ0b1vy5WU141e_ZNyrN1YbzqCA5kaDcjYUk4kZFGUlOOxHl8v0wDjqgzU-GX4bAI30&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=B2KXzcCcis9tsEyc5W_pLRoQ&rqh=1&gir=yes&clen=5844721&dur=172.321&lmt=1746536788030650&mt=1749218954&fvip=1&keepalive=yes&fexp=51466698&c=WEB_REMIX&sefc=1&txp=2318224&n=tTq92600hL5k8w&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cpcm2%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgbm-teTCK_u7pAQw0aI3CncOGQDJws9LeH0PywcDvDZoCIQDH6SNRYzDYoHxWhZcYmrLKVZdLo_O6cjaLPW1pVuCbgg%3D%3D&sig=AJfQdSswRQIhAIzp9aD3dS2B4Ce46EgVG4l78FpTp31N1j6SlCfgT89nAiBbfMKII0lJ6jyDJzjTlNEniIQDzRrOmTDZpyGj2GlE-w%3D%3D&pot=Ml-J20Fb7ru8aInnHutyPWyEYrW46w-vcidDnH0Q4psQKWIO5DC_piSuip7XLxrkJucgaKRPh4ZDExt5TtkmaFkUeghQ0UpC4Jt1wKbWoynovf99uZF-1qNg9QwWIIXG6Q%3D%3D&cver=1.20250219.01.00&t.YouTube" took 18 milliseconds Jun 06 16:17:06 volumio volumio[17804]: info: ------------------------------ 3ms Jun 06 16:17:06 volumio volumio[17804]: info: ------------------------------ 2ms Jun 06 16:17:06 volumio volumio[17804]: verbose: ControllerMpd::parseTrackInfo Jun 06 16:17:06 volumio volumio[17804]: verbose: ControllerMpd::parseTrackInfo Jun 06 16:17:06 volumio volumio[17804]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Jun 06 16:17:06 volumio volumio[17804]: verbose: MPD COMMAND [object Object] Jun 06 16:17:06 volumio volumio[17804]: verbose: MPD COMMAND [object Object] Jun 06 16:17:06 volumio volumio[17804]: verbose: MPD COMMAND [object Object] Jun 06 16:17:06 volumio volumio[17804]: info: ------------------------------ 28ms Jun 06 16:17:06 volumio volumio[17804]: info: Jun 06 16:17:06 volumio volumio[17804]: ---------------------------- MPD announces system playlist update Jun 06 16:17:06 volumio volumio[17804]: info: Ignoring MPD Status Update Jun 06 16:17:06 volumio volumio[17804]: info: Jun 06 16:17:06 volumio volumio[17804]: ---------------------------- MPD announces system playlist update Jun 06 16:17:06 volumio volumio[17804]: info: Ignoring MPD Status Update Jun 06 16:17:06 volumio volumio[17804]: info: [KeepClock] Status: stop Jun 06 16:17:06 volumio volumio[17804]: info: [KeepClock] oldStatus: undefined Jun 06 16:17:06 volumio volumio[17804]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Jun 06 16:17:06 volumio volumio[17804]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Jun 06 16:17:06 volumio volumio[17804]: info: Jun 06 16:17:06 volumio volumio[17804]: ---------------------------- MPD announces system playlist update Jun 06 16:17:06 volumio volumio[17804]: info: Ignoring MPD Status Update Jun 06 16:17:06 volumio volumio[17804]: info: ------------------------------ 9ms Jun 06 16:17:06 volumio volumio[17804]: info: ------------------------------ 6ms Jun 06 16:17:06 volumio volumio[17804]: info: ------------------------------ 5ms Jun 06 16:17:06 volumio volumio[17804]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 06 16:17:06 volumio volumio[17804]: verbose: ControllerMpd::sendMpdCommand play Jun 06 16:17:06 volumio volumio[17804]: info: Jun 06 16:17:06 volumio volumio[17804]: ---------------------------- MPD announces system playlist update Jun 06 16:17:06 volumio volumio[17804]: info: Ignoring MPD Status Update Jun 06 16:17:06 volumio volumio[17804]: info: Jun 06 16:17:06 volumio volumio[17804]: ---------------------------- MPD announces system playlist update Jun 06 16:17:06 volumio volumio[17804]: info: Ignoring MPD Status Update Jun 06 16:17:06 volumio volumio[17804]: info: ------------------------------ 3ms Jun 06 16:17:06 volumio volumio[17804]: info: sendMpdCommand play took 4 milliseconds Jun 06 16:17:06 volumio volumio[17804]: info: ------------------------------ 4ms Jun 06 16:17:06 volumio volumio[17804]: info: ------------------------------ 3ms Jun 06 16:17:06 volumio volumio[17804]: info: Jun 06 16:17:06 volumio volumio[17804]: ---------------------------- MPD announces state update: player Jun 06 16:17:06 volumio volumio[17804]: info: ControllerMpd::getState Jun 06 16:17:06 volumio volumio[17804]: verbose: ControllerMpd::sendMpdCommand status Jun 06 16:17:06 volumio volumio[17804]: info: Jun 06 16:17:06 volumio volumio[17804]: ---------------------------- MPD announces state update: player Jun 06 16:17:06 volumio volumio[17804]: info: ControllerMpd::getState Jun 06 16:17:06 volumio volumio[17804]: verbose: ControllerMpd::sendMpdCommand status Jun 06 16:17:06 volumio volumio[17804]: info: ControllerMpd::getState Jun 06 16:17:06 volumio volumio[17804]: verbose: ControllerMpd::sendMpdCommand status Jun 06 16:17:06 volumio volumio[17804]: info: Jun 06 16:17:06 volumio volumio[17804]: ---------------------------- MPD announces state update: player Jun 06 16:17:06 volumio volumio[17804]: info: ControllerMpd::getState Jun 06 16:17:06 volumio volumio[17804]: verbose: ControllerMpd::sendMpdCommand status Jun 06 16:17:06 volumio volumio[17804]: info: Jun 06 16:17:06 volumio volumio[17804]: ---------------------------- MPD announces state update: player Jun 06 16:17:06 volumio volumio[17804]: info: ControllerMpd::getState Jun 06 16:17:06 volumio volumio[17804]: verbose: ControllerMpd::sendMpdCommand status Jun 06 16:17:06 volumio volumio[17804]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 06 16:17:06 volumio volumio[17804]: info: sendMpdCommand status took 10 milliseconds Jun 06 16:17:06 volumio volumio[17804]: info: ControllerMpd::getState Jun 06 16:17:06 volumio volumio[17804]: verbose: ControllerMpd::sendMpdCommand status Jun 06 16:17:06 volumio volumio[17804]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 06 16:17:06 volumio volumio[17804]: info: sendMpdCommand status took 10 milliseconds Jun 06 16:17:06 volumio volumio[17804]: verbose: ControllerMpd::parseState Jun 06 16:17:06 volumio volumio[17804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 06 16:17:06 volumio volumio[17804]: verbose: ControllerMpd::parseState Jun 06 16:17:06 volumio volumio[17804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 06 16:17:06 volumio volumio[17804]: info: Jun 06 16:17:06 volumio volumio[17804]: ---------------------------- MPD announces state update: player Jun 06 16:17:06 volumio volumio[17804]: info: ControllerMpd::getState Jun 06 16:17:06 volumio volumio[17804]: verbose: ControllerMpd::sendMpdCommand status Jun 06 16:17:06 volumio volumio[17804]: info: Jun 06 16:17:06 volumio volumio[17804]: ---------------------------- MPD announces state update: player Jun 06 16:17:06 volumio volumio[17804]: info: ControllerMpd::getState Jun 06 16:17:06 volumio volumio[17804]: verbose: ControllerMpd::sendMpdCommand status Jun 06 16:17:06 volumio volumio[17804]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 06 16:17:06 volumio volumio[17804]: info: sendMpdCommand status took 22 milliseconds Jun 06 16:17:06 volumio volumio[17804]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 06 16:17:06 volumio volumio[17804]: info: sendMpdCommand status took 22 milliseconds Jun 06 16:17:06 volumio volumio[17804]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 06 16:17:06 volumio volumio[17804]: info: sendMpdCommand status took 22 milliseconds Jun 06 16:17:06 volumio volumio[17804]: info: sendMpdCommand clearerror took 17 milliseconds Jun 06 16:17:06 volumio volumio[17804]: info: sendMpdCommand status took 16 milliseconds Jun 06 16:17:06 volumio volumio[17804]: info: sendMpdCommand clearerror took 17 milliseconds Jun 06 16:17:06 volumio volumio[17804]: info: sendMpdCommand playlistinfo took 12 milliseconds Jun 06 16:17:06 volumio volumio[17804]: info: sendMpdCommand playlistinfo took 11 milliseconds Jun 06 16:17:06 volumio volumio[17804]: info: sendMpdCommand status took 9 milliseconds Jun 06 16:17:06 volumio volumio[17804]: info: sendMpdCommand status took 8 milliseconds Jun 06 16:17:06 volumio volumio[17804]: verbose: ControllerMpd::parseState Jun 06 16:17:06 volumio volumio[17804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 06 16:17:06 volumio volumio[17804]: verbose: ControllerMpd::parseState Jun 06 16:17:06 volumio volumio[17804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 06 16:17:06 volumio volumio[17804]: verbose: ControllerMpd::parseState Jun 06 16:17:06 volumio volumio[17804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 06 16:17:06 volumio volumio[17804]: verbose: ControllerMpd::parseState Jun 06 16:17:06 volumio volumio[17804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 06 16:17:06 volumio volumio[17804]: verbose: ControllerMpd::parseTrackInfo Jun 06 16:17:06 volumio volumio[17804]: verbose: ControllerMpd::parseTrackInfo Jun 06 16:17:06 volumio volumio[17804]: verbose: ControllerMpd::parseState Jun 06 16:17:06 volumio volumio[17804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 06 16:17:06 volumio volumio[17804]: verbose: ControllerMpd::parseState Jun 06 16:17:06 volumio volumio[17804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 06 16:17:06 volumio volumio[17804]: info: ControllerMpd::pushState Jun 06 16:17:06 volumio volumio[17804]: info: CoreCommandRouter::servicePushState Jun 06 16:17:06 volumio volumio[17804]: info: CorePlayQueue::getTrack 1 Jun 06 16:17:06 volumio volumio[17804]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":172,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Faith Move Mountain","artist":"King Kong et Little Lion Sound","album":"Faith Move Mountain","uri":"https://rr3---sn-25glenlk.googlevideo.com/videoplayback?expire=1749241025&ei=YfhCaPTYLcblxN8Pnqz88A4&ip=212.129.2.121&id=o-ANaHq7QhuDlpUcLBAkGDjfvWGq5ySQ8Oi8owQM20R_m0&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1749219425%2C&mh=QA&mm=31%2C26&mn=sn-25glenlk%2Csn-5hne6nz6&ms=au%2Conr&mv=m&mvi=3&pl=24&rms=au%2Cau&ctier=A&pfa=5&pcm2=no&gcr=fr&initcwndbps=1316250&hightc=yes&siu=1&bui=AY1jyLNt0OgXF-LvyBMOyxfrx6xuoWF_8XsJITRYI5B5wK0DmeAH8kgx9_ky9ELXcHRwKeeoyg&spc=l3OVKdPM6-BeRJ0b1vy5WU141e_ZNyrN1YbzqCA5kaDcjYUk4kZFGUlOOxHl8v0wDjqgzU-GX4bAI30&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=B2KXzcCcis9tsEyc5W_pLRoQ&rqh=1&gir=yes&clen=5844721&dur=172.321&lmt=1746536788030650&mt=1749218954&fvip=1&keepalive=yes&fexp=51466698&c=WEB_REMIX&sefc=1&txp=2318224&n=tTq92600hL5k8w&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cpcm2%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgbm-teTCK_u7pAQw0aI3CncOGQDJws9LeH0PywcDvDZoCIQDH6SNRYzDYoHxWhZcYmrLKVZdLo_O6cjaLPW1pVuCbgg%3D%3D&sig=AJfQdSswRQIhAIzp9aD3dS2B4Ce46EgVG4l78FpTp31N1j6SlCfgT89nAiBbfMKII0lJ6jyDJzjTlNEniIQDzRrOmTDZpyGj2GlE-w%3D%3D&pot=Ml-J20Fb7ru8aInnHutyPWyEYrW46w-vcidDnH0Q4psQKWIO5DC_piSuip7XLxrkJucgaKRPh4ZDExt5TtkmaFkUeghQ0UpC4Jt1wKbWoynovf99uZF-1qNg9QwWIIXG6Q%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Jun 06 16:17:06 volumio volumio[17804]: verbose: CURRENT POSITION 1 Jun 06 16:17:06 volumio volumio[17804]: info: CoreStateMachine::syncState stateService pause Jun 06 16:17:06 volumio volumio[17804]: info: CoreStateMachine::syncState currentStatus stop Jun 06 16:17:06 volumio volumio[17804]: info: CoreStateMachine::pushState Jun 06 16:17:06 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 06 16:17:06 volumio volumio[17804]: info: CoreCommandRouter::volumioPushState Jun 06 16:17:06 volumio volumio[17804]: info: ControllerMpd::pushState Jun 06 16:17:06 volumio volumio[17804]: info: CoreCommandRouter::servicePushState Jun 06 16:17:06 volumio volumio[17804]: info: CorePlayQueue::getTrack 1 Jun 06 16:17:06 volumio volumio[17804]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":172,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Faith Move Mountain","artist":"King Kong et Little Lion Sound","album":"Faith Move Mountain","uri":"https://rr3---sn-25glenlk.googlevideo.com/videoplayback?expire=1749241025&ei=YfhCaPTYLcblxN8Pnqz88A4&ip=212.129.2.121&id=o-ANaHq7QhuDlpUcLBAkGDjfvWGq5ySQ8Oi8owQM20R_m0&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1749219425%2C&mh=QA&mm=31%2C26&mn=sn-25glenlk%2Csn-5hne6nz6&ms=au%2Conr&mv=m&mvi=3&pl=24&rms=au%2Cau&ctier=A&pfa=5&pcm2=no&gcr=fr&initcwndbps=1316250&hightc=yes&siu=1&bui=AY1jyLNt0OgXF-LvyBMOyxfrx6xuoWF_8XsJITRYI5B5wK0DmeAH8kgx9_ky9ELXcHRwKeeoyg&spc=l3OVKdPM6-BeRJ0b1vy5WU141e_ZNyrN1YbzqCA5kaDcjYUk4kZFGUlOOxHl8v0wDjqgzU-GX4bAI30&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=B2KXzcCcis9tsEyc5W_pLRoQ&rqh=1&gir=yes&clen=5844721&dur=172.321&lmt=1746536788030650&mt=1749218954&fvip=1&keepalive=yes&fexp=51466698&c=WEB_REMIX&sefc=1&txp=2318224&n=tTq92600hL5k8w&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cpcm2%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgbm-teTCK_u7pAQw0aI3CncOGQDJws9LeH0PywcDvDZoCIQDH6SNRYzDYoHxWhZcYmrLKVZdLo_O6cjaLPW1pVuCbgg%3D%3D&sig=AJfQdSswRQIhAIzp9aD3dS2B4Ce46EgVG4l78FpTp31N1j6SlCfgT89nAiBbfMKII0lJ6jyDJzjTlNEniIQDzRrOmTDZpyGj2GlE-w%3D%3D&pot=Ml-J20Fb7ru8aInnHutyPWyEYrW46w-vcidDnH0Q4psQKWIO5DC_piSuip7XLxrkJucgaKRPh4ZDExt5TtkmaFkUeghQ0UpC4Jt1wKbWoynovf99uZF-1qNg9QwWIIXG6Q%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Jun 06 16:17:06 volumio volumio[17804]: verbose: CURRENT POSITION 1 Jun 06 16:17:06 volumio volumio[17804]: info: CoreStateMachine::syncState stateService pause Jun 06 16:17:06 volumio volumio[17804]: info: CoreStateMachine::syncState currentStatus stop Jun 06 16:17:06 volumio volumio[17804]: info: CoreStateMachine::pushState Jun 06 16:17:06 volumio volumio[17804]: info: CoreCommandRouter::volumioPushState Jun 06 16:17:06 volumio volumio[17804]: info: ------------------------------ 59ms Jun 06 16:17:06 volumio volumio[17804]: info: ------------------------------ 58ms Jun 06 16:17:06 volumio volumio[17804]: info: [KeepClock] Status: Jun 06 16:17:06 volumio volumio[17804]: info: [KeepClock] oldStatus: stop Jun 06 16:17:06 volumio volumio[17804]: info: [KeepClock] Status: undefined Jun 06 16:17:06 volumio volumio[17804]: info: [KeepClock] oldStatus: Jun 06 16:17:06 volumio volumio[17804]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Jun 06 16:17:06 volumio volumio[17804]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Jun 06 16:17:06 volumio volumio[17804]: info: sendMpdCommand clearerror took 48 milliseconds Jun 06 16:17:06 volumio volumio[17804]: info: sendMpdCommand clearerror took 47 milliseconds Jun 06 16:17:06 volumio volumio[17804]: info: sendMpdCommand clearerror took 46 milliseconds Jun 06 16:17:06 volumio volumio[17804]: info: sendMpdCommand playlistinfo took 37 milliseconds Jun 06 16:17:06 volumio volumio[17804]: info: sendMpdCommand playlistinfo took 38 milliseconds Jun 06 16:17:06 volumio volumio[17804]: info: sendMpdCommand playlistinfo took 37 milliseconds Jun 06 16:17:06 volumio volumio[17804]: info: sendMpdCommand playlistinfo took 37 milliseconds Jun 06 16:17:06 volumio volumio[17804]: info: sendMpdCommand playlistinfo took 36 milliseconds Jun 06 16:17:06 volumio volumio[17804]: info: sendMpdCommand playlistinfo took 36 milliseconds Jun 06 16:17:06 volumio volumio[17804]: verbose: ControllerMpd::parseTrackInfo Jun 06 16:17:06 volumio volumio[17804]: verbose: ControllerMpd::parseTrackInfo Jun 06 16:17:06 volumio volumio[17804]: verbose: ControllerMpd::parseTrackInfo Jun 06 16:17:06 volumio volumio[17804]: verbose: ControllerMpd::parseTrackInfo Jun 06 16:17:06 volumio volumio[17804]: verbose: ControllerMpd::parseTrackInfo Jun 06 16:17:06 volumio volumio[17804]: verbose: ControllerMpd::parseTrackInfo Jun 06 16:17:06 volumio volumio[17804]: info: ControllerMpd::pushState Jun 06 16:17:06 volumio volumio[17804]: info: CoreCommandRouter::servicePushState Jun 06 16:17:06 volumio volumio[17804]: info: CorePlayQueue::getTrack 1 Jun 06 16:17:06 volumio volumio[17804]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":172,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Faith Move Mountain","artist":"King Kong et Little Lion Sound","album":"Faith Move Mountain","uri":"https://rr3---sn-25glenlk.googlevideo.com/videoplayback?expire=1749241025&ei=YfhCaPTYLcblxN8Pnqz88A4&ip=212.129.2.121&id=o-ANaHq7QhuDlpUcLBAkGDjfvWGq5ySQ8Oi8owQM20R_m0&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1749219425%2C&mh=QA&mm=31%2C26&mn=sn-25glenlk%2Csn-5hne6nz6&ms=au%2Conr&mv=m&mvi=3&pl=24&rms=au%2Cau&ctier=A&pfa=5&pcm2=no&gcr=fr&initcwndbps=1316250&hightc=yes&siu=1&bui=AY1jyLNt0OgXF-LvyBMOyxfrx6xuoWF_8XsJITRYI5B5wK0DmeAH8kgx9_ky9ELXcHRwKeeoyg&spc=l3OVKdPM6-BeRJ0b1vy5WU141e_ZNyrN1YbzqCA5kaDcjYUk4kZFGUlOOxHl8v0wDjqgzU-GX4bAI30&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=B2KXzcCcis9tsEyc5W_pLRoQ&rqh=1&gir=yes&clen=5844721&dur=172.321&lmt=1746536788030650&mt=1749218954&fvip=1&keepalive=yes&fexp=51466698&c=WEB_REMIX&sefc=1&txp=2318224&n=tTq92600hL5k8w&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cpcm2%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgbm-teTCK_u7pAQw0aI3CncOGQDJws9LeH0PywcDvDZoCIQDH6SNRYzDYoHxWhZcYmrLKVZdLo_O6cjaLPW1pVuCbgg%3D%3D&sig=AJfQdSswRQIhAIzp9aD3dS2B4Ce46EgVG4l78FpTp31N1j6SlCfgT89nAiBbfMKII0lJ6jyDJzjTlNEniIQDzRrOmTDZpyGj2GlE-w%3D%3D&pot=Ml-J20Fb7ru8aInnHutyPWyEYrW46w-vcidDnH0Q4psQKWIO5DC_piSuip7XLxrkJucgaKRPh4ZDExt5TtkmaFkUeghQ0UpC4Jt1wKbWoynovf99uZF-1qNg9QwWIIXG6Q%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Jun 06 16:17:06 volumio volumio[17804]: verbose: CURRENT POSITION 1 Jun 06 16:17:06 volumio volumio[17804]: info: CoreStateMachine::syncState stateService pause Jun 06 16:17:06 volumio volumio[17804]: info: CoreStateMachine::syncState currentStatus stop Jun 06 16:17:06 volumio volumio[17804]: info: CoreStateMachine::pushState Jun 06 16:17:06 volumio volumio[17804]: info: CoreCommandRouter::volumioPushState Jun 06 16:17:06 volumio volumio[17804]: info: ControllerMpd::pushState Jun 06 16:17:06 volumio volumio[17804]: info: CoreCommandRouter::servicePushState Jun 06 16:17:06 volumio volumio[17804]: info: CorePlayQueue::getTrack 1 Jun 06 16:17:06 volumio volumio[17804]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":172,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Faith Move Mountain","artist":"King Kong et Little Lion Sound","album":"Faith Move Mountain","uri":"https://rr3---sn-25glenlk.googlevideo.com/videoplayback?expire=1749241025&ei=YfhCaPTYLcblxN8Pnqz88A4&ip=212.129.2.121&id=o-ANaHq7QhuDlpUcLBAkGDjfvWGq5ySQ8Oi8owQM20R_m0&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1749219425%2C&mh=QA&mm=31%2C26&mn=sn-25glenlk%2Csn-5hne6nz6&ms=au%2Conr&mv=m&mvi=3&pl=24&rms=au%2Cau&ctier=A&pfa=5&pcm2=no&gcr=fr&initcwndbps=1316250&hightc=yes&siu=1&bui=AY1jyLNt0OgXF-LvyBMOyxfrx6xuoWF_8XsJITRYI5B5wK0DmeAH8kgx9_ky9ELXcHRwKeeoyg&spc=l3OVKdPM6-BeRJ0b1vy5WU141e_ZNyrN1YbzqCA5kaDcjYUk4kZFGUlOOxHl8v0wDjqgzU-GX4bAI30&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=B2KXzcCcis9tsEyc5W_pLRoQ&rqh=1&gir=yes&clen=5844721&dur=172.321&lmt=1746536788030650&mt=1749218954&fvip=1&keepalive=yes&fexp=51466698&c=WEB_REMIX&sefc=1&txp=2318224&n=tTq92600hL5k8w&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cpcm2%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgbm-teTCK_u7pAQw0aI3CncOGQDJws9LeH0PywcDvDZoCIQDH6SNRYzDYoHxWhZcYmrLKVZdLo_O6cjaLPW1pVuCbgg%3D%3D&sig=AJfQdSswRQIhAIzp9aD3dS2B4Ce46EgVG4l78FpTp31N1j6SlCfgT89nAiBbfMKII0lJ6jyDJzjTlNEniIQDzRrOmTDZpyGj2GlE-w%3D%3D&pot=Ml-J20Fb7ru8aInnHutyPWyEYrW46w-vcidDnH0Q4psQKWIO5DC_piSuip7XLxrkJucgaKRPh4ZDExt5TtkmaFkUeghQ0UpC4Jt1wKbWoynovf99uZF-1qNg9QwWIIXG6Q%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Jun 06 16:17:06 volumio volumio[17804]: verbose: CURRENT POSITION 1 Jun 06 16:17:06 volumio volumio[17804]: info: CoreStateMachine::syncState stateService pause Jun 06 16:17:06 volumio volumio[17804]: info: CoreStateMachine::syncState currentStatus stop Jun 06 16:17:06 volumio volumio[17804]: info: CoreStateMachine::pushState Jun 06 16:17:06 volumio volumio[17804]: info: CoreCommandRouter::volumioPushState Jun 06 16:17:06 volumio volumio[17804]: info: ControllerMpd::pushState Jun 06 16:17:06 volumio volumio[17804]: info: CoreCommandRouter::servicePushState Jun 06 16:17:06 volumio volumio[17804]: info: CorePlayQueue::getTrack 1 Jun 06 16:17:06 volumio volumio[17804]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":172,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Faith Move Mountain","artist":"King Kong et Little Lion Sound","album":"Faith Move Mountain","uri":"https://rr3---sn-25glenlk.googlevideo.com/videoplayback?expire=1749241025&ei=YfhCaPTYLcblxN8Pnqz88A4&ip=212.129.2.121&id=o-ANaHq7QhuDlpUcLBAkGDjfvWGq5ySQ8Oi8owQM20R_m0&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1749219425%2C&mh=QA&mm=31%2C26&mn=sn-25glenlk%2Csn-5hne6nz6&ms=au%2Conr&mv=m&mvi=3&pl=24&rms=au%2Cau&ctier=A&pfa=5&pcm2=no&gcr=fr&initcwndbps=1316250&hightc=yes&siu=1&bui=AY1jyLNt0OgXF-LvyBMOyxfrx6xuoWF_8XsJITRYI5B5wK0DmeAH8kgx9_ky9ELXcHRwKeeoyg&spc=l3OVKdPM6-BeRJ0b1vy5WU141e_ZNyrN1YbzqCA5kaDcjYUk4kZFGUlOOxHl8v0wDjqgzU-GX4bAI30&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=B2KXzcCcis9tsEyc5W_pLRoQ&rqh=1&gir=yes&clen=5844721&dur=172.321&lmt=1746536788030650&mt=1749218954&fvip=1&keepalive=yes&fexp=51466698&c=WEB_REMIX&sefc=1&txp=2318224&n=tTq92600hL5k8w&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cpcm2%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgbm-teTCK_u7pAQw0aI3CncOGQDJws9LeH0PywcDvDZoCIQDH6SNRYzDYoHxWhZcYmrLKVZdLo_O6cjaLPW1pVuCbgg%3D%3D&sig=AJfQdSswRQIhAIzp9aD3dS2B4Ce46EgVG4l78FpTp31N1j6SlCfgT89nAiBbfMKII0lJ6jyDJzjTlNEniIQDzRrOmTDZpyGj2GlE-w%3D%3D&pot=Ml-J20Fb7ru8aInnHutyPWyEYrW46w-vcidDnH0Q4psQKWIO5DC_piSuip7XLxrkJucgaKRPh4ZDExt5TtkmaFkUeghQ0UpC4Jt1wKbWoynovf99uZF-1qNg9QwWIIXG6Q%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Jun 06 16:17:06 volumio volumio[17804]: verbose: CURRENT POSITION 1 Jun 06 16:17:06 volumio volumio[17804]: info: CoreStateMachine::syncState stateService pause Jun 06 16:17:06 volumio volumio[17804]: info: CoreStateMachine::syncState currentStatus stop Jun 06 16:17:06 volumio volumio[17804]: info: CoreStateMachine::pushState Jun 06 16:17:06 volumio volumio[17804]: info: CoreCommandRouter::volumioPushState Jun 06 16:17:06 volumio volumio[17804]: info: ControllerMpd::pushState Jun 06 16:17:06 volumio volumio[17804]: info: CoreCommandRouter::servicePushState Jun 06 16:17:06 volumio volumio[17804]: info: CorePlayQueue::getTrack 1 Jun 06 16:17:06 volumio volumio[17804]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":172,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Faith Move Mountain","artist":"King Kong et Little Lion Sound","album":"Faith Move Mountain","uri":"https://rr3---sn-25glenlk.googlevideo.com/videoplayback?expire=1749241025&ei=YfhCaPTYLcblxN8Pnqz88A4&ip=212.129.2.121&id=o-ANaHq7QhuDlpUcLBAkGDjfvWGq5ySQ8Oi8owQM20R_m0&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1749219425%2C&mh=QA&mm=31%2C26&mn=sn-25glenlk%2Csn-5hne6nz6&ms=au%2Conr&mv=m&mvi=3&pl=24&rms=au%2Cau&ctier=A&pfa=5&pcm2=no&gcr=fr&initcwndbps=1316250&hightc=yes&siu=1&bui=AY1jyLNt0OgXF-LvyBMOyxfrx6xuoWF_8XsJITRYI5B5wK0DmeAH8kgx9_ky9ELXcHRwKeeoyg&spc=l3OVKdPM6-BeRJ0b1vy5WU141e_ZNyrN1YbzqCA5kaDcjYUk4kZFGUlOOxHl8v0wDjqgzU-GX4bAI30&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=B2KXzcCcis9tsEyc5W_pLRoQ&rqh=1&gir=yes&clen=5844721&dur=172.321&lmt=1746536788030650&mt=1749218954&fvip=1&keepalive=yes&fexp=51466698&c=WEB_REMIX&sefc=1&txp=2318224&n=tTq92600hL5k8w&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cpcm2%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgbm-teTCK_u7pAQw0aI3CncOGQDJws9LeH0PywcDvDZoCIQDH6SNRYzDYoHxWhZcYmrLKVZdLo_O6cjaLPW1pVuCbgg%3D%3D&sig=AJfQdSswRQIhAIzp9aD3dS2B4Ce46EgVG4l78FpTp31N1j6SlCfgT89nAiBbfMKII0lJ6jyDJzjTlNEniIQDzRrOmTDZpyGj2GlE-w%3D%3D&pot=Ml-J20Fb7ru8aInnHutyPWyEYrW46w-vcidDnH0Q4psQKWIO5DC_piSuip7XLxrkJucgaKRPh4ZDExt5TtkmaFkUeghQ0UpC4Jt1wKbWoynovf99uZF-1qNg9QwWIIXG6Q%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Jun 06 16:17:06 volumio volumio[17804]: verbose: CURRENT POSITION 1 Jun 06 16:17:06 volumio volumio[17804]: info: CoreStateMachine::syncState stateService pause Jun 06 16:17:06 volumio volumio[17804]: info: CoreStateMachine::syncState currentStatus stop Jun 06 16:17:06 volumio volumio[17804]: info: CoreStateMachine::pushState Jun 06 16:17:06 volumio volumio[17804]: info: CoreCommandRouter::volumioPushState Jun 06 16:17:06 volumio volumio[17804]: info: ------------------------------ 107ms Jun 06 16:17:06 volumio volumio[17804]: info: ------------------------------ 106ms Jun 06 16:17:06 volumio volumio[17804]: info: ------------------------------ 93ms Jun 06 16:17:06 volumio volumio[17804]: info: ------------------------------ 92ms Jun 06 16:17:06 volumio volumio[17804]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Jun 06 16:17:06 volumio volumio[17804]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Jun 06 16:17:06 volumio volumio[17804]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Jun 06 16:17:06 volumio volumio[17804]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Jun 06 16:17:06 volumio volumio[17804]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jun 06 16:17:06 volumio volumio[17804]: info: CoreCommandRouter::volumioGetState Jun 06 16:17:06 volumio volumio[17804]: info: Listing playlists Jun 06 16:17:06 volumio volumio[17804]: info: Listing playlists Jun 06 16:17:06 volumio volumio[17804]: info: Updating MyVolumio device info Jun 06 16:17:06 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 06 16:17:06 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 06 16:17:06 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 06 16:17:06 volumio volumio[17804]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jun 06 16:17:07 volumio volumio[17804]: info: [KeepClock] Starting silence playback Jun 06 16:17:07 volumio volumio[17804]: error: [KeepClock] aplay error: Error: Command failed: aplay -D volumioOutput /dev/zero -f S24_3LE -r 96000 Jun 06 16:17:07 volumio volumio[17804]: aplay: main:831: audio open error: Device or resource busy Jun 06 16:17:11 volumio volumio[17804]: info: [KeepClock] Silence already playing Jun 06 16:17:13 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 06 16:17:13 volumio volumio[17804]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Jun 06 16:17:13 volumio volumio[17804]: info: CoreCommandRouter::volumioGetState Jun 06 16:17:14 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 06 16:17:14 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 06 16:17:14 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 06 16:17:14 volumio volumio-remote-updater[649]: No test mode Jun 06 16:17:14 volumio volumio-remote-updater[649]: No alpha test mode Jun 06 16:17:14 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jun 06 16:17:14 volumio volumio[17804]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jun 06 16:17:14 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jun 06 16:17:16 volumio volumio[17804]: info: CoreCommandRouter::volumioGetState Jun 06 16:17:18 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 16:17:18 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 16:17:18 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jun 06 16:17:18 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jun 06 16:17:18 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jun 06 16:17:18 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jun 06 16:17:18 volumio volumio[17804]: info: CoreCommandRouter::volumioGetBrowseSources Jun 06 16:17:18 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 06 16:17:19 volumio volumio[17804]: error: MyVolumio Plugin failed to authenticate in a timely fashion Jun 06 16:17:19 volumio volumio[17804]: info: Completed starting MyVolumio Plugin Jun 06 16:17:19 volumio volumio[17804]: [Metrics] CommandRouter: 44s 863.44ms Jun 06 16:17:19 volumio volumio[17804]: info: CoreCommandRouter::volumiosetStartupVolume Jun 06 16:17:19 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 16:17:19 volumio volumio[17804]: info: VolumeController:: Setting startup Volume 5 Jun 06 16:17:19 volumio volumio[17804]: info: VolumeController::SetAlsaVolume5 Jun 06 16:17:19 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 06 16:17:19 volumio volumio[17804]: info: CoreCommandRouter::Close All Modals sent Jun 06 16:17:19 volumio volumio[17804]: info: CoreCommandRouter::Close All Modals sent Jun 06 16:17:19 volumio volumio[17804]: info: CoreCommandRouter::volumioGetState Jun 06 16:17:19 volumio volumio[17804]: info: CoreStateMachine::pushState Jun 06 16:17:19 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 06 16:17:19 volumio volumio[17804]: info: CoreCommandRouter::volumioPushState Jun 06 16:17:19 volumio volumio[17804]: info: [KeepClock] Status: Jun 06 16:17:19 volumio volumio[17804]: info: [KeepClock] oldStatus: undefined Jun 06 16:17:19 volumio volumio[17804]: info: [KeepClock] Killing all aplay processes [object Object] Jun 06 16:17:19 volumio volumio[17804]: info: [KeepClock] Stopping silence playback Jun 06 16:17:19 volumio volumio[17804]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Jun 06 16:17:19 volumio volumio[17804]: SPOTIFY: SPOTIFY VOLUME 5 Jun 06 16:17:19 volumio volumio[17804]: SPOTIFY: VOLUMIO VOLUME 5 Jun 06 16:17:19 volumio volumio[17804]: SPOTIFY: DELTA VOLUME ENOUGH: false Jun 06 16:17:19 volumio volumio[17804]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Jun 06 16:17:19 volumio volumio[17804]: aplay: main:831: audio open error: Device or resource busy Jun 06 16:17:19 volumio volumio[17804]: error: [KeepClock] aplay error: Error: Command failed: aplay -D volumioOutput /dev/zero -f S24_3LE -r 96000 Jun 06 16:17:19 volumio volumio[17804]: Playing raw data '/dev/zero' : Signed 24 bit Little Endian in 3bytes, Rate 96000 Hz, Mono Jun 06 16:17:19 volumio volumio[17804]: info: [KeepClock] aplay processes terminated Jun 06 16:17:19 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jun 06 16:17:19 volumio dbus-daemon[621]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.10367' (uid=0 pid=18180 comm="timedatectl show --property=NTPSynchronized --valu") Jun 06 16:17:19 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jun 06 16:17:19 volumio dbus-daemon[621]: [system] Successfully activated service 'org.freedesktop.timedate1' Jun 06 16:17:19 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Jun 06 16:17:19 volumio setdatetime-helper.sh[18179]: Time is already synchronized. Jun 06 16:17:19 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jun 06 16:17:19 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jun 06 16:17:20 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jun 06 16:17:20 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 06 16:17:20 volumio volumio[17804]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jun 06 16:17:25 volumio sudo[18215]: volumio : TTY=pts/0 ; PWD=/home/volumio/volumio-plugins-sources-bookworm/keepclock ; USER=root ; COMMAND=/bin/systemctl restart volumio Jun 06 16:17:25 volumio sudo[18215]: pam_unix(sudo:session): session opened for user root(uid=0) by volumio(uid=1000) Jun 06 16:17:25 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Jun 06 16:17:25 volumio systemd[1]: Stopping volumio.service - Volumio Backend Module... Jun 06 16:17:25 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Jun 06 16:17:25 volumio volumio-remote-updater[649]: [2025-06-06 16:17:25] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jun 06 16:17:25 volumio volumio-remote-updater[649]: [2025-06-06 16:17:25] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jun 06 16:17:25 volumio go-librespot[17996]: time="2025-06-06T16:17:25+02:00" level=error msg="websocket connection errored" error="failed to get reader: failed to read frame header: EOF" Jun 06 16:17:25 volumio systemd[1]: volumio.service: Deactivated successfully. Jun 06 16:17:25 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Jun 06 16:17:25 volumio systemd[1]: volumio.service: Consumed 37.124s CPU time. Jun 06 16:17:25 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Jun 06 16:17:25 volumio sudo[18215]: pam_unix(sudo:session): session closed for user root Jun 06 16:17:26 volumio volumio[18225]: info: ------------------------------------------- Jun 06 16:17:26 volumio volumio[18225]: info: ----- Volumio3 ---- Jun 06 16:17:26 volumio volumio[18225]: info: ------------------------------------------- Jun 06 16:17:26 volumio volumio[18225]: info: ----- System startup ---- Jun 06 16:17:26 volumio volumio[18225]: info: ------------------------------------------- Jun 06 16:17:26 volumio volumio[18225]: info: MYVOLUMIO Environment detected Jun 06 16:17:26 volumio volumio[18225]: info: Plugin folders cleanup Jun 06 16:17:26 volumio volumio[18225]: info: Scanning into folder /volumio/app/plugins/ Jun 06 16:17:26 volumio volumio[18225]: info: Scanning category audio_interface Jun 06 16:17:26 volumio volumio[18225]: info: Scanning category miscellanea Jun 06 16:17:26 volumio volumio[18225]: info: Scanning category music_service Jun 06 16:17:26 volumio volumio[18225]: info: Scanning category plugins.json Jun 06 16:17:26 volumio volumio[18225]: info: Scanning category system_controller Jun 06 16:17:26 volumio volumio[18225]: info: Scanning category user_interface Jun 06 16:17:26 volumio volumio[18225]: info: Scanning into folder /data/plugins/ Jun 06 16:17:26 volumio volumio[18225]: info: Scanning category music_service Jun 06 16:17:26 volumio volumio[18225]: info: Plugin folders cleanup completed Jun 06 16:17:26 volumio volumio[18225]: info: ------------------------------------------- Jun 06 16:17:26 volumio volumio[18225]: info: ----- Core plugins startup ---- Jun 06 16:17:26 volumio volumio[18225]: info: ------------------------------------------- Jun 06 16:17:26 volumio volumio[18225]: info: Loading plugins from folder /volumio/app/plugins/ Jun 06 16:17:26 volumio volumio[18225]: info: Adding plugin upnp to MyMusic Plugins Jun 06 16:17:26 volumio volumio[18225]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 06 16:17:26 volumio volumio[18225]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 06 16:17:26 volumio volumio[18225]: info: Loading plugins from folder /data/plugins/ Jun 06 16:17:26 volumio volumio[18225]: info: Loading plugin "system"... Jun 06 16:17:26 volumio volumio[18225]: info: Loading plugin "appearance"... Jun 06 16:17:27 volumio volumio[18225]: info: Loading plugin "network"... Jun 06 16:17:27 volumio volumio[18225]: info: Refreshing Cached IP Addresses Jun 06 16:17:27 volumio sudo[18249]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 06 16:17:27 volumio sudo[18249]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 16:17:27 volumio sudo[18251]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 06 16:17:27 volumio volumio[18225]: info: Loading plugin "services"... Jun 06 16:17:27 volumio sudo[18251]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 16:17:27 volumio sudo[18249]: pam_unix(sudo:session): session closed for user root Jun 06 16:17:27 volumio volumio[18225]: info: Loading plugin "alsa_controller"... Jun 06 16:17:27 volumio sudo[18251]: pam_unix(sudo:session): session closed for user root Jun 06 16:17:27 volumio sudo[18259]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 06 16:17:27 volumio sudo[18259]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 16:17:27 volumio volumio[18225]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 06 16:17:27 volumio volumio[18225]: info: Loading plugin "wizard"... Jun 06 16:17:27 volumio volumio[18225]: info: Loading plugin "networkfs"... Jun 06 16:17:27 volumio volumio[18225]: info: Starting Udev Watcher for removable devices Jun 06 16:17:27 volumio volumio[18225]: info: Ignoring mount for partition: boot Jun 06 16:17:27 volumio volumio[18225]: info: Ignoring mount for partition: volumio Jun 06 16:17:27 volumio volumio[18225]: info: Ignoring mount for partition: volumio_data Jun 06 16:17:27 volumio volumio[18225]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 06 16:17:27 volumio volumio[18225]: info: Loading plugin "volumio_command_line_client"... Jun 06 16:17:27 volumio volumio[18225]: info: Loading plugin "upnp"... Jun 06 16:17:27 volumio volumio[18225]: info: [1749219447613] Starting Upmpd Daemon Jun 06 16:17:27 volumio volumio[18225]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 06 16:17:27 volumio volumio[18225]: info: Loading plugin "my_music"... Jun 06 16:17:27 volumio volumio[18225]: info: Loading plugin "mpd"... Jun 06 16:17:27 volumio volumio[18225]: info: Loading plugin "upnp_browser"... Jun 06 16:17:28 volumio volumio[18225]: info: Loading plugin "alarm-clock"... Jun 06 16:17:28 volumio volumio[18225]: info: Loading plugin "airplay_emulation"... Jun 06 16:17:28 volumio volumio[18225]: info: Starting Shairport Sync Jun 06 16:17:28 volumio volumio[18225]: info: Loading plugin "last_100"... Jun 06 16:17:28 volumio volumio[18225]: info: Loading plugin "webradio"... Jun 06 16:17:28 volumio volumio[18225]: info: Loading plugin "i2s_dacs"... Jun 06 16:17:28 volumio volumio[18225]: info: Loading plugin "volumiodiscovery"... Jun 06 16:17:28 volumio volumio[18225]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 06 16:17:28 volumio volumio[18225]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 06 16:17:28 volumio volumio[18225]: *** WARNING *** For more information see Jun 06 16:17:28 volumio volumio[18225]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 06 16:17:28 volumio volumio[18225]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 06 16:17:28 volumio node[18225]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 06 16:17:28 volumio volumio[18225]: *** WARNING *** For more information see Jun 06 16:17:28 volumio node[18225]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 06 16:17:28 volumio node[18225]: *** WARNING *** For more information see Jun 06 16:17:28 volumio node[18225]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 06 16:17:28 volumio node[18225]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 06 16:17:28 volumio node[18225]: *** WARNING *** For more information see Jun 06 16:17:28 volumio volumio[18225]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 06 16:17:28 volumio volumio[18225]: info: Discovery: Started advertising with name: Volumio Jun 06 16:17:28 volumio volumio[18225]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 06 16:17:28 volumio volumio[18225]: info: Loading plugin "bandcamp"... Jun 06 16:17:29 volumio volumio[18225]: info: Loading plugin "soundcloud"... Jun 06 16:17:29 volumio volumio[18225]: info: Loading plugin "spop"... Jun 06 16:17:30 volumio volumio[18225]: info: Loading plugin "youtube2"... Jun 06 16:17:30 volumio volumio-remote-updater[649]: [2025-06-06 16:17:30] [connect] Successful connection Jun 06 16:17:30 volumio sudo[18259]: pam_unix(sudo:session): session closed for user root Jun 06 16:17:31 volumio volumio[18225]: info: Loading plugin "ytcr"... Jun 06 16:17:33 volumio volumio[18225]: info: Loading plugin "ytmusic"... Jun 06 16:17:34 volumio volumio[18225]: info: Loading plugin "outputs"... Jun 06 16:17:34 volumio volumio[18225]: info: Loading plugin "albumart"... Jun 06 16:17:34 volumio volumio[18225]: info: Plugin example_plugin is not enabled Jun 06 16:17:34 volumio volumio[18225]: info: Loading plugin "inputs"... Jun 06 16:17:34 volumio volumio[18225]: info: Loading plugin "updater_comm"... Jun 06 16:17:34 volumio volumio[18225]: info: Plugin mpdemulation is not enabled Jun 06 16:17:34 volumio volumio[18225]: info: Loading plugin "rest_api"... Jun 06 16:17:34 volumio volumio[18225]: info: Loading plugin "websocket"... Jun 06 16:17:34 volumio volumio[18225]: info: Starting Socket.io Server version 1.7.4 Jun 06 16:17:34 volumio volumio[18225]: info: Loading plugin "keepclock"... Jun 06 16:17:34 volumio volumio[18225]: info: Applying required configuration parameters for plugin keepclock Jun 06 16:17:34 volumio volumio[18225]: info: Loading i18n strings for locale fr Jun 06 16:17:34 volumio volumio[18225]: Updating browse sources language Jun 06 16:17:34 volumio volumio[18225]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 06 16:17:34 volumio volumio[18281]: Forking 3 albumart workers Jun 06 16:17:35 volumio volumio[18225]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 06 16:17:35 volumio volumio[18225]: info: CoreCommandRouter::initPlayerControls Jun 06 16:17:35 volumio volumio[18225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 16:17:35 volumio volumio[18225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 16:17:35 volumio volumio[18225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 16:17:35 volumio volumio[18225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 16:17:35 volumio volumio[18225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 16:17:35 volumio volumio[18225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 16:17:35 volumio volumio[18225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 16:17:35 volumio volumio[18225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 16:17:35 volumio volumio[18225]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 06 16:17:35 volumio volumio[18225]: Express server listening on port 3000 Jun 06 16:17:35 volumio volumio[18225]: [Metrics] WebUI: 8s 907.55ms Jun 06 16:17:35 volumio volumio[18225]: info: CoreStateMachine::resetVolumioState Jun 06 16:17:35 volumio volumio[18225]: info: CoreStateMachine::getcurrentVolume Jun 06 16:17:35 volumio volumio[18225]: info: CoreCommandRouter::volumioRetrievevolume Jun 06 16:17:35 volumio volumio[18225]: info: Volumio Network Manager: Network status updated: 2 Jun 06 16:17:35 volumio volumio[18225]: info: CoreStateMachine::pushState Jun 06 16:17:35 volumio volumio[18225]: info: CorePlayQueue::getTrack 0 Jun 06 16:17:35 volumio volumio[18225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 06 16:17:35 volumio volumio[18225]: info: CoreCommandRouter::volumioPushState Jun 06 16:17:35 volumio volumio[18225]: info: CoreStateMachine::updateTrackBlock Jun 06 16:17:35 volumio volumio[18225]: info: CorePlayQueue::getTrackBlock Jun 06 16:17:35 volumio volumio[18225]: info: CoreCommandRouter::volumioRetrievevolume Jun 06 16:17:35 volumio volumio[18225]: info: CoreCommandRouter::volumioGetState Jun 06 16:17:35 volumio volumio[18225]: info: CorePlayQueue::getTrack 0 Jun 06 16:17:35 volumio volumio[18225]: verbose: New Socket.io Connection to 10.20.2.67 from 10.20.2.72 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Jun 06 16:17:35 volumio volumio[18225]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 06 16:17:35 volumio volumio[18225]: info: Reloading queue from file Jun 06 16:17:35 volumio volumio[18225]: verbose: New Socket.io Connection to 10.20.2.67 from 10.20.2.72 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Jun 06 16:17:35 volumio volumio[18225]: info: CoreStateMachine::pushState Jun 06 16:17:35 volumio volumio[18225]: info: CorePlayQueue::getTrack 0 Jun 06 16:17:35 volumio volumio[18225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 06 16:17:35 volumio volumio[18225]: info: CoreCommandRouter::volumioPushState Jun 06 16:17:35 volumio volumio[18225]: info: CoreStateMachine::setRepeat null single undefined Jun 06 16:17:35 volumio volumio[18225]: info: CoreStateMachine::pushState Jun 06 16:17:35 volumio volumio[18225]: info: CorePlayQueue::getTrack 0 Jun 06 16:17:35 volumio volumio[18225]: info: CoreCommandRouter::volumioPushState Jun 06 16:17:35 volumio volumio[18225]: info: CoreStateMachine::setRandom null Jun 06 16:17:35 volumio volumio[18225]: info: CoreStateMachine::pushState Jun 06 16:17:35 volumio volumio[18225]: info: CorePlayQueue::getTrack 0 Jun 06 16:17:35 volumio volumio[18225]: info: CoreCommandRouter::volumioPushState Jun 06 16:17:35 volumio volumio[18225]: info: Listing playlists Jun 06 16:17:35 volumio volumio[18225]: info: Listing playlists Jun 06 16:17:35 volumio volumio-remote-updater[649]: [2025-06-06 16:17:35] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1749219450 101 Jun 06 16:17:35 volumio volumio[18225]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 3 Jun 06 16:17:35 volumio volumio[18225]: info: Setting Device type: Raspberry PI Jun 06 16:17:35 volumio volumio[18225]: info: Completed loading Core Plugins Jun 06 16:17:35 volumio volumio[18225]: info: Preparing to generate the ALSA configuration file Jun 06 16:17:35 volumio volumio[18225]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jun 06 16:17:35 volumio volumio[18225]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03115 Jun 06 16:17:35 volumio volumio[18225]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jun 06 16:17:35 volumio volumio[18225]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jun 06 16:17:35 volumio volumio[18225]: info: Reading ALSA contributions from plugins. Jun 06 16:17:35 volumio volumio[18225]: verbose: New Socket.io Connection to 10.20.2.67 from 10.20.2.72 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Jun 06 16:17:35 volumio volumio[18225]: info: Discovery: adding a677849c-d0c1-472d-a87d-484d0d9963d0 Jun 06 16:17:35 volumio volumio[18225]: info: Discovery: Found device Volumio Jun 06 16:17:35 volumio volumio[18225]: info: CoreCommandRouter::volumioGetState Jun 06 16:17:35 volumio volumio[18225]: info: CorePlayQueue::getTrack 0 Jun 06 16:17:35 volumio volumio[18225]: info: Discovery: this is already registered, a677849c-d0c1-472d-a87d-484d0d9963d0 Jun 06 16:17:35 volumio volumio[18225]: info: Discovery: Found device Volumio Jun 06 16:17:35 volumio volumio[18225]: info: CoreCommandRouter::volumioGetState Jun 06 16:17:35 volumio volumio[18225]: info: CorePlayQueue::getTrack 0 Jun 06 16:17:35 volumio volumio[18225]: info: Asound.conf file unchanged, so no further update is needed Jun 06 16:17:35 volumio volumio[18225]: info: Output device has changed, restarting MPD Jun 06 16:17:35 volumio volumio[18225]: info: Output device has changed, restarting Shairport Sync Jun 06 16:17:35 volumio volumio[18225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 16:17:35 volumio volumio[18225]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 06 16:17:35 volumio sudo[18344]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 06 16:17:35 volumio sudo[18344]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 16:17:35 volumio sudo[18344]: pam_unix(sudo:session): session closed for user root Jun 06 16:17:35 volumio sudo[18346]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 06 16:17:35 volumio sudo[18346]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 16:17:35 volumio volumio[18225]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 06 16:17:35 volumio volumio[18225]: info: ___________ START PLUGINS ___________ Jun 06 16:17:35 volumio volumio[18225]: info: ControllerMpd::onStart: Initializing MPD Jun 06 16:17:35 volumio volumio[18225]: info: Creating MPD Configuration file Jun 06 16:17:35 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Jun 06 16:17:35 volumio volumio[18225]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 06 16:17:35 volumio volumio[18225]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 06 16:17:35 volumio volumio[18225]: info: [1749219455654] CoreMusicLibrary::Adding element Serveurs Média Jun 06 16:17:35 volumio volumio[18225]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 06 16:17:35 volumio sudo[18355]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 06 16:17:35 volumio sudo[18355]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 16:17:35 volumio sudo[18356]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 06 16:17:35 volumio sudo[18356]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 16:17:35 volumio sudo[18356]: pam_unix(sudo:session): session closed for user root Jun 06 16:17:35 volumio volumio[18225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 16:17:35 volumio volumio[18225]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 06 16:17:35 volumio volumio[18225]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 06 16:17:35 volumio volumio[18225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 16:17:35 volumio volumio[18225]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 06 16:17:35 volumio systemd[1]: mpd.service: Deactivated successfully. Jun 06 16:17:35 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Jun 06 16:17:35 volumio systemd[1]: mpd.service: Consumed 3.251s CPU time. Jun 06 16:17:35 volumio systemd[1]: mpd.socket: Deactivated successfully. Jun 06 16:17:35 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jun 06 16:17:35 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jun 06 16:17:35 volumio volumio[18225]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 06 16:17:35 volumio volumio[18225]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 06 16:17:35 volumio volumio[18225]: info: [1749219455781] CoreMusicLibrary::Adding element Last_100 Jun 06 16:17:35 volumio volumio[18225]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 06 16:17:35 volumio volumio[18225]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 06 16:17:35 volumio volumio[18225]: info: [1749219455784] CoreMusicLibrary::Adding element Webradio Jun 06 16:17:35 volumio volumio[18225]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 06 16:17:35 volumio volumio[18225]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 06 16:17:35 volumio volumio[18225]: info: Initializing BBC Radios Jun 06 16:17:35 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 06 16:17:35 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Jun 06 16:17:35 volumio volumio[18292]: Starting albumart workers Jun 06 16:17:35 volumio volumio[18225]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 06 16:17:35 volumio volumio[18225]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 06 16:17:35 volumio volumio[18225]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 06 16:17:35 volumio volumio[18225]: info: [1749219455893] CoreMusicLibrary::Adding element Bandcamp Discover Jun 06 16:17:35 volumio volumio[18225]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 06 16:17:35 volumio volumio[18225]: Cannot find translation for source Bandcamp Discover Jun 06 16:17:35 volumio volumio[18225]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 06 16:17:35 volumio volumio[18225]: info: [1749219455904] CoreMusicLibrary::Adding element SoundCloud Jun 06 16:17:35 volumio volumio[18225]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 06 16:17:35 volumio volumio[18225]: Cannot find translation for source Bandcamp Discover Jun 06 16:17:35 volumio volumio[18225]: Cannot find translation for source SoundCloud Jun 06 16:17:35 volumio volumio[18225]: info: Creating Spotify config file Jun 06 16:17:35 volumio volumio[18225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 16:17:35 volumio volumio[18225]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 06 16:17:35 volumio volumio[18225]: info: [1749219455942] CoreMusicLibrary::Adding element YouTube2 Jun 06 16:17:35 volumio volumio[18225]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 06 16:17:35 volumio volumio[18225]: Cannot find translation for source Bandcamp Discover Jun 06 16:17:35 volumio volumio[18225]: Cannot find translation for source SoundCloud Jun 06 16:17:35 volumio volumio[18225]: Cannot find translation for source YouTube2 Jun 06 16:17:35 volumio volumio[18291]: Starting albumart workers Jun 06 16:17:35 volumio volumio[18225]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 06 16:17:35 volumio volumio[18225]: info: [1749219455988] CoreMusicLibrary::Adding element YouTube Music Jun 06 16:17:35 volumio volumio[18225]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 06 16:17:35 volumio volumio[18225]: Cannot find translation for source Bandcamp Discover Jun 06 16:17:35 volumio volumio[18225]: Cannot find translation for source SoundCloud Jun 06 16:17:35 volumio volumio[18225]: Cannot find translation for source YouTube2 Jun 06 16:17:35 volumio volumio[18225]: Cannot find translation for source YouTube Music Jun 06 16:17:36 volumio volumio[18225]: info: [KeepClock] Starting ... Jun 06 16:17:35 volumio sudo[18371]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 06 16:17:35 volumio sudo[18371]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 06 16:17:36 volumio sudo[18371]: pam_unix(sudo:session): session closed for user root Jun 06 16:17:36 volumio volumio[18225]: info: Volumio Calling Home Jun 06 16:17:36 volumio volumio[18293]: Starting albumart workers Jun 06 16:17:36 volumio volumio[18225]: info: MPD Permissions set Jun 06 16:17:36 volumio volumio[18225]: info: MPD Permissions set Jun 06 16:17:36 volumio volumio[18225]: info: CoreCommandRouter::volumioGetState Jun 06 16:17:36 volumio volumio[18225]: info: CorePlayQueue::getTrack 0 Jun 06 16:17:36 volumio volumio[18225]: info: Volumio called home Jun 06 16:17:36 volumio volumio[18225]: info: Spotify config file written Jun 06 16:17:36 volumio volumio[18225]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jun 06 16:17:36 volumio volumio[18225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 16:17:36 volumio volumio[18225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 16:17:36 volumio volumio[18225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 16:17:36 volumio volumio[18225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 16:17:36 volumio volumio[18225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 16:17:36 volumio volumio[18225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 16:17:36 volumio volumio[18225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 16:17:36 volumio volumio[18225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 16:17:36 volumio sudo[18406]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jun 06 16:17:36 volumio sudo[18406]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 16:17:36 volumio volumio[18225]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 06 16:17:36 volumio volumio[18225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 16:17:36 volumio volumio[18225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 16:17:36 volumio volumio[18225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 16:17:36 volumio volumio[18225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 16:17:36 volumio volumio[18225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 16:17:36 volumio volumio[18225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 16:17:36 volumio volumio[18225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 16:17:36 volumio volumio[18225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 16:17:36 volumio volumio[18225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 16:17:36 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Jun 06 16:17:36 volumio systemd[1]: go-librespot-daemon.service: Killing process 18001 (go-librespot) with signal SIGKILL. Jun 06 16:17:36 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Jun 06 16:17:36 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jun 06 16:17:36 volumio volumio[18225]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 06 16:17:36 volumio volumio[18225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 16:17:36 volumio volumio[18225]: info: No need to fix Spotify hosts Jun 06 16:17:36 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jun 06 16:17:36 volumio sudo[18406]: pam_unix(sudo:session): session closed for user root Jun 06 16:17:36 volumio go-librespot[18418]: go-librespot daemon starting... Jun 06 16:17:36 volumio go-librespot[18419]: time="2025-06-06T16:17:36+02:00" level=info msg="running go-librespot 0.2.0" Jun 06 16:17:36 volumio go-librespot[18419]: time="2025-06-06T16:17:36+02:00" level=debug msg="app state loaded" Jun 06 16:17:36 volumio go-librespot[18419]: time="2025-06-06T16:17:36+02:00" level=debug msg="stored credentials not found" Jun 06 16:17:36 volumio go-librespot[18419]: time="2025-06-06T16:17:36+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 06 16:17:36 volumio volumio[18225]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Jun 06 16:17:37 volumio volumio[18225]: info: Starting Shairport Sync Jun 06 16:17:37 volumio go-librespot[18419]: time="2025-06-06T16:17:37+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Jun 06 16:17:37 volumio go-librespot[18419]: time="2025-06-06T16:17:37+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Jun 06 16:17:37 volumio go-librespot[18419]: time="2025-06-06T16:17:37+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Jun 06 16:17:37 volumio volumio[18225]: info: Starting Shairport Sync Jun 06 16:17:37 volumio go-librespot[18419]: time="2025-06-06T16:17:37+02:00" level=info msg="zeroconf server listening on port 42737" Jun 06 16:17:37 volumio volumio[18225]: info: Starting Shairport Sync Jun 06 16:17:37 volumio sudo[18429]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 06 16:17:37 volumio sudo[18431]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 06 16:17:37 volumio sudo[18431]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 16:17:37 volumio sudo[18429]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 16:17:37 volumio sudo[18433]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 06 16:17:37 volumio sudo[18433]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 16:17:37 volumio volumio[18225]: info: [KeepClock] socket.io connecté Jun 06 16:17:37 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jun 06 16:17:37 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Jun 06 16:17:37 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 06 16:17:37 volumio systemd[1]: shairport-sync.service: Consumed 1.820s CPU time. Jun 06 16:17:37 volumio volumio[18225]: info: CoreCommandRouter::volumioGetState Jun 06 16:17:37 volumio volumio[18225]: info: CorePlayQueue::getTrack 0 Jun 06 16:17:37 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 06 16:17:37 volumio sudo[18429]: pam_unix(sudo:session): session closed for user root Jun 06 16:17:37 volumio sudo[18431]: pam_unix(sudo:session): session closed for user root Jun 06 16:17:37 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jun 06 16:17:37 volumio volumio[18225]: info: [KeepClock] Status: stop Jun 06 16:17:37 volumio volumio[18225]: info: [KeepClock] oldStatus: null Jun 06 16:17:37 volumio volumio[18225]: info: [KeepClock] Killing all aplay processes null Jun 06 16:17:37 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Jun 06 16:17:37 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 06 16:17:37 volumio volumio[18225]: info: [KeepClock] Stopping silence playback Jun 06 16:17:37 volumio volumio[18225]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 06 16:17:37 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 06 16:17:37 volumio volumio[18225]: TypeError: Cannot read properties of null (reading 'kill') Jun 06 16:17:37 volumio volumio[18225]: at ControllerKeepClock.stopSilence (/data/plugins/music_service/keepclock/index.js:270:25) Jun 06 16:17:37 volumio volumio[18225]: at Socket. (/data/plugins/music_service/keepclock/index.js:112:8) Jun 06 16:17:37 volumio volumio[18225]: at Emitter.emit (/data/plugins/music_service/keepclock/node_modules/component-emitter/index.js:133:20) Jun 06 16:17:37 volumio volumio[18225]: at Socket.onevent (/data/plugins/music_service/keepclock/node_modules/socket.io-client/lib/socket.js:278:10) Jun 06 16:17:37 volumio volumio[18225]: at Socket.onpacket (/data/plugins/music_service/keepclock/node_modules/socket.io-client/lib/socket.js:236:12) Jun 06 16:17:37 volumio volumio[18225]: at Manager. (/data/plugins/music_service/keepclock/node_modules/component-bind/index.js:21:15) Jun 06 16:17:37 volumio volumio[18225]: at Emitter.emit (/data/plugins/music_service/keepclock/node_modules/component-emitter/index.js:133:20) Jun 06 16:17:37 volumio volumio[18225]: at Manager.ondecoded (/data/plugins/music_service/keepclock/node_modules/socket.io-client/lib/manager.js:345:8) Jun 06 16:17:37 volumio volumio[18225]: at Decoder. (/data/plugins/music_service/keepclock/node_modules/component-bind/index.js:21:15) Jun 06 16:17:37 volumio volumio[18225]: at Emitter.emit (/data/plugins/music_service/keepclock/node_modules/component-emitter/index.js:133:20) Jun 06 16:17:37 volumio volumio[18225]: at Decoder.add (/data/plugins/music_service/keepclock/node_modules/socket.io-parser/index.js:251:12) Jun 06 16:17:37 volumio volumio[18225]: at Manager.ondata (/data/plugins/music_service/keepclock/node_modules/socket.io-client/lib/manager.js:335:16) Jun 06 16:17:37 volumio volumio[18225]: at Socket. (/data/plugins/music_service/keepclock/node_modules/component-bind/index.js:21:15) Jun 06 16:17:37 volumio volumio[18225]: at Emitter.emit (/data/plugins/music_service/keepclock/node_modules/component-emitter/index.js:133:20) Jun 06 16:17:37 volumio volumio[18225]: at Socket.onPacket (/data/plugins/music_service/keepclock/node_modules/engine.io-client/lib/socket.js:456:14) Jun 06 16:17:37 volumio volumio[18225]: at XHR. (/data/plugins/music_service/keepclock/node_modules/engine.io-client/lib/socket.js:273:10) Jun 06 16:17:37 volumio volumio[18225]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 06 16:17:37 volumio sudo[18433]: pam_unix(sudo:session): session closed for user root Jun 06 16:17:37 volumio sudo[18483]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-06-06 16:16' Jun 06 16:17:37 volumio sudo[18483]: 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="fd567ba9bc0dc34c0403e2d5b72a0f46467d1983" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri May 23 08:02:06 UTC 2025" VOLUMIO_VERSION="0.069" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="76fa67e30b28261e1dc7c3a89e610b9e"