-- Logs begin at Thu 2019-02-14 05:11:59 -05, end at Fri 2025-05-09 07:54:26 -05. -- May 09 07:53:01 zero volumio[4662]: info: Loading plugin "ytmusic"... May 09 07:53:06 zero volumio[4662]: info: Loading plugin "outputs"... May 09 07:53:06 zero volumio[4662]: info: Loading plugin "albumart"... May 09 07:53:06 zero volumio[4662]: info: Plugin example_plugin is not enabled May 09 07:53:06 zero volumio[4662]: info: Loading plugin "inputs"... May 09 07:53:06 zero volumio[4662]: info: Loading plugin "updater_comm"... May 09 07:53:07 zero volumio[4662]: info: Plugin mpdemulation is not enabled May 09 07:53:07 zero volumio[4662]: info: Loading plugin "rest_api"... May 09 07:53:07 zero volumio[4662]: info: Loading plugin "websocket"... May 09 07:53:07 zero volumio[4662]: info: Starting Socket.io Server version 2.3.0 May 09 07:53:07 zero volumio[4662]: info: Loading plugin "fusiondsp"... May 09 07:53:07 zero volumio[4662]: Forking 3 albumart workers May 09 07:53:07 zero volumio[4662]: info: Applying required configuration parameters for plugin fusiondsp May 09 07:53:07 zero volumio[4662]: info: Loading plugin "80s80s"... May 09 07:53:08 zero volumio[4662]: info: Applying required configuration parameters for plugin 80s80s May 09 07:53:08 zero volumio[4662]: info: [1746795188374] [80s80s] API delay: 30 May 09 07:53:08 zero volumio[4662]: Starting albumart workers May 09 07:53:08 zero volumio[4662]: Starting albumart workers May 09 07:53:08 zero volumio[4662]: Starting albumart workers May 09 07:53:08 zero volumio[4662]: info: Loading i18n strings for locale es May 09 07:53:08 zero volumio[4662]: Updating browse sources language May 09 07:53:08 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 07:53:08 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 09 07:53:08 zero volumio[4662]: info: CoreCommandRouter::initPlayerControls May 09 07:53:08 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 07:53:08 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 07:53:08 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 07:53:08 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 07:53:08 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 09 07:53:08 zero volumio[4662]: Express server listening on port 3000 May 09 07:53:08 zero volumio[4662]: [Metrics] WebUI: 27s 256.07ms May 09 07:53:09 zero volumio[4662]: info: CoreStateMachine::resetVolumioState May 09 07:53:09 zero volumio[4662]: info: CoreStateMachine::getcurrentVolume May 09 07:53:09 zero volumio[4662]: info: CoreCommandRouter::volumioRetrievevolume May 09 07:53:09 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 07:53:09 zero volumio[4662]: info: Volumio Network Manager: Network status updated: 2 May 09 07:53:09 zero volumio[4662]: verbose: New Socket.io Connection to 192.168.1.13 from 192.168.1.16 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 1 May 09 07:53:09 zero sudo[4791]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 09 07:53:09 zero sudo[4793]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 09 07:53:09 zero sudo[4791]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 07:53:09 zero sudo[4793]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 07:53:09 zero sudo[4793]: pam_unix(sudo:session): session closed for user root May 09 07:53:09 zero volumio[4662]: verbose: New Socket.io Connection to 192.168.1.13 from 192.168.1.16 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 2 May 09 07:53:09 zero sudo[4791]: pam_unix(sudo:session): session closed for user root May 09 07:53:09 zero volumio[4662]: verbose: New Socket.io Connection to 192.168.1.13 from 192.168.1.16 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 3 May 09 07:53:09 zero volumio[4662]: verbose: New Socket.io Connection to 192.168.1.13 from 192.168.1.16 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 4 May 09 07:53:09 zero volumio[4662]: verbose: New Socket.io Connection to 192.168.1.13 from 192.168.1.16 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 5 May 09 07:53:09 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 09 07:53:09 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 09 07:53:09 zero volumio[4662]: info: Discovery: Getting this device information May 09 07:53:09 zero volumio[4662]: info: CoreCommandRouter::volumioGetState May 09 07:53:09 zero volumio[4662]: info: CorePlayQueue::getTrack 0 May 09 07:53:09 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 09 07:53:09 zero volumio[4662]: info: Reloading queue from file May 09 07:53:09 zero volumio[4662]: info: CoreStateMachine::setRepeat false single undefined May 09 07:53:09 zero volumio[4662]: info: CoreStateMachine::pushState May 09 07:53:09 zero volumio[4662]: info: CorePlayQueue::getTrack 0 May 09 07:53:09 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 07:53:09 zero volumio[4662]: info: CoreCommandRouter::volumioPushState May 09 07:53:09 zero volumio[4662]: info: CoreStateMachine::setRandom true May 09 07:53:09 zero volumio[4662]: info: CoreStateMachine::pushState May 09 07:53:09 zero volumio[4662]: info: CorePlayQueue::getTrack 0 May 09 07:53:09 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 07:53:09 zero volumio[4662]: info: CoreCommandRouter::volumioPushState May 09 07:53:09 zero volumio[4662]: info: VolumeController:: Volume=30 Mute =false May 09 07:53:09 zero volumio[4662]: info: CoreStateMachine::pushState May 09 07:53:09 zero volumio[4662]: info: CorePlayQueue::getTrack 0 May 09 07:53:09 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 07:53:09 zero volumio[4662]: info: CoreCommandRouter::volumioPushState May 09 07:53:09 zero volumio[4662]: info: CoreStateMachine::updateTrackBlock May 09 07:53:09 zero volumio[4662]: info: CorePlayQueue::getTrackBlock May 09 07:53:09 zero volumio[4662]: info: CoreCommandRouter::volumioRetrievevolume May 09 07:53:09 zero volumio[4662]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb May 09 07:53:09 zero volumio[4662]: info: USB Boot Capable - System SBC Revision found in cpuinfo: 902120 May 09 07:53:09 zero volumio[4662]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI May 09 07:53:09 zero volumio[4662]: info: Setting Device type: Raspberry PI May 09 07:53:10 zero sudo[4831]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 09 07:53:10 zero sudo[4831]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 07:53:10 zero volumio[4662]: info: Completed loading Core Plugins May 09 07:53:10 zero volumio[4662]: info: Preparing to generate the ALSA configuration file May 09 07:53:10 zero sudo[4831]: pam_unix(sudo:session): session closed for user root May 09 07:53:10 zero volumio[4662]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 09 07:53:10 zero volumio[4662]: info: Reading ALSA contributions from plugins. May 09 07:53:10 zero volumio[4662]: info: VolumeController:: Volume=30 Mute =false May 09 07:53:10 zero volumio[4662]: info: CoreStateMachine::pushState May 09 07:53:10 zero volumio[4662]: info: CorePlayQueue::getTrack 0 May 09 07:53:10 zero volumio[4662]: info: CoreCommandRouter::volumioPushState May 09 07:53:10 zero volumio[4662]: info: Upmpdcli Daemon Started May 09 07:53:10 zero volumio[4662]: info: Asound.conf file written May 09 07:53:10 zero sudo[4836]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 09 07:53:10 zero sudo[4836]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 07:53:10 zero sudo[4836]: pam_unix(sudo:session): session closed for user root May 09 07:53:10 zero volumio[4662]: info: Output device has changed, restarting MPD May 09 07:53:10 zero sudo[4841]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 09 07:53:10 zero sudo[4841]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 07:53:10 zero sudo[4841]: pam_unix(sudo:session): session closed for user root May 09 07:53:10 zero volumio[4662]: info: Output device has changed, restarting Shairport Sync May 09 07:53:10 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 07:53:10 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 07:53:10 zero sudo[4844]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 09 07:53:10 zero sudo[4844]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 07:53:10 zero systemd[1]: Stopping Music Player Daemon... May 09 07:53:10 zero volumio[4662]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 09 07:53:10 zero volumio[4662]: info: ___________ START PLUGINS ___________ May 09 07:53:10 zero volumio[4662]: info: ControllerMpd::onStart: Initializing MPD May 09 07:53:10 zero volumio[4662]: info: Creating MPD Configuration file May 09 07:53:10 zero sudo[4850]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 09 07:53:10 zero sudo[4850]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 07:53:10 zero sudo[4850]: pam_unix(sudo:session): session closed for user root May 09 07:53:10 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 09 07:53:10 zero volumio[4662]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 09 07:53:10 zero volumio[4662]: info: [1746795190564] CoreMusicLibrary::Adding element Media Servers May 09 07:53:10 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 07:53:10 zero sudo[4853]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 09 07:53:10 zero sudo[4853]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 07:53:10 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 07:53:10 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 07:53:10 zero volumio[4662]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 09 07:53:10 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 07:53:10 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 07:53:10 zero volumio[4662]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 09 07:53:10 zero volumio[4662]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 09 07:53:10 zero volumio[4662]: info: [1746795190774] CoreMusicLibrary::Adding element Last_100 May 09 07:53:10 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 07:53:10 zero volumio[4662]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 09 07:53:10 zero volumio[4662]: info: [1746795190778] CoreMusicLibrary::Adding element Webradio May 09 07:53:10 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 07:53:10 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 09 07:53:10 zero volumio[4662]: info: Initializing BBC Radios May 09 07:53:10 zero volumio[4662]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory May 09 07:53:10 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 07:53:10 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 09 07:53:10 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 07:53:10 zero volumio[4662]: info: Creating Spotify config file May 09 07:53:10 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 07:53:10 zero volumio[4662]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 09 07:53:10 zero volumio[4662]: info: [1746795190971] CoreMusicLibrary::Adding element YouTube2 May 09 07:53:10 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 07:53:10 zero volumio[4662]: Cannot find translation for source YouTube2 May 09 07:53:11 zero volumio[4662]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 09 07:53:11 zero volumio[4662]: info: [1746795191047] CoreMusicLibrary::Adding element YouTube Music May 09 07:53:11 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 07:53:11 zero volumio[4662]: Cannot find translation for source YouTube2 May 09 07:53:11 zero volumio[4662]: Cannot find translation for source YouTube Music May 09 07:53:11 zero volumio[4662]: info: Loading i18n strings for locale es May 09 07:53:11 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile May 09 07:53:11 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 07:53:11 zero volumio[4662]: info: FusionDsp - mixtype--------------------- Hardware May 09 07:53:11 zero volumio[4662]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 09 07:53:11 zero volumio[4662]: info: [1746795191100] CoreMusicLibrary::Adding element 80s80s Radio May 09 07:53:11 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 07:53:11 zero volumio[4662]: Cannot find translation for source YouTube2 May 09 07:53:11 zero volumio[4662]: Cannot find translation for source YouTube Music May 09 07:53:11 zero volumio[4662]: Cannot find translation for source 80s80s Radio May 09 07:53:11 zero volumio[4662]: info: Volumio Calling Home May 09 07:53:11 zero volumio[4662]: info: Preparing to generate the ALSA configuration file May 09 07:53:11 zero volumio[4662]: verbose: [yt-cast-receiver] Setting app pid to stored value: 0790e94f-2a47-4c4e-8911-b2505d7af98b May 09 07:53:11 zero volumio[4662]: verbose: [yt-cast-receiver] Starting YouTubeApp... May 09 07:53:11 zero volumio[4662]: verbose: [yt-cast-receiver] (YouTube) Configuring session with stored MDX context: May 09 07:53:11 zero volumio[4662]: { May 09 07:53:11 zero volumio[4662]: deviceId: 'b09c8eaa-61a9-47d1-8655-fee1bad2fba5', May 09 07:53:11 zero volumio[4662]: screenId: '79t767efofk6v1tep43qs0h93s' May 09 07:53:11 zero volumio[4662]: } May 09 07:53:11 zero volumio[4662]: verbose: [yt-cast-receiver] (YouTube Music) Configuring session with stored MDX context: May 09 07:53:11 zero volumio[4662]: { May 09 07:53:11 zero volumio[4662]: deviceId: 'd23b40c7-a779-4ac5-8fa3-143b46148b7c', May 09 07:53:11 zero volumio[4662]: screenId: 'qbs9eq2sphvv2o0lhh0c2f1dpp' May 09 07:53:11 zero volumio[4662]: } May 09 07:53:11 zero volumio[4662]: info: Discovery: adding 9f8d5a4a-6806-46fe-b062-8e8decea0002 May 09 07:53:11 zero volumio[4662]: info: Discovery: Found device Zero May 09 07:53:11 zero volumio[4662]: info: CoreCommandRouter::volumioGetState May 09 07:53:11 zero volumio[4662]: info: CorePlayQueue::getTrack 0 May 09 07:53:11 zero volumio[4662]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 09 07:53:11 zero volumio[4662]: info: Reading ALSA contributions from plugins. May 09 07:53:11 zero volumio[4662]: verbose: New Socket.io Connection to 192.168.1.13 from 192.168.1.16 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 6 May 09 07:53:11 zero volumio[4662]: info: MPD Permissions set May 09 07:53:11 zero volumio[4662]: info: MPD Permissions set May 09 07:53:11 zero volumio[4662]: info: Spotify config file written May 09 07:53:11 zero volumio[4662]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 May 09 07:53:11 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 07:53:11 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 07:53:11 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 07:53:11 zero sudo[4874]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service May 09 07:53:11 zero sudo[4874]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 07:53:11 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 07:53:11 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 07:53:11 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 07:53:11 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 07:53:11 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 07:53:11 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 07:53:11 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 07:53:11 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 07:53:11 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 07:53:11 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 07:53:11 zero systemd[1]: Stopping go-librespot Daemon... May 09 07:53:11 zero systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM May 09 07:53:11 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 07:53:11 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 07:53:11 zero volumio[4662]: info: No need to fix Spotify hosts May 09 07:53:11 zero systemd[1]: go-librespot-daemon.service: Succeeded. May 09 07:53:11 zero systemd[1]: Stopped go-librespot Daemon. May 09 07:53:11 zero volumio[4662]: info: CoreCommandRouter::volumioGetState May 09 07:53:11 zero volumio[4662]: info: CorePlayQueue::getTrack 0 May 09 07:53:11 zero systemd[1]: Started go-librespot Daemon. May 09 07:53:11 zero systemd[1]: mpd.service: Succeeded. May 09 07:53:11 zero sudo[4874]: pam_unix(sudo:session): session closed for user root May 09 07:53:11 zero systemd[1]: Stopped Music Player Daemon. May 09 07:53:11 zero go-librespot[4884]: go-librespot daemon starting... May 09 07:53:11 zero systemd[1]: Starting Music Player Daemon... May 09 07:53:11 zero volumio-remote-updater[507]: [2025-05-09 07:53:11] [connect] Successful connection May 09 07:53:11 zero volumio[4662]: info: Received Get System Info May 09 07:53:11 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 09 07:53:11 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 09 07:53:11 zero volumio[4662]: info: Discovery: Getting this device information May 09 07:53:11 zero volumio[4662]: info: CoreCommandRouter::volumioGetState May 09 07:53:11 zero volumio[4662]: info: CorePlayQueue::getTrack 0 May 09 07:53:11 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 09 07:53:11 zero volumio[4662]: info: CoreCommandRouter::volumioGetState May 09 07:53:11 zero volumio[4662]: info: CorePlayQueue::getTrack 0 May 09 07:53:11 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 09 07:53:11 zero volumio[4662]: info: CoreCommandRouter::volumioGetVisibleSources May 09 07:53:11 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 07:53:11 zero volumio[4662]: info: CoreCommandRouter::volumioGetState May 09 07:53:11 zero volumio[4662]: info: CorePlayQueue::getTrack 0 May 09 07:53:11 zero volumio[4662]: info: CoreCommandRouter::volumioGetState May 09 07:53:11 zero volumio[4662]: info: CorePlayQueue::getTrack 0 May 09 07:53:11 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 09 07:53:11 zero volumio[4662]: info: CoreCommandRouter::volumioGetQueue May 09 07:53:11 zero volumio[4662]: info: CoreStateMachine::getQueue May 09 07:53:11 zero volumio[4662]: info: CorePlayQueue::getQueue May 09 07:53:11 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 09 07:53:11 zero volumio[4662]: info: Received Get System Info May 09 07:53:11 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 09 07:53:11 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 09 07:53:11 zero volumio[4662]: info: Discovery: Getting this device information May 09 07:53:11 zero volumio[4662]: info: CoreCommandRouter::volumioGetState May 09 07:53:11 zero volumio[4662]: info: CorePlayQueue::getTrack 0 May 09 07:53:11 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 09 07:53:11 zero volumio[4662]: info: CoreCommandRouter::volumioGetState May 09 07:53:11 zero volumio[4662]: info: CorePlayQueue::getTrack 0 May 09 07:53:12 zero volumio[4662]: info: Listing playlists May 09 07:53:12 zero sudo[4886]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 09 07:53:12 zero sudo[4886]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 07:53:12 zero sudo[4886]: pam_unix(sudo:session): session closed for user root May 09 07:53:12 zero volumio[4662]: info: Starting Shairport Sync May 09 07:53:12 zero volumio[4662]: info: Starting Shairport Sync May 09 07:53:12 zero sudo[4896]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 09 07:53:12 zero sudo[4896]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 07:53:12 zero volumio[4662]: info: Starting Shairport Sync May 09 07:53:12 zero sudo[4898]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 09 07:53:12 zero sudo[4898]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 07:53:12 zero sudo[4902]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 09 07:53:12 zero sudo[4902]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 07:53:12 zero go-librespot[4884]: time="2025-05-09T07:53:12-05:00" level=info msg="running go-librespot 0.2.0" May 09 07:53:12 zero go-librespot[4884]: time="2025-05-09T07:53:12-05:00" level=debug msg="app state loaded" May 09 07:53:12 zero volumio[4662]: 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 May 09 07:53:12 zero volumio[4662]: info: CoreCommandRouter::volumioGetState May 09 07:53:12 zero volumio[4662]: info: CorePlayQueue::getTrack 0 May 09 07:53:12 zero go-librespot[4884]: time="2025-05-09T07:53:12-05:00" level=info msg="api server listening on 127.0.0.1:9879" May 09 07:53:12 zero systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 09 07:53:12 zero systemd[1]: shairport-sync.service: Succeeded. May 09 07:53:12 zero volumio-remote-updater[507]: [2025-05-09 07:53:12] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1746795191 101 May 09 07:53:12 zero systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 09 07:53:12 zero volumio[4662]: info: Volumio called home May 09 07:53:12 zero volumio[4662]: info: Asound.conf file unchanged, so no further update is needed May 09 07:53:12 zero volumio[4662]: info: Output device has changed, restarting MPD May 09 07:53:12 zero systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 09 07:53:12 zero sudo[4898]: pam_unix(sudo:session): session closed for user root May 09 07:53:12 zero sudo[4902]: pam_unix(sudo:session): session closed for user root May 09 07:53:12 zero sudo[4906]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 09 07:53:12 zero sudo[4896]: pam_unix(sudo:session): session closed for user root May 09 07:53:12 zero sudo[4906]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 07:53:12 zero volumio[4662]: info: Output device has changed, restarting Shairport Sync May 09 07:53:12 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 07:53:12 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 07:53:12 zero sudo[4906]: pam_unix(sudo:session): session closed for user root May 09 07:53:12 zero sudo[4908]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 09 07:53:12 zero sudo[4908]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 07:53:12 zero systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 09 07:53:12 zero systemd[1]: mpd.service: Succeeded. May 09 07:53:12 zero systemd[1]: Stopped Music Player Daemon. May 09 07:53:12 zero volumio[4662]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 09 07:53:12 zero systemd[1]: Starting Music Player Daemon... May 09 07:53:12 zero volumio[4662]: info: MPD Permissions set May 09 07:53:12 zero volumio[4662]: info: Shairport-Sync Started May 09 07:53:12 zero volumio[4662]: Error adding Membership: Error: addMembership EINVAL May 09 07:53:12 zero volumio[4662]: info: Shairport-Sync Started May 09 07:53:12 zero volumio[4662]: info: Shairport-Sync Started May 09 07:53:12 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 07:53:12 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 07:53:12 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 07:53:12 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 07:53:12 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 07:53:12 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 07:53:12 zero sudo[4914]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 09 07:53:12 zero sudo[4914]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 07:53:12 zero sudo[4914]: pam_unix(sudo:session): session closed for user root May 09 07:53:12 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 07:53:12 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 07:53:12 zero volumio[4662]: info: Starting Shairport Sync May 09 07:53:12 zero volumio[4662]: verbose: [yt-cast-receiver] (YouTube Music) Obtained lounge token: May 09 07:53:12 zero volumio[4662]: { May 09 07:53:12 zero volumio[4662]: screenId: 'qbs9eq2sphvv2o0lhh0c2f1dpp', May 09 07:53:12 zero volumio[4662]: refreshIntervalInMillis: 1123200000, May 09 07:53:12 zero volumio[4662]: remoteRefreshIntervalMs: 79200000, May 09 07:53:12 zero volumio[4662]: refreshIntervalMs: 1123200000, May 09 07:53:12 zero volumio[4662]: loungeTokenLifespanMs: 1209600000, May 09 07:53:12 zero volumio[4662]: loungeToken: 'AGdO5p_Jt1k-BGqfkM8kA4vzfPBLNTEGelLYASTB0vl0pBnvBb64wBQdNQ3W2qKzpLiFsr1cqk9OLfQss0iu4yRraqSOB4DvfHj14Tes8kI55Ie3qooCEbc', May 09 07:53:12 zero volumio[4662]: remoteRefreshIntervalInMillis: 79200000, May 09 07:53:12 zero volumio[4662]: expiration: 1747918392146 May 09 07:53:12 zero volumio[4662]: } May 09 07:53:12 zero sudo[4925]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 09 07:53:12 zero sudo[4925]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 07:53:12 zero systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 09 07:53:12 zero systemd[1]: shairport-sync.service: Succeeded. May 09 07:53:12 zero systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 09 07:53:13 zero volumio[4662]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 07:53:13 zero volumio[4662]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 07:53:13 zero volumio[4662]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 07:53:13 zero systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 09 07:53:13 zero volumio[4662]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 07:53:13 zero volumio[4662]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 07:53:13 zero volumio[4662]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 07:53:13 zero sudo[4925]: pam_unix(sudo:session): session closed for user root May 09 07:53:13 zero volumio[4662]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 07:53:13 zero volumio[4662]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 07:53:13 zero go-librespot[4884]: time="2025-05-09T07:53:13-05:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" May 09 07:53:13 zero go-librespot[4884]: time="2025-05-09T07:53:13-05:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" May 09 07:53:13 zero go-librespot[4884]: time="2025-05-09T07:53:13-05:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" May 09 07:53:13 zero volumio[4662]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 07:53:13 zero volumio[4662]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 07:53:13 zero volumio[4662]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 07:53:13 zero volumio[4662]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 07:53:13 zero go-librespot[4884]: time="2025-05-09T07:53:13-05:00" level=info msg="zeroconf server listening on port 36639" May 09 07:53:13 zero volumio[4662]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 07:53:13 zero volumio[4662]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 07:53:13 zero volumio[4662]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 07:53:13 zero volumio[4662]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 07:53:13 zero volumio[4662]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 07:53:13 zero volumio[4662]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 07:53:13 zero volumio[4662]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 07:53:13 zero volumio[4662]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 07:53:13 zero volumio[4662]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 07:53:13 zero volumio[4662]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 07:53:13 zero volumio[4662]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 07:53:13 zero volumio[4662]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 07:53:13 zero volumio[4662]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 07:53:13 zero volumio[4662]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 07:53:13 zero volumio[4662]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 07:53:13 zero volumio[4662]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 07:53:13 zero volumio[4662]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 07:53:13 zero volumio[4662]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 07:53:13 zero volumio[4662]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 07:53:13 zero volumio[4662]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists May 09 07:53:13 zero volumio[4662]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo May 09 07:53:13 zero volumio[4662]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists May 09 07:53:13 zero volumio[4662]: info: camilladsp spawned new process with pid 4937, instance 1, run: true May 09 07:53:13 zero volumio[4662]: info: camilladsp service started and running in background, instance 1 May 09 07:53:13 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 07:53:13 zero volumio[4662]: info: FusionDsp - AAAAAAAAAAAAAA-> 44100 48000 <-AAAAAAAAAAAAA May 09 07:53:13 zero volumio[4662]: info: FusionDsp loaded May 09 07:53:13 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 09 07:53:13 zero volumio[4662]: info: FusionDsp - Reporting Fusion DSP Enabled May 09 07:53:13 zero volumio[4662]: info: Adding Signal Path Element [object Object] May 09 07:53:13 zero volumio[4662]: info: Adding fusiondspeq DSP Signal Path Element May 09 07:53:13 zero volumio[4662]: info: FusionDsp - ---- installed callbackRead May 09 07:53:13 zero volumio[4662]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 09 07:53:13 zero volumio[4662]: info: Shairport-Sync Started May 09 07:53:13 zero volumio[4662]: verbose: [yt-cast-receiver] (YouTube) Obtained lounge token: May 09 07:53:13 zero volumio[4662]: { May 09 07:53:13 zero volumio[4662]: screenId: '79t767efofk6v1tep43qs0h93s', May 09 07:53:13 zero volumio[4662]: refreshIntervalInMillis: 1123200000, May 09 07:53:13 zero volumio[4662]: remoteRefreshIntervalMs: 79200000, May 09 07:53:13 zero volumio[4662]: refreshIntervalMs: 1123200000, May 09 07:53:13 zero volumio[4662]: loungeTokenLifespanMs: 1209600000, May 09 07:53:13 zero volumio[4662]: loungeToken: 'AGdO5p-AF6xbawiRZSeI5k_qKRiwRFwfNz-czASZlGWAyQXGqzbgK-QlH_Ure5_crSYK27_xhlrVaAvSUHb8J7XwgptT_hnPjTB9KlYapVctLx7xj75kjv4', May 09 07:53:13 zero volumio[4662]: remoteRefreshIntervalInMillis: 79200000, May 09 07:53:13 zero volumio[4662]: expiration: 1747918392171 May 09 07:53:13 zero volumio[4662]: } May 09 07:53:13 zero sudo[4947]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service May 09 07:53:13 zero sudo[4947]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 07:53:13 zero volumio[4662]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 May 09 07:53:13 zero sudo[4947]: pam_unix(sudo:session): session closed for user root May 09 07:53:15 zero mpd[4919]: May 09 07:53 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 09 07:53:15 zero systemd[1]: Started Music Player Daemon. May 09 07:53:15 zero sudo[4844]: pam_unix(sudo:session): session closed for user root May 09 07:53:15 zero sudo[4908]: pam_unix(sudo:session): session closed for user root May 09 07:53:15 zero sudo[4853]: pam_unix(sudo:session): session closed for user root May 09 07:53:23 zero volumio[4662]: info: FusionDsp - {"Reload":{"result":"Ok"}} May 09 07:53:23 zero ntpd[686]: Soliciting pool server 2803:480:1::3 May 09 07:53:23 zero volumio[4662]: info: go-librespot daemon successfully initialized May 09 07:53:27 zero volumio[4662]: info: Initializing connection to go-librespot Websocket May 09 07:53:27 zero volumio[4662]: error: [yt-cast-receiver] (YouTube Music) Error occurred in SendMessageTask: May 09 07:53:27 zero volumio[4662]: LoungeScreenDisconnected { May 09 07:53:27 zero volumio[4662]: AID: null, May 09 07:53:27 zero volumio[4662]: name: 'loungeScreenDisconnected', May 09 07:53:27 zero volumio[4662]: payload: {} May 09 07:53:27 zero volumio[4662]: } May 09 07:53:27 zero volumio[4662]: (IncompleteAPIDataError) Missing data required to construct query string from bind params May 09 07:53:27 zero volumio[4662]: Error info: { missing: [ 'SID', 'gsessionid' ] } May 09 07:53:27 zero volumio[4662]: Stack trace: May 09 07:53:27 zero volumio[4662]: IncompleteAPIDataError: Missing data required to construct query string from bind params May 09 07:53:27 zero volumio[4662]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) May 09 07:53:27 zero volumio[4662]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) May 09 07:53:27 zero volumio[4662]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) May 09 07:53:27 zero volumio[4662]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) May 09 07:53:27 zero volumio[4662]: at AsyncTaskQueue.push (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:39:18) May 09 07:53:27 zero volumio[4662]: at /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:339:67 May 09 07:53:27 zero volumio[4662]: at new Promise () May 09 07:53:27 zero volumio[4662]: at Session.sendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:338:16) May 09 07:53:27 zero volumio[4662]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:236:28) May 09 07:53:27 zero volumio[4662]: at Session.begin (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:207:26) May 09 07:53:27 zero volumio[4662]: at processTicksAndRejections (internal/process/task_queues.js:93:5) May 09 07:53:27 zero volumio[4662]: at async Promise.all (index 1) May 09 07:53:27 zero volumio[4662]: at async YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:110:13) May 09 07:53:27 zero volumio[4662]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) May 09 07:53:27 zero volumio[4662]: verbose: [yt-cast-receiver] (YouTube Music) Retry task after refreshing lounge token... May 09 07:53:27 zero volumio[4662]: verbose: [yt-cast-receiver] (YouTube Music) Refreshing lounge token... May 09 07:53:27 zero volumio[4662]: error: [yt-cast-receiver] Failed to start receiver: May 09 07:53:27 zero volumio[4662]: (AppError) Failed to start YouTubeApp May 09 07:53:27 zero volumio[4662]: --->(SessionError) (YouTube Music) Failed to establish session May 09 07:53:27 zero volumio[4662]: ------>(ConnectionError) (YouTube Music) Connection error in fetching session data May 09 07:53:27 zero volumio[4662]: Error info: { May 09 07:53:27 zero volumio[4662]: url: 'https://www.youtube.com/api/lounge/bc/bind?CVER=1&RID=42713&VER=8&app=ytcr&capabilities=dsp%2Cmic%2Cdpa%2Cntb&cst=m&device=LOUNGE_SCREEN&deviceInfo=%7B%22year%22%3A0%2C%22os%22%3A%22Windows%22%2C%22osVersion%22%3A%2210.0%22%2C%22chipset%22%3A%22%22%2C%22clientName%22%3A%22TVHTML5%22%2C%22dialAdditionalDataSupportLevel%22%3A%22unsupported%22%2C%22mdxDialServerType%22%3A%22MDX_DIAL_SERVER_TYPE_UNKNOWN%22%2C%22brand%22%3A%22Generic%22%2C%22model%22%3A%22SmartTV%22%7D&id=d23b40c7-a779-4ac5-8fa3-143b46148b7c&loungeIdToken=AGdO5p_Jt1k-BGqfkM8kA4vzfPBLNTEGelLYASTB0vl0pBnvBb64wBQdNQ3W2qKzpLiFsr1cqk9OLfQss0iu4yRraqSOB4DvfHj14Tes8kI55Ie3qooCEbc&mdxVersion=2&name=YouTube%20on%20Zero&obfuscatedGaiaId=&t=1&theme=m&v=2&zx=04db0ca47af8' May 09 07:53:27 zero volumio[4662]: } May 09 07:53:27 zero volumio[4662]: --------->(FetchError) request to https://www.youtube.com/api/lounge/bc/bind?CVER=1&RID=42713&VER=8&app=ytcr&capabilities=dsp%2Cmic%2Cdpa%2Cntb&cst=m&device=LOUNGE_SCREEN&deviceInfo=%7B%22year%22%3A0%2C%22os%22%3A%22Windows%22%2C%22osVersion%22%3A%2210.0%22%2C%22chipset%22%3A%22%22%2C%22clientName%22%3A%22TVHTML5%22%2C%22dialAdditionalDataSupportLevel%22%3A%22unsupported%22%2C%22mdxDialServerType%22%3A%22MDX_DIAL_SERVER_TYPE_UNKNOWN%22%2C%22brand%22%3A%22Generic%22%2C%22model%22%3A%22SmartTV%22%7D&id=d23b40c7-a779-4ac5-8fa3-143b46148b7c&loungeIdToken=AGdO5p_Jt1k-BGqfkM8kA4vzfPBLNTEGelLYASTB0vl0pBnvBb64wBQdNQ3W2qKzpLiFsr1cqk9OLfQss0iu4yRraqSOB4DvfHj14Tes8kI55Ie3qooCEbc&mdxVersion=2&name=YouTube%20on%20Zero&obfuscatedGaiaId=&t=1&theme=m&v=2&zx=04db0ca47af8 failed, reason: socket hang up May 09 07:53:27 zero volumio[4662]: Stack trace: May 09 07:53:27 zero volumio[4662]: AppError: Failed to start YouTubeApp May 09 07:53:27 zero volumio[4662]: at YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:125:19) May 09 07:53:27 zero volumio[4662]: at processTicksAndRejections (internal/process/task_queues.js:93:5) May 09 07:53:27 zero volumio[4662]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) May 09 07:53:27 zero volumio[4662]: error: [yt-cast-receiver] (YouTube) Error occurred in SendMessageTask: May 09 07:53:27 zero volumio[4662]: LoungeScreenDisconnected { May 09 07:53:27 zero volumio[4662]: AID: null, May 09 07:53:27 zero volumio[4662]: name: 'loungeScreenDisconnected', May 09 07:53:27 zero volumio[4662]: payload: {} May 09 07:53:27 zero volumio[4662]: } May 09 07:53:27 zero volumio[4662]: (IncompleteAPIDataError) Missing data required to construct query string from bind params May 09 07:53:27 zero volumio[4662]: Error info: { missing: [ 'SID', 'gsessionid' ] } May 09 07:53:27 zero volumio[4662]: Stack trace: May 09 07:53:27 zero volumio[4662]: IncompleteAPIDataError: Missing data required to construct query string from bind params May 09 07:53:27 zero volumio[4662]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) May 09 07:53:27 zero volumio[4662]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) May 09 07:53:27 zero volumio[4662]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) May 09 07:53:27 zero volumio[4662]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) May 09 07:53:27 zero volumio[4662]: at AsyncTaskQueue.push (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:39:18) May 09 07:53:27 zero volumio[4662]: at /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:339:67 May 09 07:53:27 zero volumio[4662]: at new Promise () May 09 07:53:27 zero volumio[4662]: at Session.sendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:338:16) May 09 07:53:27 zero volumio[4662]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:236:28) May 09 07:53:27 zero volumio[4662]: at /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:118:35 May 09 07:53:27 zero volumio[4662]: at Array.forEach () May 09 07:53:27 zero volumio[4662]: at YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:114:24) May 09 07:53:27 zero volumio[4662]: at processTicksAndRejections (internal/process/task_queues.js:93:5) May 09 07:53:27 zero volumio[4662]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) May 09 07:53:27 zero volumio[4662]: verbose: [yt-cast-receiver] (YouTube) Retry task after refreshing lounge token... May 09 07:53:27 zero volumio[4662]: verbose: [yt-cast-receiver] (YouTube) Refreshing lounge token... May 09 07:53:27 zero volumio[4662]: error: [yt-cast-receiver] (YouTube Music) Error occurred in SendMessageTask: May 09 07:53:27 zero volumio[4662]: LoungeScreenDisconnected { May 09 07:53:27 zero volumio[4662]: AID: null, May 09 07:53:27 zero volumio[4662]: name: 'loungeScreenDisconnected', May 09 07:53:27 zero volumio[4662]: payload: {} May 09 07:53:27 zero volumio[4662]: } May 09 07:53:27 zero volumio[4662]: (IncompleteAPIDataError) Missing data required to construct query string from bind params May 09 07:53:27 zero volumio[4662]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } May 09 07:53:27 zero volumio[4662]: Stack trace: May 09 07:53:27 zero volumio[4662]: IncompleteAPIDataError: Missing data required to construct query string from bind params May 09 07:53:27 zero volumio[4662]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) May 09 07:53:27 zero volumio[4662]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) May 09 07:53:27 zero volumio[4662]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) May 09 07:53:27 zero volumio[4662]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) May 09 07:53:27 zero volumio[4662]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) May 09 07:53:27 zero volumio[4662]: at processTicksAndRejections (internal/process/task_queues.js:93:5) May 09 07:53:27 zero volumio[4662]: verbose: [yt-cast-receiver] (YouTube Music) Retry task after refreshing lounge token... May 09 07:53:27 zero volumio[4662]: verbose: [yt-cast-receiver] (YouTube Music) Refreshing lounge token... May 09 07:53:27 zero volumio[4662]: error: [ytcr] Failed to start plugin: May 09 07:53:27 zero volumio[4662]: (AppError) Failed to start YouTubeApp May 09 07:53:27 zero volumio[4662]: --->(SessionError) (YouTube Music) Failed to establish session May 09 07:53:27 zero volumio[4662]: ------>(ConnectionError) (YouTube Music) Connection error in fetching session data May 09 07:53:27 zero volumio[4662]: Error info: { May 09 07:53:27 zero volumio[4662]: url: 'https://www.youtube.com/api/lounge/bc/bind?CVER=1&RID=42713&VER=8&app=ytcr&capabilities=dsp%2Cmic%2Cdpa%2Cntb&cst=m&device=LOUNGE_SCREEN&deviceInfo=%7B%22year%22%3A0%2C%22os%22%3A%22Windows%22%2C%22osVersion%22%3A%2210.0%22%2C%22chipset%22%3A%22%22%2C%22clientName%22%3A%22TVHTML5%22%2C%22dialAdditionalDataSupportLevel%22%3A%22unsupported%22%2C%22mdxDialServerType%22%3A%22MDX_DIAL_SERVER_TYPE_UNKNOWN%22%2C%22brand%22%3A%22Generic%22%2C%22model%22%3A%22SmartTV%22%7D&id=d23b40c7-a779-4ac5-8fa3-143b46148b7c&loungeIdToken=AGdO5p_Jt1k-BGqfkM8kA4vzfPBLNTEGelLYASTB0vl0pBnvBb64wBQdNQ3W2qKzpLiFsr1cqk9OLfQss0iu4yRraqSOB4DvfHj14Tes8kI55Ie3qooCEbc&mdxVersion=2&name=YouTube%20on%20Zero&obfuscatedGaiaId=&t=1&theme=m&v=2&zx=04db0ca47af8' May 09 07:53:27 zero volumio[4662]: } May 09 07:53:27 zero volumio[4662]: --------->(FetchError) request to https://www.youtube.com/api/lounge/bc/bind?CVER=1&RID=42713&VER=8&app=ytcr&capabilities=dsp%2Cmic%2Cdpa%2Cntb&cst=m&device=LOUNGE_SCREEN&deviceInfo=%7B%22year%22%3A0%2C%22os%22%3A%22Windows%22%2C%22osVersion%22%3A%2210.0%22%2C%22chipset%22%3A%22%22%2C%22clientName%22%3A%22TVHTML5%22%2C%22dialAdditionalDataSupportLevel%22%3A%22unsupported%22%2C%22mdxDialServerType%22%3A%22MDX_DIAL_SERVER_TYPE_UNKNOWN%22%2C%22brand%22%3A%22Generic%22%2C%22model%22%3A%22SmartTV%22%7D&id=d23b40c7-a779-4ac5-8fa3-143b46148b7c&loungeIdToken=AGdO5p_Jt1k-BGqfkM8kA4vzfPBLNTEGelLYASTB0vl0pBnvBb64wBQdNQ3W2qKzpLiFsr1cqk9OLfQss0iu4yRraqSOB4DvfHj14Tes8kI55Ie3qooCEbc&mdxVersion=2&name=YouTube%20on%20Zero&obfuscatedGaiaId=&t=1&theme=m&v=2&zx=04db0ca47af8 failed, reason: socket hang up May 09 07:53:27 zero volumio[4662]: Stack trace: May 09 07:53:27 zero volumio[4662]: AppError: Failed to start YouTubeApp May 09 07:53:27 zero volumio[4662]: at YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:125:19) May 09 07:53:27 zero volumio[4662]: at processTicksAndRejections (internal/process/task_queues.js:93:5) May 09 07:53:27 zero volumio[4662]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) May 09 07:53:27 zero volumio[4662]: info: Completed starting Core Plugins May 09 07:53:27 zero volumio[4662]: info: ------------------------------------------- May 09 07:53:27 zero volumio[4662]: info: ----- MyVolumio plugins startup ---- May 09 07:53:27 zero volumio[4662]: info: ------------------------------------------- May 09 07:53:27 zero volumio[4662]: info: [MyVolumio PluginManager] Fetching plans data.... May 09 07:53:27 zero volumio[4662]: info: Discovery: Browse raised the following error Error: dns service error: unknown May 09 07:53:27 zero volumio[4662]: info: MPD running with PID4919 May 09 07:53:27 zero volumio[4662]: ,establishing connection May 09 07:53:27 zero volumio[4662]: error: MPD error: The expression evaluated to a falsy value: May 09 07:53:27 zero volumio[4662]: assert.ok(self.idling) May 09 07:53:27 zero volumio[4662]: error: The expression evaluated to a falsy value: May 09 07:53:27 zero volumio[4662]: assert.ok(self.idling) May 09 07:53:27 zero volumio[4662]: error: MPD error: The expression evaluated to a falsy value: May 09 07:53:27 zero volumio[4662]: assert.ok(self.idling) May 09 07:53:27 zero volumio[4662]: error: The expression evaluated to a falsy value: May 09 07:53:27 zero volumio[4662]: assert.ok(self.idling) May 09 07:53:27 zero volumio[4662]: error: MPD error: The expression evaluated to a falsy value: May 09 07:53:27 zero volumio[4662]: assert.ok(self.idling) May 09 07:53:27 zero volumio[4662]: error: The expression evaluated to a falsy value: May 09 07:53:27 zero volumio[4662]: assert.ok(self.idling) May 09 07:53:27 zero go-librespot[4884]: time="2025-05-09T07:53:27-05:00" level=debug msg="new websocket client" May 09 07:53:28 zero volumio[4662]: info: Connection to go-librespot Websocket established May 09 07:53:28 zero volumio[4662]: error: updateQueue error: null May 09 07:53:28 zero volumio[4662]: verbose: [yt-cast-receiver] (YouTube) Received messages for establishing session: May 09 07:53:28 zero volumio[4662]: [ May 09 07:53:28 zero volumio[4662]: Message { AID: 0, name: 'c', payload: [ '8021D03FC590C1DE', '', 8 ] }, May 09 07:53:28 zero volumio[4662]: Message { May 09 07:53:28 zero volumio[4662]: AID: 1, May 09 07:53:28 zero volumio[4662]: name: 'S', May 09 07:53:28 zero volumio[4662]: payload: '87et1jdBUe-yDGWQdsKkK88OXo-f1t1GkOBFtsUHqcE' May 09 07:53:28 zero volumio[4662]: }, May 09 07:53:28 zero volumio[4662]: Message { May 09 07:53:28 zero volumio[4662]: AID: 2, May 09 07:53:28 zero volumio[4662]: name: 'loungeStatus', May 09 07:53:28 zero volumio[4662]: payload: { May 09 07:53:28 zero volumio[4662]: queueId: 'RQJR_1rmBJ9K5LRBF9gPjKVcKqm2A', May 09 07:53:28 zero volumio[4662]: devices: '[{"app":"ytcr","capabilities":"dsp,mic,dpa,ntb,que,mus","clientName":"unknown","experiments":"","name":"YouTube on Zero","theme":"cl","id":"b09c8eaa-61a9-47d1-8655-fee1bad2fba5","type":"LOUNGE_SCREEN","hasCc":"true","deviceInfo":"{\\"year\\":0,\\"os\\":\\"Windows\\",\\"osVersion\\":\\"10.0\\",\\"chipset\\":\\"\\",\\"clientName\\":\\"TVHTML5\\",\\"dialAdditionalDataSupportLevel\\":\\"unsupported\\",\\"mdxDialServerType\\":\\"MDX_DIAL_SERVER_TYPE_UNKNOWN\\",\\"brand\\":\\"Generic\\",\\"model\\":\\"SmartTV\\"}","receiverIdentityMatchStatus":"IS_RECEIVER"}]', May 09 07:53:28 zero volumio[4662]: connectionEventDetails: '{"deviceId":"b09c8eaa-61a9-47d1-8655-fee1bad2fba5"}' May 09 07:53:28 zero volumio[4662]: } May 09 07:53:28 zero volumio[4662]: }, May 09 07:53:28 zero volumio[4662]: Message { AID: 3, name: 'getNowPlaying', payload: {} }, May 09 07:53:28 zero volumio[4662]: Message { AID: 4, name: 'getDiscoveryDeviceId', payload: {} } May 09 07:53:28 zero volumio[4662]: ] May 09 07:53:28 zero volumio[4662]: error: [yt-cast-receiver] (YouTube) Error occurred in SendMessageTask: May 09 07:53:28 zero volumio[4662]: LoungeScreenDisconnected { May 09 07:53:28 zero volumio[4662]: AID: null, May 09 07:53:28 zero volumio[4662]: name: 'loungeScreenDisconnected', May 09 07:53:28 zero volumio[4662]: payload: {} May 09 07:53:28 zero volumio[4662]: } May 09 07:53:28 zero volumio[4662]: (IncompleteAPIDataError) Missing data required to construct query string from bind params May 09 07:53:28 zero volumio[4662]: Error info: { missing: [ 'loungeIdToken' ] } May 09 07:53:28 zero volumio[4662]: Stack trace: May 09 07:53:28 zero volumio[4662]: IncompleteAPIDataError: Missing data required to construct query string from bind params May 09 07:53:28 zero volumio[4662]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) May 09 07:53:28 zero volumio[4662]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) May 09 07:53:28 zero volumio[4662]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) May 09 07:53:28 zero volumio[4662]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) May 09 07:53:28 zero volumio[4662]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) May 09 07:53:28 zero volumio[4662]: at processTicksAndRejections (internal/process/task_queues.js:93:5) May 09 07:53:28 zero volumio[4662]: at async /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:118:21 May 09 07:53:28 zero volumio[4662]: verbose: [yt-cast-receiver] (YouTube) Retry task after refreshing lounge token... May 09 07:53:28 zero volumio[4662]: verbose: [yt-cast-receiver] (YouTube) Refreshing lounge token... May 09 07:53:28 zero volumio[4662]: verbose: [yt-cast-receiver] (YouTube Music) Obtained lounge token: May 09 07:53:28 zero volumio[4662]: { May 09 07:53:28 zero volumio[4662]: screenId: 'qbs9eq2sphvv2o0lhh0c2f1dpp', May 09 07:53:28 zero volumio[4662]: refreshIntervalInMillis: 1123200000, May 09 07:53:28 zero volumio[4662]: remoteRefreshIntervalMs: 79200000, May 09 07:53:28 zero volumio[4662]: refreshIntervalMs: 1123200000, May 09 07:53:28 zero volumio[4662]: loungeTokenLifespanMs: 1209600000, May 09 07:53:28 zero volumio[4662]: loungeToken: 'AGdO5p_7d6mYJrCR-77qS1h9NVsreg-doDiBxKTxEJeyuTzxOp2wu6fi_k0lKQuCIw14R_uQ2j-GQSVVq2bDhlW8J86TKn9dr79TJYLFf5aD1Av1-TDGscM', May 09 07:53:28 zero volumio[4662]: remoteRefreshIntervalInMillis: 79200000, May 09 07:53:28 zero volumio[4662]: expiration: 1747918408659 May 09 07:53:28 zero volumio[4662]: } May 09 07:53:28 zero volumio[4662]: verbose: [yt-cast-receiver] (YouTube Music) Obtained lounge token: May 09 07:53:28 zero volumio[4662]: { May 09 07:53:28 zero volumio[4662]: screenId: 'qbs9eq2sphvv2o0lhh0c2f1dpp', May 09 07:53:28 zero volumio[4662]: refreshIntervalInMillis: 1123200000, May 09 07:53:28 zero volumio[4662]: remoteRefreshIntervalMs: 79200000, May 09 07:53:28 zero volumio[4662]: refreshIntervalMs: 1123200000, May 09 07:53:28 zero volumio[4662]: loungeTokenLifespanMs: 1209600000, May 09 07:53:28 zero volumio[4662]: loungeToken: 'AGdO5p_dE0yox7LQTkc1qEBWaiIbWPR8AO3TlvrKpX4mU_dMa8dfsBC1k6ACOxdpavYDkhrFDwXgK-UjApo6kRN9ISVVemB0kpFmZ0rsq9Qc_58erjiFjFk', May 09 07:53:28 zero volumio[4662]: remoteRefreshIntervalInMillis: 79200000, May 09 07:53:28 zero volumio[4662]: expiration: 1747918408665 May 09 07:53:28 zero volumio[4662]: } May 09 07:53:28 zero volumio[4662]: verbose: [yt-cast-receiver] (YouTube) Obtained lounge token: May 09 07:53:28 zero volumio[4662]: { May 09 07:53:28 zero volumio[4662]: screenId: '79t767efofk6v1tep43qs0h93s', May 09 07:53:28 zero volumio[4662]: refreshIntervalInMillis: 1123200000, May 09 07:53:28 zero volumio[4662]: remoteRefreshIntervalMs: 79200000, May 09 07:53:28 zero volumio[4662]: refreshIntervalMs: 1123200000, May 09 07:53:28 zero volumio[4662]: loungeTokenLifespanMs: 1209600000, May 09 07:53:28 zero volumio[4662]: loungeToken: 'AGdO5p-yaw84vndZB5dospFoNnM1c0fRquLwjQ-6iCx5mQJIhlVwuyx1_MqZMqukW_24QTsG2Iyq8JwKrCQ52xquStj3GQ1dVuvDgP0JJ6huEUCJM9gN1BA', May 09 07:53:28 zero volumio[4662]: remoteRefreshIntervalInMillis: 79200000, May 09 07:53:28 zero volumio[4662]: expiration: 1747918408670 May 09 07:53:28 zero volumio[4662]: } May 09 07:53:28 zero volumio[4662]: verbose: [yt-cast-receiver] (YouTube) Obtained lounge token: May 09 07:53:28 zero volumio[4662]: { May 09 07:53:28 zero volumio[4662]: screenId: '79t767efofk6v1tep43qs0h93s', May 09 07:53:28 zero volumio[4662]: refreshIntervalInMillis: 1123200000, May 09 07:53:28 zero volumio[4662]: remoteRefreshIntervalMs: 79200000, May 09 07:53:28 zero volumio[4662]: refreshIntervalMs: 1123200000, May 09 07:53:28 zero volumio[4662]: loungeTokenLifespanMs: 1209600000, May 09 07:53:28 zero volumio[4662]: loungeToken: 'AGdO5p_czwVSChOA6iTYkeVRl7wMCRqUk4-qZvKnG8lFDUb7jFZk2TfWXYdEqezqZJRknvvQH6BvRa3aCyLyxNDRO9ErBvMCnc35MN0G6YO_Wgr1CTvZzJU', May 09 07:53:28 zero volumio[4662]: remoteRefreshIntervalInMillis: 79200000, May 09 07:53:28 zero volumio[4662]: expiration: 1747918408879 May 09 07:53:28 zero volumio[4662]: } May 09 07:53:29 zero volumio[4662]: verbose: [yt-cast-receiver] (YouTube Music) Received messages for establishing session: May 09 07:53:29 zero volumio[4662]: [ May 09 07:53:29 zero volumio[4662]: Message { AID: 0, name: 'c', payload: [ '512B6F2FEC289718', '', 8 ] }, May 09 07:53:29 zero volumio[4662]: Message { May 09 07:53:29 zero volumio[4662]: AID: 1, May 09 07:53:29 zero volumio[4662]: name: 'S', May 09 07:53:29 zero volumio[4662]: payload: 'VerXOmc0nY4wsLP5Z0RNxJPjOcFplsem8UqE2qWYaZg' May 09 07:53:29 zero volumio[4662]: }, May 09 07:53:29 zero volumio[4662]: Message { May 09 07:53:29 zero volumio[4662]: AID: 2, May 09 07:53:29 zero volumio[4662]: name: 'loungeStatus', May 09 07:53:29 zero volumio[4662]: payload: { May 09 07:53:29 zero volumio[4662]: queueId: 'RQPfqNTdeTe5s8nUWjUFpQcJ6AAFI', May 09 07:53:29 zero volumio[4662]: devices: '[{"app":"ytcr","capabilities":"dsp,mic,dpa,ntb","clientName":"unknown","experiments":"","name":"YouTube on Zero","theme":"m","id":"d23b40c7-a779-4ac5-8fa3-143b46148b7c","type":"LOUNGE_SCREEN","hasCc":"true","deviceInfo":"{\\"year\\":0,\\"os\\":\\"Windows\\",\\"osVersion\\":\\"10.0\\",\\"chipset\\":\\"\\",\\"clientName\\":\\"TVHTML5\\",\\"dialAdditionalDataSupportLevel\\":\\"unsupported\\",\\"mdxDialServerType\\":\\"MDX_DIAL_SERVER_TYPE_UNKNOWN\\",\\"brand\\":\\"Generic\\",\\"model\\":\\"SmartTV\\"}","receiverIdentityMatchStatus":"IS_RECEIVER"}]', May 09 07:53:29 zero volumio[4662]: connectionEventDetails: '{"deviceId":"d23b40c7-a779-4ac5-8fa3-143b46148b7c"}' May 09 07:53:29 zero volumio[4662]: } May 09 07:53:29 zero volumio[4662]: }, May 09 07:53:29 zero volumio[4662]: Message { AID: 3, name: 'getNowPlaying', payload: {} }, May 09 07:53:29 zero volumio[4662]: Message { AID: 4, name: 'getDiscoveryDeviceId', payload: {} } May 09 07:53:29 zero volumio[4662]: ] May 09 07:53:29 zero volumio[4662]: verbose: [yt-cast-receiver] Connecting to RPC URL: https://www.youtube.com/api/lounge/bc/bind?AID=3&CI=0&RID=rpc&SID=512B6F2FEC289718&TYPE=xmlhttp&VER=8&app=ytcr&capabilities=dsp%2Cmic%2Cdpa%2Cntb&cst=m&device=LOUNGE_SCREEN&gsessionid=VerXOmc0nY4wsLP5Z0RNxJPjOcFplsem8UqE2qWYaZg&id=d23b40c7-a779-4ac5-8fa3-143b46148b7c&loungeIdToken=AGdO5p_dE0yox7LQTkc1qEBWaiIbWPR8AO3TlvrKpX4mU_dMa8dfsBC1k6ACOxdpavYDkhrFDwXgK-UjApo6kRN9ISVVemB0kpFmZ0rsq9Qc_58erjiFjFk&mdxVersion=2&name=YouTube%20on%20Zero&obfuscatedGaiaId=&t=1&theme=m&v=2&zx=1a5e03a8a729 May 09 07:53:29 zero volumio[4662]: verbose: [yt-cast-receiver] (YouTube Music) Received messages for establishing session: May 09 07:53:29 zero volumio[4662]: [ May 09 07:53:29 zero volumio[4662]: Message { AID: 0, name: 'c', payload: [ '70CAA724AE0A1EB8', '', 8 ] }, May 09 07:53:29 zero volumio[4662]: Message { May 09 07:53:29 zero volumio[4662]: AID: 1, May 09 07:53:29 zero volumio[4662]: name: 'S', May 09 07:53:29 zero volumio[4662]: payload: 'D5bRKNodeIps_zW-KAi8SB2Lw-aqd6L-xVH2m-T6sxw' May 09 07:53:29 zero volumio[4662]: }, May 09 07:53:29 zero volumio[4662]: Message { May 09 07:53:29 zero volumio[4662]: AID: 2, May 09 07:53:29 zero volumio[4662]: name: 'loungeStatus', May 09 07:53:29 zero volumio[4662]: payload: { May 09 07:53:29 zero volumio[4662]: queueId: 'RQPfqNTdeTe5s8nUWjUFpQcJ6AAFI', May 09 07:53:29 zero volumio[4662]: devices: '[{"app":"ytcr","capabilities":"dsp,mic,dpa,ntb","clientName":"unknown","experiments":"","name":"YouTube on Zero","theme":"m","id":"d23b40c7-a779-4ac5-8fa3-143b46148b7c","type":"LOUNGE_SCREEN","hasCc":"true","deviceInfo":"{\\"year\\":0,\\"os\\":\\"Windows\\",\\"osVersion\\":\\"10.0\\",\\"chipset\\":\\"\\",\\"clientName\\":\\"TVHTML5\\",\\"dialAdditionalDataSupportLevel\\":\\"unsupported\\",\\"mdxDialServerType\\":\\"MDX_DIAL_SERVER_TYPE_UNKNOWN\\",\\"brand\\":\\"Generic\\",\\"model\\":\\"SmartTV\\"}","receiverIdentityMatchStatus":"IS_RECEIVER"}]', May 09 07:53:29 zero volumio[4662]: connectionEventDetails: '{"deviceId":"d23b40c7-a779-4ac5-8fa3-143b46148b7c"}' May 09 07:53:29 zero volumio[4662]: } May 09 07:53:29 zero volumio[4662]: }, May 09 07:53:29 zero volumio[4662]: Message { AID: 3, name: 'getNowPlaying', payload: {} }, May 09 07:53:29 zero volumio[4662]: Message { AID: 4, name: 'getDiscoveryDeviceId', payload: {} } May 09 07:53:29 zero volumio[4662]: ] May 09 07:53:29 zero volumio[4662]: verbose: [yt-cast-receiver] Connecting to RPC URL: https://www.youtube.com/api/lounge/bc/bind?AID=3&CI=0&RID=rpc&SID=70CAA724AE0A1EB8&TYPE=xmlhttp&VER=8&app=ytcr&capabilities=dsp%2Cmic%2Cdpa%2Cntb&cst=m&device=LOUNGE_SCREEN&gsessionid=D5bRKNodeIps_zW-KAi8SB2Lw-aqd6L-xVH2m-T6sxw&id=d23b40c7-a779-4ac5-8fa3-143b46148b7c&loungeIdToken=AGdO5p_dE0yox7LQTkc1qEBWaiIbWPR8AO3TlvrKpX4mU_dMa8dfsBC1k6ACOxdpavYDkhrFDwXgK-UjApo6kRN9ISVVemB0kpFmZ0rsq9Qc_58erjiFjFk&mdxVersion=2&name=YouTube%20on%20Zero&obfuscatedGaiaId=&t=1&theme=m&v=2&zx=aaf34e70a8de May 09 07:53:29 zero volumio[4662]: verbose: [yt-cast-receiver] (YouTube) Received messages for establishing session: May 09 07:53:29 zero volumio[4662]: [ May 09 07:53:29 zero volumio[4662]: Message { AID: 0, name: 'c', payload: [ 'F273BFB63014FE28', '', 8 ] }, May 09 07:53:29 zero volumio[4662]: Message { May 09 07:53:29 zero volumio[4662]: AID: 1, May 09 07:53:29 zero volumio[4662]: name: 'S', May 09 07:53:29 zero volumio[4662]: payload: 'B9BJtcgYW6__Vv4DVKuoK6dhvMzx8aHZDbsbKbtM6Ts' May 09 07:53:29 zero volumio[4662]: }, May 09 07:53:29 zero volumio[4662]: Message { May 09 07:53:29 zero volumio[4662]: AID: 2, May 09 07:53:29 zero volumio[4662]: name: 'loungeStatus', May 09 07:53:29 zero volumio[4662]: payload: { May 09 07:53:29 zero volumio[4662]: queueId: 'RQJR_1rmBJ9K5LRBF9gPjKVcKqm2A', May 09 07:53:29 zero volumio[4662]: devices: '[{"app":"ytcr","capabilities":"dsp,mic,dpa,ntb,que,mus","clientName":"unknown","experiments":"","name":"YouTube on Zero","theme":"cl","id":"b09c8eaa-61a9-47d1-8655-fee1bad2fba5","type":"LOUNGE_SCREEN","hasCc":"true","deviceInfo":"{\\"year\\":0,\\"os\\":\\"Windows\\",\\"osVersion\\":\\"10.0\\",\\"chipset\\":\\"\\",\\"clientName\\":\\"TVHTML5\\",\\"dialAdditionalDataSupportLevel\\":\\"unsupported\\",\\"mdxDialServerType\\":\\"MDX_DIAL_SERVER_TYPE_UNKNOWN\\",\\"brand\\":\\"Generic\\",\\"model\\":\\"SmartTV\\"}","receiverIdentityMatchStatus":"IS_RECEIVER"}]', May 09 07:53:29 zero volumio[4662]: connectionEventDetails: '{"deviceId":"b09c8eaa-61a9-47d1-8655-fee1bad2fba5"}' May 09 07:53:29 zero volumio[4662]: } May 09 07:53:29 zero volumio[4662]: }, May 09 07:53:29 zero volumio[4662]: Message { AID: 3, name: 'getNowPlaying', payload: {} }, May 09 07:53:29 zero volumio[4662]: Message { AID: 4, name: 'getDiscoveryDeviceId', payload: {} } May 09 07:53:29 zero volumio[4662]: ] May 09 07:53:29 zero volumio[4662]: verbose: [yt-cast-receiver] Connecting to RPC URL: https://www.youtube.com/api/lounge/bc/bind?AID=3&CI=0&RID=rpc&SID=F273BFB63014FE28&TYPE=xmlhttp&VER=8&app=ytcr&capabilities=dsp%2Cmic%2Cdpa%2Cntb&cst=m&device=LOUNGE_SCREEN&gsessionid=B9BJtcgYW6__Vv4DVKuoK6dhvMzx8aHZDbsbKbtM6Ts&id=b09c8eaa-61a9-47d1-8655-fee1bad2fba5&loungeIdToken=AGdO5p_czwVSChOA6iTYkeVRl7wMCRqUk4-qZvKnG8lFDUb7jFZk2TfWXYdEqezqZJRknvvQH6BvRa3aCyLyxNDRO9ErBvMCnc35MN0G6YO_Wgr1CTvZzJU&mdxVersion=2&name=YouTube%20on%20Zero&obfuscatedGaiaId=&t=1&theme=cl&v=2&zx=b323bc38fb14 May 09 07:53:29 zero volumio[4662]: verbose: [yt-cast-receiver] (YouTube) Received messages for establishing session: May 09 07:53:29 zero volumio[4662]: [ May 09 07:53:29 zero volumio[4662]: Message { AID: 0, name: 'c', payload: [ 'CC5B5BF83905E84B', '', 8 ] }, May 09 07:53:29 zero volumio[4662]: Message { May 09 07:53:29 zero volumio[4662]: AID: 1, May 09 07:53:29 zero volumio[4662]: name: 'S', May 09 07:53:29 zero volumio[4662]: payload: 'OSDjlCbadPflPG4dJKgehHfraQXZFI2CJ8H2zV__s7g' May 09 07:53:29 zero volumio[4662]: }, May 09 07:53:29 zero volumio[4662]: Message { May 09 07:53:29 zero volumio[4662]: AID: 2, May 09 07:53:29 zero volumio[4662]: name: 'loungeStatus', May 09 07:53:29 zero volumio[4662]: payload: { May 09 07:53:29 zero volumio[4662]: queueId: 'RQJR_1rmBJ9K5LRBF9gPjKVcKqm2A', May 09 07:53:29 zero volumio[4662]: devices: '[{"app":"ytcr","capabilities":"dsp,mic,dpa,ntb,que,mus","clientName":"unknown","experiments":"","name":"YouTube on Zero","theme":"cl","id":"b09c8eaa-61a9-47d1-8655-fee1bad2fba5","type":"LOUNGE_SCREEN","hasCc":"true","deviceInfo":"{\\"year\\":0,\\"os\\":\\"Windows\\",\\"osVersion\\":\\"10.0\\",\\"chipset\\":\\"\\",\\"clientName\\":\\"TVHTML5\\",\\"dialAdditionalDataSupportLevel\\":\\"unsupported\\",\\"mdxDialServerType\\":\\"MDX_DIAL_SERVER_TYPE_UNKNOWN\\",\\"brand\\":\\"Generic\\",\\"model\\":\\"SmartTV\\"}","receiverIdentityMatchStatus":"IS_RECEIVER"}]', May 09 07:53:29 zero volumio[4662]: connectionEventDetails: '{"deviceId":"b09c8eaa-61a9-47d1-8655-fee1bad2fba5"}' May 09 07:53:29 zero volumio[4662]: } May 09 07:53:29 zero volumio[4662]: }, May 09 07:53:29 zero volumio[4662]: Message { AID: 3, name: 'getNowPlaying', payload: {} }, May 09 07:53:29 zero volumio[4662]: Message { AID: 4, name: 'getDiscoveryDeviceId', payload: {} } May 09 07:53:29 zero volumio[4662]: ] May 09 07:53:29 zero volumio[4662]: verbose: [yt-cast-receiver] Connecting to RPC URL: https://www.youtube.com/api/lounge/bc/bind?AID=3&CI=0&RID=rpc&SID=CC5B5BF83905E84B&TYPE=xmlhttp&VER=8&app=ytcr&capabilities=dsp%2Cmic%2Cdpa%2Cntb&cst=m&device=LOUNGE_SCREEN&gsessionid=OSDjlCbadPflPG4dJKgehHfraQXZFI2CJ8H2zV__s7g&id=b09c8eaa-61a9-47d1-8655-fee1bad2fba5&loungeIdToken=AGdO5p_czwVSChOA6iTYkeVRl7wMCRqUk4-qZvKnG8lFDUb7jFZk2TfWXYdEqezqZJRknvvQH6BvRa3aCyLyxNDRO9ErBvMCnc35MN0G6YO_Wgr1CTvZzJU&mdxVersion=2&name=YouTube%20on%20Zero&obfuscatedGaiaId=&t=1&theme=cl&v=2&zx=afc7bf4b0bdd May 09 07:53:29 zero volumio[4662]: verbose: [yt-cast-receiver] RPC connection established. May 09 07:53:29 zero volumio[4662]: verbose: [yt-cast-receiver] (YouTube Music) Session established. May 09 07:53:29 zero volumio[4662]: verbose: [yt-cast-receiver] (YouTube Music) Saving MDX context to data store: May 09 07:53:29 zero volumio[4662]: { May 09 07:53:29 zero volumio[4662]: deviceId: 'd23b40c7-a779-4ac5-8fa3-143b46148b7c', May 09 07:53:29 zero volumio[4662]: screenId: 'qbs9eq2sphvv2o0lhh0c2f1dpp' May 09 07:53:29 zero volumio[4662]: } May 09 07:53:29 zero volumio[4662]: verbose: [yt-cast-receiver] (YouTube Music) Closing old RPC connection... May 09 07:53:29 zero volumio[4662]: (node:4662) UnhandledPromiseRejectionWarning: TypeError: Cannot read property 'removeAllListeners' of undefined May 09 07:53:29 zero volumio[4662]: at Session._Session_refreshLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:372:16) May 09 07:53:29 zero volumio[4662]: at processTicksAndRejections (internal/process/task_queues.js:93:5) May 09 07:53:29 zero volumio[4662]: (Use `node --trace-warnings ...` to show where the warning was created) May 09 07:53:29 zero volumio[4662]: (node:4662) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 5) May 09 07:53:29 zero volumio[4662]: (node:4662) [DEP0018] DeprecationWarning: Unhandled promise rejections are deprecated. In the future, promise rejections that are not handled will terminate the Node.js process with a non-zero exit code. May 09 07:53:29 zero volumio[4662]: verbose: [yt-cast-receiver] RPC connection established. May 09 07:53:29 zero volumio[4662]: verbose: [yt-cast-receiver] (YouTube Music) Session established. May 09 07:53:29 zero volumio[4662]: verbose: [yt-cast-receiver] (YouTube Music) Saving MDX context to data store: May 09 07:53:29 zero volumio[4662]: { May 09 07:53:29 zero volumio[4662]: deviceId: 'd23b40c7-a779-4ac5-8fa3-143b46148b7c', May 09 07:53:29 zero volumio[4662]: screenId: 'qbs9eq2sphvv2o0lhh0c2f1dpp' May 09 07:53:29 zero volumio[4662]: } May 09 07:53:29 zero volumio[4662]: verbose: [yt-cast-receiver] (YouTube Music) Closing old RPC connection... May 09 07:53:29 zero volumio[4662]: (node:4662) UnhandledPromiseRejectionWarning: TypeError: Cannot read property 'removeAllListeners' of undefined May 09 07:53:29 zero volumio[4662]: at Session._Session_refreshLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:372:16) May 09 07:53:29 zero volumio[4662]: at processTicksAndRejections (internal/process/task_queues.js:93:5) May 09 07:53:29 zero volumio[4662]: (node:4662) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 6) May 09 07:53:29 zero volumio[4662]: verbose: [yt-cast-receiver] RPC connection established. May 09 07:53:29 zero volumio[4662]: verbose: [yt-cast-receiver] (YouTube) Session established. May 09 07:53:29 zero volumio[4662]: verbose: [yt-cast-receiver] (YouTube) Saving MDX context to data store: May 09 07:53:29 zero volumio[4662]: { May 09 07:53:29 zero volumio[4662]: deviceId: 'b09c8eaa-61a9-47d1-8655-fee1bad2fba5', May 09 07:53:29 zero volumio[4662]: screenId: '79t767efofk6v1tep43qs0h93s' May 09 07:53:29 zero volumio[4662]: } May 09 07:53:29 zero volumio[4662]: verbose: [yt-cast-receiver] (YouTube) Closing old RPC connection... May 09 07:53:29 zero volumio[4662]: (node:4662) UnhandledPromiseRejectionWarning: TypeError: Cannot read property 'removeAllListeners' of undefined May 09 07:53:29 zero volumio[4662]: at Session._Session_refreshLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:372:16) May 09 07:53:29 zero volumio[4662]: at processTicksAndRejections (internal/process/task_queues.js:93:5) May 09 07:53:29 zero volumio[4662]: (node:4662) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 7) May 09 07:53:29 zero volumio[4662]: verbose: [yt-cast-receiver] RPC connection established. May 09 07:53:29 zero volumio[4662]: verbose: [yt-cast-receiver] (YouTube) Session established. May 09 07:53:29 zero volumio[4662]: verbose: [yt-cast-receiver] (YouTube) Saving MDX context to data store: May 09 07:53:29 zero volumio[4662]: { May 09 07:53:29 zero volumio[4662]: deviceId: 'b09c8eaa-61a9-47d1-8655-fee1bad2fba5', May 09 07:53:29 zero volumio[4662]: screenId: '79t767efofk6v1tep43qs0h93s' May 09 07:53:29 zero volumio[4662]: } May 09 07:53:29 zero volumio[4662]: verbose: [yt-cast-receiver] (YouTube) Closing old RPC connection... May 09 07:53:29 zero volumio[4662]: (node:4662) UnhandledPromiseRejectionWarning: TypeError: Cannot read property 'removeAllListeners' of undefined May 09 07:53:29 zero volumio[4662]: at Session._Session_refreshLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:372:16) May 09 07:53:29 zero volumio[4662]: at processTicksAndRejections (internal/process/task_queues.js:93:5) May 09 07:53:29 zero volumio[4662]: (node:4662) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 8) May 09 07:53:31 zero volumio[4662]: info: Getting Spotify volume May 09 07:53:31 zero volumio[4662]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 May 09 07:53:31 zero volumio[4662]: info: CoreCommandRouter::volumioGetState May 09 07:53:31 zero volumio[4662]: info: CorePlayQueue::getTrack 0 May 09 07:53:31 zero volumio[4662]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30 May 09 07:53:31 zero volumio[4662]: SPOTIFY: SPOTIFY VOLUME undefined May 09 07:53:31 zero volumio[4662]: SPOTIFY: VOLUMIO VOLUME 30 May 09 07:53:31 zero volumio[4662]: info: Aligning Spotify Volume to Volumio Volume May 09 07:53:31 zero volumio[4662]: info: CoreCommandRouter::volumioGetState May 09 07:53:31 zero volumio[4662]: info: CorePlayQueue::getTrack 0 May 09 07:53:31 zero volumio[4662]: info: Setting Spotify Volume from Volumio: 30 May 09 07:53:32 zero volumio[4662]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory May 09 07:53:33 zero volumio[4662]: SPOTIFY: SETTING SPOTIFY VOLUME 30 May 09 07:53:33 zero volumio[4662]: info: Sending Spotify command with payload to local API: /player/volume May 09 07:53:35 zero volumio[4662]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium May 09 07:53:35 zero volumio[4662]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium May 09 07:53:35 zero volumio[4662]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium May 09 07:53:35 zero volumio[4662]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium May 09 07:53:35 zero volumio[4662]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium May 09 07:53:35 zero volumio[4662]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium May 09 07:53:35 zero volumio[4662]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium May 09 07:53:35 zero volumio[4662]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium May 09 07:53:35 zero volumio[4662]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium May 09 07:53:35 zero volumio[4662]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium May 09 07:53:35 zero volumio[4662]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium May 09 07:53:35 zero volumio[4662]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar May 09 07:53:35 zero volumio[4662]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar May 09 07:53:35 zero volumio[4662]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar May 09 07:53:35 zero volumio[4662]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar May 09 07:53:35 zero volumio[4662]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar May 09 07:53:35 zero volumio[4662]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar May 09 07:53:35 zero volumio[4662]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar May 09 07:53:35 zero volumio[4662]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar May 09 07:53:35 zero volumio[4662]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar May 09 07:53:35 zero volumio[4662]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar May 09 07:53:35 zero volumio[4662]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar May 09 07:53:35 zero volumio[4662]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso May 09 07:53:35 zero volumio[4662]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso May 09 07:53:35 zero volumio[4662]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso May 09 07:53:35 zero volumio[4662]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso May 09 07:53:35 zero volumio[4662]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso May 09 07:53:35 zero volumio[4662]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso May 09 07:53:35 zero volumio[4662]: info: Adding plugin bluetooth to MyMusic Plugins May 09 07:53:35 zero volumio[4662]: info: Adding plugin multiroom to MyMusic Plugins May 09 07:53:35 zero volumio[4662]: info: Adding plugin metavolumio to MyMusic Plugins May 09 07:53:35 zero volumio[4662]: info: Adding plugin cd_controller to MyMusic Plugins May 09 07:53:35 zero volumio[4662]: info: Adding plugin qobuzconnect to MyMusic Plugins May 09 07:53:35 zero volumio[4662]: info: Adding plugin smart_inputs to MyMusic Plugins May 09 07:53:35 zero volumio[4662]: info: Adding plugin tidalconnect to MyMusic Plugins May 09 07:53:35 zero volumio[4662]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... May 09 07:53:38 zero volumio[4662]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded May 09 07:53:38 zero volumio[4662]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio May 09 07:53:38 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 07:53:38 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 07:53:38 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 07:53:38 zero volumio[4662]: info: Starting MyVolumio Remote Streaming Endpoints May 09 07:53:38 zero volumio[4662]: info: MyVolumio login type: Token May 09 07:53:38 zero volumio[4662]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started May 09 07:53:38 zero volumio[4662]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... May 09 07:53:40 zero volumio[4662]: info: Starting Streaming Service Transparent Proxy May 09 07:53:40 zero volumio[4662]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded May 09 07:53:40 zero volumio[4662]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services May 09 07:53:40 zero volumio[4662]: info: Streaming services startup May 09 07:53:40 zero volumio[4662]: info: Starting Streaming Daemon May 09 07:53:40 zero volumio[4662]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started May 09 07:53:40 zero sudo[5040]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 09 07:53:40 zero sudo[5040]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 07:53:40 zero sudo[5040]: pam_unix(sudo:session): session closed for user root May 09 07:53:41 zero volumio[4662]: error: Cannot start Volumio Streaming Daemon May 09 07:53:41 zero volumio[4662]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 09 07:53:41 zero volumio[4662]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 09 07:53:41 zero volumio[4662]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory May 09 07:53:41 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 07:53:41 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 09 07:53:41 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 07:53:41 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 09 07:53:41 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 09 07:53:41 zero volumio-remote-updater[507]: No test mode May 09 07:53:41 zero volumio-remote-updater[507]: No alpha test mode May 09 07:53:41 zero volumio[4662]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN May 09 07:53:41 zero volumio[4662]: info: CoreCommandRouter::volumioPlay May 09 07:53:41 zero volumio[4662]: info: CoreStateMachine::play index undefined May 09 07:53:41 zero volumio[4662]: info: CoreStateMachine::setConsumeUpdateService undefined May 09 07:53:41 zero volumio[4662]: info: CorePlayQueue::getTrack 0 May 09 07:53:41 zero volumio[4662]: info: CoreStateMachine::startPlaybackTimer May 09 07:53:41 zero volumio[4662]: info: CorePlayQueue::getTrack 0 May 09 07:53:41 zero volumio[4662]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/Spotify 2/Oldies spotify/Escape (The Pina Colada Song).mp3 May 09 07:53:41 zero volumio[4662]: verbose: ControllerMpd::sendMpdCommand stop May 09 07:53:41 zero volumio[4662]: info: sendMpdCommand stop took 5 milliseconds May 09 07:53:41 zero volumio[4662]: verbose: ControllerMpd::sendMpdCommand clear May 09 07:53:41 zero volumio[4662]: info: May 09 07:53:41 zero volumio[4662]: ---------------------------- MPD announces system playlist update May 09 07:53:41 zero volumio[4662]: info: Ignoring MPD Status Update May 09 07:53:41 zero volumio[4662]: info: sendMpdCommand clear took 4 milliseconds May 09 07:53:41 zero volumio[4662]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/Spotify 2/Oldies spotify/Escape (The Pina Colada Song).mp3" May 09 07:53:41 zero volumio[4662]: info: May 09 07:53:41 zero volumio[4662]: ---------------------------- MPD announces system playlist update May 09 07:53:41 zero volumio[4662]: info: Ignoring MPD Status Update May 09 07:53:41 zero volumio[4662]: info: May 09 07:53:41 zero volumio[4662]: ---------------------------- MPD announces system playlist update May 09 07:53:41 zero volumio[4662]: info: Ignoring MPD Status Update May 09 07:53:41 zero volumio[4662]: info: May 09 07:53:41 zero volumio[4662]: ---------------------------- MPD announces system playlist update May 09 07:53:41 zero volumio[4662]: info: Ignoring MPD Status Update May 09 07:53:41 zero volumio[4662]: error: updateQueue error: null May 09 07:53:41 zero volumio[4662]: info: May 09 07:53:41 zero volumio[4662]: ---------------------------- MPD announces system playlist update May 09 07:53:41 zero volumio[4662]: info: Ignoring MPD Status Update May 09 07:53:41 zero volumio[4662]: info: ------------------------------ 15ms May 09 07:53:41 zero volumio[4662]: info: sendMpdCommand add "INTERNAL/Spotify 2/Oldies spotify/Escape (The Pina Colada Song).mp3" took 12 milliseconds May 09 07:53:41 zero volumio[4662]: info: ------------------------------ 11ms May 09 07:53:41 zero volumio[4662]: info: ------------------------------ 9ms May 09 07:53:41 zero volumio[4662]: verbose: ControllerMpd::sendMpdCommand play May 09 07:53:41 zero volumio[4662]: info: May 09 07:53:41 zero volumio[4662]: ---------------------------- MPD announces system playlist update May 09 07:53:41 zero volumio[4662]: info: Ignoring MPD Status Update May 09 07:53:41 zero volumio[4662]: info: May 09 07:53:41 zero volumio[4662]: ---------------------------- MPD announces system playlist update May 09 07:53:41 zero volumio[4662]: info: Ignoring MPD Status Update May 09 07:53:41 zero volumio[4662]: info: May 09 07:53:41 zero volumio[4662]: ---------------------------- MPD announces system playlist update May 09 07:53:41 zero volumio[4662]: info: Ignoring MPD Status Update May 09 07:53:41 zero volumio[4662]: info: ------------------------------ 16ms May 09 07:53:41 zero volumio[4662]: info: sendMpdCommand play took 12 milliseconds May 09 07:53:41 zero volumio[4662]: info: ------------------------------ 11ms May 09 07:53:41 zero volumio[4662]: info: ------------------------------ 10ms May 09 07:53:41 zero volumio[4662]: info: ------------------------------ 7ms May 09 07:53:41 zero volumio[4662]: info: May 09 07:53:41 zero volumio[4662]: ---------------------------- MPD announces state update: player May 09 07:53:41 zero volumio[4662]: info: ControllerMpd::getState May 09 07:53:41 zero volumio[4662]: verbose: ControllerMpd::sendMpdCommand status May 09 07:53:41 zero volumio[4662]: info: May 09 07:53:41 zero volumio[4662]: ---------------------------- MPD announces state update: player May 09 07:53:41 zero volumio[4662]: info: ControllerMpd::getState May 09 07:53:41 zero volumio[4662]: verbose: ControllerMpd::sendMpdCommand status May 09 07:53:41 zero volumio[4662]: info: May 09 07:53:41 zero volumio[4662]: ---------------------------- MPD announces state update: player May 09 07:53:41 zero volumio[4662]: info: ControllerMpd::getState May 09 07:53:41 zero volumio[4662]: verbose: ControllerMpd::sendMpdCommand status May 09 07:53:41 zero volumio[4662]: info: May 09 07:53:41 zero volumio[4662]: ---------------------------- MPD announces state update: player May 09 07:53:41 zero volumio[4662]: info: ControllerMpd::getState May 09 07:53:41 zero volumio[4662]: verbose: ControllerMpd::sendMpdCommand status May 09 07:53:41 zero volumio[4662]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 09 07:53:41 zero volumio[4662]: info: FusionDsp - ---- read samplerate from file: 44100 May 09 07:53:41 zero volumio[4662]: info: camilladsp stopping service pid 4937... May 09 07:53:41 zero volumio[4662]: info: camilladsp service terminated, instance 1 May 09 07:53:41 zero volumio[4662]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 09 07:53:41 zero volumio[4662]: info: camilladsp service started and running in background, instance 1 May 09 07:53:41 zero volumio[4662]: info: May 09 07:53:41 zero volumio[4662]: ---------------------------- MPD announces state update: player May 09 07:53:41 zero volumio[4662]: info: sendMpdCommand status took 139 milliseconds May 09 07:53:41 zero volumio[4662]: info: ControllerMpd::getState May 09 07:53:41 zero volumio[4662]: verbose: ControllerMpd::sendMpdCommand status May 09 07:53:41 zero volumio[4662]: info: sendMpdCommand status took 131 milliseconds May 09 07:53:41 zero volumio[4662]: info: sendMpdCommand status took 128 milliseconds May 09 07:53:41 zero volumio[4662]: info: sendMpdCommand status took 126 milliseconds May 09 07:53:41 zero volumio[4662]: verbose: ControllerMpd::parseState May 09 07:53:41 zero volumio[4662]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 07:53:41 zero volumio[4662]: verbose: ControllerMpd::parseState May 09 07:53:41 zero volumio[4662]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 07:53:41 zero volumio[4662]: verbose: ControllerMpd::parseState May 09 07:53:41 zero volumio[4662]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 07:53:41 zero volumio[4662]: verbose: ControllerMpd::parseState May 09 07:53:41 zero volumio[4662]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 07:53:41 zero volumio[4662]: info: May 09 07:53:41 zero volumio[4662]: ---------------------------- MPD announces state update: player May 09 07:53:41 zero volumio[4662]: info: ControllerMpd::getState May 09 07:53:41 zero volumio[4662]: verbose: ControllerMpd::sendMpdCommand status May 09 07:53:41 zero volumio[4662]: info: May 09 07:53:41 zero volumio[4662]: ---------------------------- MPD announces state update: player May 09 07:53:41 zero volumio[4662]: info: ControllerMpd::getState May 09 07:53:41 zero volumio[4662]: verbose: ControllerMpd::sendMpdCommand status May 09 07:53:41 zero volumio[4662]: info: May 09 07:53:41 zero volumio[4662]: ---------------------------- MPD announces state update: player May 09 07:53:41 zero volumio[4662]: info: ControllerMpd::getState May 09 07:53:41 zero volumio[4662]: verbose: ControllerMpd::sendMpdCommand status May 09 07:53:41 zero volumio[4662]: info: sendMpdCommand status took 23 milliseconds May 09 07:53:41 zero volumio[4662]: info: sendMpdCommand playlistinfo took 18 milliseconds May 09 07:53:41 zero volumio[4662]: info: sendMpdCommand playlistinfo took 17 milliseconds May 09 07:53:41 zero volumio[4662]: info: sendMpdCommand playlistinfo took 17 milliseconds May 09 07:53:41 zero volumio[4662]: info: sendMpdCommand playlistinfo took 16 milliseconds May 09 07:53:41 zero volumio[4662]: info: sendMpdCommand status took 13 milliseconds May 09 07:53:41 zero volumio[4662]: info: sendMpdCommand status took 12 milliseconds May 09 07:53:41 zero volumio[4662]: info: sendMpdCommand status took 9 milliseconds May 09 07:53:41 zero volumio[4662]: verbose: ControllerMpd::parseState May 09 07:53:41 zero volumio[4662]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 07:53:41 zero volumio[4662]: verbose: ControllerMpd::parseTrackInfo May 09 07:53:41 zero volumio[4662]: verbose: ControllerMpd::parseTrackInfo May 09 07:53:41 zero volumio[4662]: verbose: ControllerMpd::parseState May 09 07:53:41 zero volumio[4662]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 07:53:41 zero volumio[4662]: verbose: ControllerMpd::parseState May 09 07:53:41 zero volumio[4662]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 07:53:41 zero volumio[4662]: verbose: ControllerMpd::parseState May 09 07:53:41 zero volumio[4662]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 07:53:41 zero volumio[4662]: info: ControllerMpd::pushState May 09 07:53:41 zero volumio[4662]: info: CoreCommandRouter::servicePushState May 09 07:53:41 zero volumio[4662]: info: CorePlayQueue::getTrack 0 May 09 07:53:41 zero volumio[4662]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":276,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Escape (The Pina Colada Song)","artist":"Rupert Holmes","album":"'70s Pop #1's","uri":"INTERNAL/Spotify 2/Oldies spotify/Escape (The Pina Colada Song).mp3","trackType":"mp3"} May 09 07:53:41 zero volumio[4662]: verbose: CURRENT POSITION 0 May 09 07:53:41 zero volumio[4662]: info: CoreStateMachine::syncState stateService play May 09 07:53:41 zero volumio[4662]: info: CoreStateMachine::syncState currentStatus stop May 09 07:53:41 zero volumio[4662]: info: ControllerMpd::pushState May 09 07:53:41 zero volumio[4662]: info: CoreCommandRouter::servicePushState May 09 07:53:41 zero volumio[4662]: info: CorePlayQueue::getTrack 0 May 09 07:53:41 zero volumio[4662]: verbose: STATE SERVICE {"status":"play","position":0,"seek":605,"duration":276,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Escape (The Pina Colada Song)","artist":"Rupert Holmes","album":"'70s Pop #1's","uri":"INTERNAL/Spotify 2/Oldies spotify/Escape (The Pina Colada Song).mp3","trackType":"mp3"} May 09 07:53:41 zero volumio[4662]: verbose: CURRENT POSITION 0 May 09 07:53:41 zero volumio[4662]: info: CoreStateMachine::syncState stateService play May 09 07:53:41 zero volumio[4662]: info: CoreStateMachine::syncState currentStatus play May 09 07:53:41 zero volumio[4662]: info: Received an update from plugin. extracting info from payload May 09 07:53:41 zero volumio[4662]: info: CoreStateMachine::pushState May 09 07:53:41 zero volumio[4662]: info: CorePlayQueue::getTrack 0 May 09 07:53:41 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 07:53:41 zero volumio[4662]: info: CoreCommandRouter::volumioPushState May 09 07:53:41 zero volumio[4662]: info: CoreStateMachine::pushState May 09 07:53:41 zero volumio[4662]: info: CorePlayQueue::getTrack 0 May 09 07:53:41 zero volumio[4662]: info: CoreCommandRouter::volumioPushState May 09 07:53:41 zero volumio[4662]: info: ControllerMpd::pushState May 09 07:53:41 zero volumio[4662]: info: CoreCommandRouter::servicePushState May 09 07:53:41 zero volumio[4662]: info: CorePlayQueue::getTrack 0 May 09 07:53:41 zero volumio[4662]: verbose: STATE SERVICE {"status":"play","position":0,"seek":605,"duration":276,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Escape (The Pina Colada Song)","artist":"Rupert Holmes","album":"'70s Pop #1's","uri":"INTERNAL/Spotify 2/Oldies spotify/Escape (The Pina Colada Song).mp3","trackType":"mp3"} May 09 07:53:41 zero volumio[4662]: verbose: CURRENT POSITION 0 May 09 07:53:41 zero volumio[4662]: info: CoreStateMachine::syncState stateService play May 09 07:53:41 zero volumio[4662]: info: CoreStateMachine::syncState currentStatus play May 09 07:53:41 zero volumio[4662]: info: Received an update from plugin. extracting info from payload May 09 07:53:41 zero volumio[4662]: info: CoreStateMachine::pushState May 09 07:53:41 zero volumio[4662]: info: CorePlayQueue::getTrack 0 May 09 07:53:41 zero volumio[4662]: info: CoreCommandRouter::volumioPushState May 09 07:53:41 zero volumio[4662]: info: CoreStateMachine::pushState May 09 07:53:41 zero volumio[4662]: info: CorePlayQueue::getTrack 0 May 09 07:53:41 zero volumio[4662]: info: CoreCommandRouter::volumioPushState May 09 07:53:41 zero volumio[4662]: info: ControllerMpd::pushState May 09 07:53:41 zero volumio[4662]: info: CoreCommandRouter::servicePushState May 09 07:53:41 zero volumio[4662]: info: CorePlayQueue::getTrack 0 May 09 07:53:41 zero volumio[4662]: verbose: STATE SERVICE {"status":"play","position":0,"seek":731,"duration":276,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Escape (The Pina Colada Song)","artist":"Rupert Holmes","album":"'70s Pop #1's","uri":"INTERNAL/Spotify 2/Oldies spotify/Escape (The Pina Colada Song).mp3","trackType":"mp3"} May 09 07:53:41 zero volumio[4662]: verbose: CURRENT POSITION 0 May 09 07:53:41 zero volumio[4662]: info: CoreStateMachine::syncState stateService play May 09 07:53:41 zero volumio[4662]: info: CoreStateMachine::syncState currentStatus play May 09 07:53:41 zero volumio[4662]: info: Received an update from plugin. extracting info from payload May 09 07:53:41 zero volumio[4662]: info: CoreStateMachine::pushState May 09 07:53:41 zero volumio[4662]: info: CorePlayQueue::getTrack 0 May 09 07:53:41 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 07:53:41 zero volumio[4662]: info: CoreCommandRouter::volumioPushState May 09 07:53:41 zero volumio[4662]: info: CoreStateMachine::pushState May 09 07:53:41 zero volumio[4662]: info: CorePlayQueue::getTrack 0 May 09 07:53:41 zero volumio[4662]: info: CoreCommandRouter::volumioPushState May 09 07:53:41 zero volumio[4662]: info: ------------------------------ 232ms May 09 07:53:41 zero volumio[4662]: info: ------------------------------ 295ms May 09 07:53:41 zero volumio[4662]: info: ------------------------------ 285ms May 09 07:53:41 zero volumio[4662]: info: ------------------------------ 284ms May 09 07:53:41 zero volumio[4662]: info: sendMpdCommand playlistinfo took 186 milliseconds May 09 07:53:41 zero volumio[4662]: info: sendMpdCommand playlistinfo took 182 milliseconds May 09 07:53:41 zero volumio[4662]: info: sendMpdCommand playlistinfo took 182 milliseconds May 09 07:53:41 zero volumio[4662]: info: sendMpdCommand playlistinfo took 181 milliseconds May 09 07:53:41 zero volumio[4662]: verbose: ControllerMpd::parseTrackInfo May 09 07:53:41 zero volumio[4662]: verbose: ControllerMpd::parseTrackInfo May 09 07:53:41 zero volumio[4662]: verbose: ControllerMpd::parseTrackInfo May 09 07:53:41 zero volumio[4662]: verbose: ControllerMpd::parseTrackInfo May 09 07:53:41 zero volumio[4662]: info: ControllerMpd::pushState May 09 07:53:41 zero volumio[4662]: info: CoreCommandRouter::servicePushState May 09 07:53:41 zero volumio[4662]: info: CorePlayQueue::getTrack 0 May 09 07:53:41 zero volumio[4662]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1485,"duration":276,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Escape (The Pina Colada Song)","artist":"Rupert Holmes","album":"'70s Pop #1's","uri":"INTERNAL/Spotify 2/Oldies spotify/Escape (The Pina Colada Song).mp3","trackType":"mp3"} May 09 07:53:41 zero volumio[4662]: verbose: CURRENT POSITION 0 May 09 07:53:41 zero volumio[4662]: info: CoreStateMachine::syncState stateService play May 09 07:53:41 zero volumio[4662]: info: CoreStateMachine::syncState currentStatus play May 09 07:53:41 zero volumio[4662]: info: Received an update from plugin. extracting info from payload May 09 07:53:41 zero volumio[4662]: info: CoreStateMachine::pushState May 09 07:53:41 zero volumio[4662]: info: CorePlayQueue::getTrack 0 May 09 07:53:41 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 07:53:41 zero volumio[4662]: info: CoreCommandRouter::volumioPushState May 09 07:53:41 zero volumio[4662]: info: CoreStateMachine::pushState May 09 07:53:41 zero volumio[4662]: info: CorePlayQueue::getTrack 0 May 09 07:53:41 zero volumio[4662]: info: CoreCommandRouter::volumioPushState May 09 07:53:41 zero volumio[4662]: info: ControllerMpd::pushState May 09 07:53:41 zero volumio[4662]: info: CoreCommandRouter::servicePushState May 09 07:53:41 zero volumio[4662]: info: CorePlayQueue::getTrack 0 May 09 07:53:41 zero volumio[4662]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1485,"duration":276,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Escape (The Pina Colada Song)","artist":"Rupert Holmes","album":"'70s Pop #1's","uri":"INTERNAL/Spotify 2/Oldies spotify/Escape (The Pina Colada Song).mp3","trackType":"mp3"} May 09 07:53:41 zero volumio[4662]: verbose: CURRENT POSITION 0 May 09 07:53:41 zero volumio[4662]: info: CoreStateMachine::syncState stateService play May 09 07:53:41 zero volumio[4662]: info: CoreStateMachine::syncState currentStatus play May 09 07:53:41 zero volumio[4662]: info: Received an update from plugin. extracting info from payload May 09 07:53:41 zero volumio[4662]: info: CoreStateMachine::pushState May 09 07:53:41 zero volumio[4662]: info: CorePlayQueue::getTrack 0 May 09 07:53:41 zero volumio[4662]: info: CoreCommandRouter::volumioPushState May 09 07:53:41 zero volumio[4662]: info: CoreStateMachine::pushState May 09 07:53:41 zero volumio[4662]: info: CorePlayQueue::getTrack 0 May 09 07:53:41 zero volumio[4662]: info: CoreCommandRouter::volumioPushState May 09 07:53:41 zero volumio[4662]: info: ControllerMpd::pushState May 09 07:53:41 zero volumio[4662]: info: CoreCommandRouter::servicePushState May 09 07:53:41 zero volumio[4662]: info: CorePlayQueue::getTrack 0 May 09 07:53:41 zero volumio[4662]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1485,"duration":276,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Escape (The Pina Colada Song)","artist":"Rupert Holmes","album":"'70s Pop #1's","uri":"INTERNAL/Spotify 2/Oldies spotify/Escape (The Pina Colada Song).mp3","trackType":"mp3"} May 09 07:53:41 zero volumio[4662]: verbose: CURRENT POSITION 0 May 09 07:53:41 zero volumio[4662]: info: CoreStateMachine::syncState stateService play May 09 07:53:41 zero volumio[4662]: info: CoreStateMachine::syncState currentStatus play May 09 07:53:41 zero volumio[4662]: info: Received an update from plugin. extracting info from payload May 09 07:53:41 zero volumio[4662]: info: CoreStateMachine::pushState May 09 07:53:41 zero volumio[4662]: info: CorePlayQueue::getTrack 0 May 09 07:53:41 zero volumio[4662]: info: CoreCommandRouter::volumioPushState May 09 07:53:41 zero volumio[4662]: info: CoreStateMachine::pushState May 09 07:53:41 zero volumio[4662]: info: CorePlayQueue::getTrack 0 May 09 07:53:41 zero volumio[4662]: info: CoreCommandRouter::volumioPushState May 09 07:53:41 zero volumio[4662]: info: ControllerMpd::pushState May 09 07:53:41 zero volumio[4662]: info: CoreCommandRouter::servicePushState May 09 07:53:41 zero volumio[4662]: info: CorePlayQueue::getTrack 0 May 09 07:53:41 zero volumio[4662]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1485,"duration":276,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Escape (The Pina Colada Song)","artist":"Rupert Holmes","album":"'70s Pop #1's","uri":"INTERNAL/Spotify 2/Oldies spotify/Escape (The Pina Colada Song).mp3","trackType":"mp3"} May 09 07:53:41 zero volumio[4662]: verbose: CURRENT POSITION 0 May 09 07:53:41 zero volumio[4662]: info: CoreStateMachine::syncState stateService play May 09 07:53:41 zero volumio[4662]: info: CoreStateMachine::syncState currentStatus play May 09 07:53:41 zero volumio[4662]: info: Received an update from plugin. extracting info from payload May 09 07:53:41 zero volumio[4662]: info: CoreStateMachine::pushState May 09 07:53:41 zero volumio[4662]: info: CorePlayQueue::getTrack 0 May 09 07:53:41 zero volumio[4662]: info: CoreCommandRouter::volumioPushState May 09 07:53:41 zero volumio[4662]: info: CoreStateMachine::pushState May 09 07:53:41 zero volumio[4662]: info: CorePlayQueue::getTrack 0 May 09 07:53:41 zero volumio[4662]: info: CoreCommandRouter::volumioPushState May 09 07:53:41 zero volumio[4662]: info: ------------------------------ 337ms May 09 07:53:41 zero volumio[4662]: info: ------------------------------ 324ms May 09 07:53:41 zero volumio[4662]: info: ------------------------------ 322ms May 09 07:53:41 zero volumio[4662]: info: ------------------------------ 321ms May 09 07:53:42 zero volumio[4662]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30 May 09 07:53:42 zero volumio[4662]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30 May 09 07:53:42 zero volumio[4662]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30 May 09 07:53:42 zero volumio[4662]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30 May 09 07:53:42 zero volumio[4662]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30 May 09 07:53:42 zero volumio[4662]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30 May 09 07:53:42 zero volumio[4662]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30 May 09 07:53:42 zero volumio[4662]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30 May 09 07:53:42 zero volumio[4662]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30 May 09 07:53:42 zero volumio[4662]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30 May 09 07:53:42 zero volumio[4662]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30 May 09 07:53:42 zero volumio[4662]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30 May 09 07:53:42 zero volumio[4662]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 09 07:53:42 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 09 07:53:42 zero volumio[4662]: info: camilladsp spawned new process with pid 5064, instance 1, run: true May 09 07:53:42 zero volumio[4662]: info: MyVolumio token set successfully May 09 07:53:42 zero volumio[4662]: info: MYVOLUMIO: Adding device May 09 07:53:42 zero volumio[4662]: info: MYVOLUMIO: Evaluating Server May 09 07:53:42 zero volumio[4662]: info: MyVolumio Plan changed: premium May 09 07:53:42 zero volumio[4662]: info: [MyVolumio PluginManager] Subscribed plan changed to premium May 09 07:53:42 zero volumio[4662]: info: Removing browser output: myVolumio user plan is not superstar May 09 07:53:42 zero volumio[4662]: info: Removing audio output: May 09 07:53:42 zero volumio[4662]: info: MYVOLUMIO: Adding device May 09 07:53:42 zero volumio[4662]: info: MYVOLUMIO: Evaluating Server May 09 07:53:42 zero volumio[4662]: info: Remote config written successfully May 09 07:53:42 zero volumio[4662]: info: Starting Tunnel 1 May 09 07:53:42 zero volumio[4662]: info: Starting Tunnel Connection Checker May 09 07:53:42 zero volumio[4662]: info: Completed starting MyVolumio Plugin May 09 07:53:43 zero volumio[4662]: STREAMING PROXY: Starting server on port 3245 May 09 07:53:43 zero volumio[4662]: Node JS runtime: 14 May 09 07:53:43 zero volumio[4662]: info: MYVolumio Device enabled May 09 07:53:43 zero volumio[4662]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... May 09 07:53:43 zero volumio[4662]: info: MyVolumio status changed May 09 07:53:43 zero volumio[4662]: info: Streaming services startup May 09 07:53:43 zero volumio[4662]: info: Starting Streaming Daemon May 09 07:53:43 zero sudo[5105]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 09 07:53:43 zero sudo[5105]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 07:53:43 zero volumio[4662]: info: Setting Geolocation for MyVolumio to us1 May 09 07:53:43 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 07:53:43 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 07:53:43 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 07:53:43 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 07:53:43 zero sudo[5105]: pam_unix(sudo:session): session closed for user root May 09 07:53:43 zero volumio[4662]: error: Cannot start Volumio Streaming Daemon May 09 07:53:43 zero volumio[4662]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 09 07:53:43 zero volumio[4662]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 09 07:53:43 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid May 09 07:53:43 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 07:53:43 zero volumio[4662]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... May 09 07:53:43 zero volumio[4662]: info: [1746795223982] Starting BluetoothController May 09 07:53:44 zero volumio[4662]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... May 09 07:53:46 zero volumio[4662]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom May 09 07:53:46 zero sudo[5108]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom May 09 07:53:46 zero sudo[5108]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 07:53:46 zero sudo[5108]: pam_unix(sudo:session): session closed for user root May 09 07:53:46 zero volumio[4662]: /bin/mkdir: cannot create directory ‘/tmp/hls’: File exists May 09 07:53:46 zero volumio[4662]: info: MRS: MultiRoom plugin initialized May 09 07:53:46 zero volumio[4662]: info: MRS: STOPPING SNAPCLIENT May 09 07:53:46 zero volumio[4662]: info: MRS: Snap server stop May 09 07:53:46 zero sudo[5138]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient May 09 07:53:46 zero sudo[5138]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 07:53:46 zero volumio[4662]: info: MRS: STOPPING volumioStreaming May 09 07:53:46 zero sudo[5141]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver May 09 07:53:46 zero sudo[5141]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 07:53:46 zero sudo[5144]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming May 09 07:53:46 zero sudo[5144]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 07:53:46 zero volumio[4662]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... May 09 07:53:46 zero sudo[5147]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* May 09 07:53:46 zero sudo[5147]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 07:53:46 zero sudo[5147]: pam_unix(sudo:session): session closed for user root May 09 07:53:46 zero volumio[4662]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... May 09 07:53:46 zero volumio[4662]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... May 09 07:53:46 zero sudo[5138]: pam_unix(sudo:session): session closed for user root May 09 07:53:46 zero sudo[5144]: pam_unix(sudo:session): session closed for user root May 09 07:53:46 zero sudo[5141]: pam_unix(sudo:session): session closed for user root May 09 07:53:46 zero volumio[4662]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... May 09 07:53:46 zero volumio[4662]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... May 09 07:53:46 zero volumio[4662]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio May 09 07:53:46 zero volumio[4662]: info: [MyVolumio PluginManager] Loading plugin "tidal"... May 09 07:53:46 zero volumio[4662]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... May 09 07:53:47 zero volumio[4662]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... May 09 07:53:47 zero volumio[4662]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... May 09 07:53:47 zero volumio[4662]: info: Preparing to generate the ALSA configuration file May 09 07:53:47 zero volumio[4662]: info: Updating MyVolumio device info May 09 07:53:47 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 07:53:47 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 07:53:47 zero volumio[4662]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf May 09 07:53:47 zero volumio[4662]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 09 07:53:47 zero volumio[4662]: info: Reading ALSA contributions from plugins. May 09 07:53:47 zero volumio[4662]: info: Setting Geolocation for MyVolumio to us1 May 09 07:53:47 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 07:53:47 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 07:53:47 zero volumio[4662]: info: VolumeController::SetAlsaVolume66 May 09 07:53:47 zero volumio[4662]: info: CoreCommandRouter::volumioGetState May 09 07:53:47 zero volumio[4662]: info: CorePlayQueue::getTrack 0 May 09 07:53:47 zero volumio[4662]: info: CoreStateMachine::pushState May 09 07:53:47 zero volumio[4662]: info: CorePlayQueue::getTrack 0 May 09 07:53:47 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 07:53:47 zero volumio[4662]: info: CoreCommandRouter::volumioPushState May 09 07:53:47 zero volumio[4662]: info: MRS: Removed streaming files May 09 07:53:47 zero volumio[4662]: info: MRS: volumioStreaming STOPPED May 09 07:53:47 zero volumio[4662]: info: MRS: SNAPSERVER STOPPED May 09 07:53:47 zero volumio[4662]: info: MRS: SNAPCLIENT STOPPED May 09 07:53:47 zero volumio[4662]: info: Successfully Added MyVolumio device May 09 07:53:47 zero volumio[4662]: SPOTIFY: RECEIVED VOLUMIO VOLUME 66 May 09 07:53:47 zero volumio[4662]: SPOTIFY: SPOTIFY VOLUME 30 May 09 07:53:47 zero volumio[4662]: SPOTIFY: VOLUMIO VOLUME 66 May 09 07:53:47 zero volumio[4662]: SPOTIFY: DELTA VOLUME ENOUGH: true May 09 07:53:47 zero volumio[4662]: info: Setting Spotify Volume from Volumio: 66 May 09 07:53:47 zero volumio[4662]: info: Asound.conf file written May 09 07:53:47 zero sudo[5151]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 09 07:53:47 zero sudo[5151]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 07:53:47 zero sudo[5151]: pam_unix(sudo:session): session closed for user root May 09 07:53:47 zero volumio[4662]: info: Output device has changed, restarting MPD May 09 07:53:48 zero sudo[5156]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 09 07:53:48 zero sudo[5156]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 07:53:48 zero sudo[5156]: pam_unix(sudo:session): session closed for user root May 09 07:53:48 zero volumio[4662]: info: Output device has changed, restarting Shairport Sync May 09 07:53:48 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 07:53:48 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 07:53:48 zero sudo[5159]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 09 07:53:48 zero sudo[5159]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 07:53:48 zero systemd[1]: Stopping Music Player Daemon... May 09 07:53:48 zero volumio[4662]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 09 07:53:48 zero volumio[4662]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 09 07:53:48 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 07:53:48 zero volumio[4662]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth May 09 07:53:48 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 09 07:53:48 zero volumio[4662]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom May 09 07:53:48 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus May 09 07:53:48 zero volumio[4662]: info: MRS: Pushing multiroomSync output for this device May 09 07:53:48 zero volumio[4662]: info: MRS: Pushing multiroomSync output May 09 07:53:48 zero volumio[4662]: info: Adding audio output: May 09 07:53:48 zero volumio[4662]: info: Adding audio output: May 09 07:53:48 zero sudo[5167]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Zero May 09 07:53:48 zero sudo[5167]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 07:53:48 zero volumio[4662]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 09 07:53:48 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 07:53:48 zero sudo[5167]: pam_unix(sudo:session): session closed for user root May 09 07:53:48 zero volumio[4662]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio May 09 07:53:48 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 07:53:48 zero volumio[4662]: info: Adding METAVOLUMIO REST API Endpoints May 09 07:53:48 zero volumio[4662]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio May 09 07:53:48 zero volumio[4662]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio May 09 07:53:48 zero volumio[4662]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio May 09 07:53:48 zero volumio[4662]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio May 09 07:53:48 zero volumio[4662]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui May 09 07:53:48 zero volumio[4662]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller May 09 07:53:48 zero volumio[4662]: info: Preparing CD Folders May 09 07:53:48 zero volumio[4662]: info: Adding CD REST API Endpoints May 09 07:53:48 zero volumio[4662]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller May 09 07:53:48 zero volumio[4662]: info: Starting UDEV Watcher for CD May 09 07:53:48 zero volumio[4662]: info: Detecting CD presence with UDEV May 09 07:53:48 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices May 09 07:53:48 zero volumio[4662]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs May 09 07:53:48 zero volumio[4662]: info: Adding inputs REST Endpoints May 09 07:53:48 zero volumio[4662]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs May 09 07:53:48 zero volumio[4662]: info: Scanning Audio Inputs May 09 07:53:48 zero volumio[4662]: info: Checking against Known Cards name May 09 07:53:48 zero volumio[4662]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 09 07:53:48 zero volumio[4662]: info: [1746795228680] CoreMusicLibrary::Adding element USB Audio Device May 09 07:53:48 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 07:53:48 zero volumio[4662]: Cannot find translation for source YouTube2 May 09 07:53:48 zero volumio[4662]: Cannot find translation for source YouTube Music May 09 07:53:48 zero volumio[4662]: Cannot find translation for source 80s80s Radio May 09 07:53:48 zero volumio[4662]: Cannot find translation for source USB Audio Device May 09 07:53:48 zero volumio[4662]: info: Checking against Known Cards name May 09 07:53:48 zero volumio[4662]: info: Adding Server instance for streaming May 09 07:53:48 zero volumio[4662]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio May 09 07:53:48 zero volumio[4662]: error: Hi Res Audio Failed Login: Missing Login Data May 09 07:53:48 zero volumio[4662]: info: Adding HIGHRESAUDIO REST API Endpoints May 09 07:53:48 zero volumio[4662]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio May 09 07:53:48 zero volumio[4662]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal May 09 07:53:48 zero volumio[4662]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz May 09 07:53:48 zero volumio[4662]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect May 09 07:53:48 zero volumio[4662]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect May 09 07:53:48 zero volumio[4662]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect May 09 07:53:48 zero sudo[5175]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 09 07:53:48 zero sudo[5175]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 07:53:48 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 07:53:48 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 07:53:48 zero volumio[4662]: info: QobuzConnect: Setting Qobuz Connect card as outputdevice to 5 May 09 07:53:48 zero volumio[4662]: info: QobuzConnect: Starting Qobuz Connect socket and service May 09 07:53:48 zero systemd[1]: Stopping Volumio Tidal Connect Service... May 09 07:53:48 zero systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM May 09 07:53:48 zero systemd[1]: vtcs.service: Succeeded. May 09 07:53:48 zero systemd[1]: Stopped Volumio Tidal Connect Service. May 09 07:53:48 zero sudo[5175]: pam_unix(sudo:session): session closed for user root May 09 07:53:48 zero sudo[5181]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect May 09 07:53:48 zero sudo[5181]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 07:53:48 zero volumio[4662]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections May 09 07:53:48 zero volumio[4662]: info: Stopping AccessToken refresher cron May 09 07:53:49 zero systemd[1]: Stopping Volumio Qobuz Connect Service... May 09 07:53:49 zero volumio[4662]: info: AccessToken refresher cron started May 09 07:53:49 zero volumio[4662]: info: Stopping AccessToken refresher cron for QOBUZ May 09 07:53:49 zero volumio[4662]: info: AccessToken refresher cron started for QOBUZ May 09 07:53:49 zero volumio[4662]: info: Adding TIDAL REST API Endpoints May 09 07:53:49 zero volumio[4662]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal May 09 07:53:49 zero volumio[4662]: info: Adding QOBUZ REST API Endpoints May 09 07:53:49 zero volumio[4662]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz May 09 07:53:49 zero volumio[4662]: info: Updating MyVolumio device info May 09 07:53:49 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 07:53:49 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 07:53:49 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 07:53:49 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 07:53:49 zero volumio[4662]: info: Successfully Added MyVolumio device May 09 07:53:49 zero volumio[4662]: info: Successfully Updated MyVolumio device May 09 07:53:49 zero volumio[4662]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined May 09 07:53:49 zero volumio[4662]: info: Bluetooth name changed to Zero May 09 07:53:49 zero volumio[4662]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined May 09 07:53:49 zero volumio[4662]: info: MPD Permissions set May 09 07:53:49 zero volumio[4662]: info: camilladsp spawned new process with pid 5184, instance 1, run: true May 09 07:53:49 zero volumio[4662]: SPOTIFY: SETTING SPOTIFY VOLUME 66 May 09 07:53:49 zero volumio[4662]: info: Sending Spotify command with payload to local API: /player/volume May 09 07:53:49 zero qobuz-connect[4499]: 20250509 07:53:49.281 [4499.4499] INFO SampleApp: Stopping Local configuration server May 09 07:53:49 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 07:53:49 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 07:53:49 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 07:53:49 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 07:53:49 zero systemd[1]: qobuz-connect.service: Succeeded. May 09 07:53:49 zero systemd[1]: Stopped Volumio Qobuz Connect Service. May 09 07:53:49 zero systemd[1]: Started Volumio Qobuz Connect Service. May 09 07:53:49 zero sudo[5181]: pam_unix(sudo:session): session closed for user root May 09 07:53:49 zero volumio[4662]: info: Starting Shairport Sync May 09 07:53:49 zero systemd[1]: mpd.service: Succeeded. May 09 07:53:49 zero sudo[5203]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service May 09 07:53:49 zero sudo[5203]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 07:53:49 zero systemd[1]: Stopped Music Player Daemon. May 09 07:53:49 zero systemd[1]: Starting Music Player Daemon... May 09 07:53:49 zero systemd[1]: Stopping MyVolumio SSH Tunnel... May 09 07:53:49 zero volumio[4662]: info: MRS: Found cast device: Chromecast-Audio-d953eef841b34e41cf3ea8eac1f52c3f May 09 07:53:49 zero volumio[4662]: info: Adding audio output: May 09 07:53:49 zero systemd[1]: sshtunnel.service: Main process exited, code=killed, status=15/TERM May 09 07:53:49 zero autossh[4464]: received signal to exit (15) May 09 07:53:49 zero volumio[4662]: info: MRS: Found cast device: Chromecast-d25eef6ce3d8eaac1fb358a143b065a5 May 09 07:53:49 zero volumio[4662]: info: Adding audio output: May 09 07:53:49 zero sudo[5209]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 09 07:53:49 zero sudo[5209]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 07:53:49 zero systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 09 07:53:49 zero volumio[4662]: info: MRS: Found cast device: Google-Nest-Hub-Max-a9d7fa47015c1b70ec957319e7ec6eef May 09 07:53:49 zero volumio[4662]: info: Adding audio output: May 09 07:53:49 zero volumio[4662]: info: MRS: Found cast device: Google-Nest-Hub-1013caa979b3c5073af1a5992a739e98 May 09 07:53:49 zero volumio[4662]: info: Adding audio output: May 09 07:53:49 zero systemd[1]: shairport-sync.service: Succeeded. May 09 07:53:49 zero systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 09 07:53:49 zero systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 09 07:53:49 zero sudo[5209]: pam_unix(sudo:session): session closed for user root May 09 07:53:49 zero volumio[4662]: info: Shairport-Sync Started May 09 07:53:49 zero volumio[4662]: info: Remote SSH Started May 09 07:53:49 zero sudo[5203]: pam_unix(sudo:session): session closed for user root May 09 07:53:49 zero systemd[1]: sshtunnel.service: Succeeded. May 09 07:53:49 zero sudo[5207]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 09 07:53:49 zero systemd[1]: Stopped MyVolumio SSH Tunnel. May 09 07:53:49 zero sudo[5207]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 07:53:49 zero systemd[1]: Started MyVolumio SSH Tunnel. May 09 07:53:49 zero sudo[5207]: pam_unix(sudo:session): session closed for user root May 09 07:53:50 zero autossh[5213]: port set to 0, monitoring disabled May 09 07:53:50 zero autossh[5213]: starting ssh (count 1) May 09 07:53:50 zero autossh[5213]: ssh child pid is 5219 May 09 07:53:50 zero volumio[4662]: info: Executing endpoint qc_getconfig May 09 07:53:50 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig May 09 07:53:50 zero qobuz-connect[5201]: 20250509 07:53:50.234 [5201.5201] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket May 09 07:53:50 zero volumio[4662]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 May 09 07:53:50 zero volumio[4662]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] May 09 07:53:50 zero volumio[4662]: info: QobuzConnect: QOBUZ Connect daemon connected May 09 07:53:50 zero qobuz-connect[5201]: 20250509 07:53:50.267 [5201.5201] INFO VolumeManager: [0x184a358]: Setting new playback volume: 75 May 09 07:53:50 zero qobuz-connect[5201]: 20250509 07:53:50.267 [5201.5201] INFO VolumeManager: [0x184a358]: Setting new mute state: 0 May 09 07:53:50 zero qobuz-connect[5201]: 20250509 07:53:50.267 [5201.5201] INFO QobuzConnect: [0x184ad28]: Client initialized! May 09 07:53:50 zero qobuz-connect[5201]: 20250509 07:53:50.267 [5201.5201] INFO SampleApp: Starting Avahi advertising, name: Zero, service name: _qobuz-connect._tcp May 09 07:53:50 zero qobuz-connect[5201]: 20250509 07:53:50.282 [5201.5201] INFO LocalConfigManager: [0x1849c38]: Starting Local Configuration server May 09 07:53:50 zero qobuz-connect[5201]: 20250509 07:53:50.282 [5201.5201] INFO SampleApp: Starting Local configuration server May 09 07:53:50 zero qobuz-connect[5201]: 20250509 07:53:50.283 [5201.5201] INFO SampleApp: Connected to UNIX socket client 0x183ff60 May 09 07:53:50 zero volumio[4662]: info: CoreCommandRouter::volumioGetState May 09 07:53:50 zero volumio[4662]: info: CorePlayQueue::getTrack 0 May 09 07:53:50 zero qobuz-connect[5201]: 20250509 07:53:50.384 [5201.5201] INFO SampleApp: Playback volume changed: 75 May 09 07:53:50 zero volumio[4662]: info: CoreCommandRouter::volumioGetState May 09 07:53:50 zero volumio[4662]: info: CorePlayQueue::getTrack 0 May 09 07:53:50 zero volumio[4662]: info: Successfully Updated MyVolumio device May 09 07:53:50 zero volumiossh-tunnel[5212]: Warning: Permanently added '[us1.myvolumio.org]:2222,[162.243.167.188]:2222' (RSA) to the list of known hosts. May 09 07:53:51 zero sudo[5225]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service May 09 07:53:51 zero sudo[5225]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 07:53:51 zero sudo[5225]: pam_unix(sudo:session): session closed for user root May 09 07:53:51 zero volumio[4662]: info: Volumio BT Module successfully started May 09 07:53:52 zero volumio[4662]: info: TidalConnect service stoped! May 09 07:53:52 zero volumio[4662]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 09 07:53:52 zero volumio[4662]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect May 09 07:53:52 zero sudo[5236]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 09 07:53:52 zero sudo[5236]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 07:53:52 zero systemd[1]: Started Volumio Tidal Connect Service. May 09 07:53:52 zero sudo[5236]: pam_unix(sudo:session): session closed for user root May 09 07:53:52 zero volumio[4662]: info: Executing endpoint tc_getconfig May 09 07:53:52 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig May 09 07:53:52 zero vtcs[5238]: STARTING TidalConnect services, version: 1.4.0.34 May 09 07:53:52 zero vtcs[5238]: [2025-05-09 07:53:52.975] [tisoc] [critical] [DeviceInfo.cpp:56] ioctl error. ret=-1, errno="No such device" May 09 07:53:52 zero vtcs[5238]: [2025-05-09 07:53:52.975] [tisoc] [critical] [DeviceInfo.cpp:57] [ASSERT] generateDeviceId(0) May 09 07:53:52 zero vtcs[5238]: STARTED TidalConnect services. May 09 07:53:52 zero volumio[4662]: info: Executing endpoint tc_connect May 09 07:53:52 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect May 09 07:53:52 zero volumio[4662]: info: Connecting to TidalConnect May 09 07:53:53 zero volumio[4662]: info: CoreCommandRouter::servicePushState May 09 07:53:53 zero volumio[4662]: info: CoreStateMachine::pushState May 09 07:53:53 zero volumio[4662]: info: CorePlayQueue::getTrack 0 May 09 07:53:53 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 07:53:53 zero volumio[4662]: info: CoreCommandRouter::volumioPushState May 09 07:53:53 zero volumio[4662]: info: MRS: Pushing multiroomSync output update for this device May 09 07:53:53 zero volumio[4662]: info: MRS: Pushing multiroomSync output May 09 07:53:53 zero volumio[4662]: info: CoreCommandRouter::volumioGetState May 09 07:53:53 zero volumio[4662]: info: CorePlayQueue::getTrack 0 May 09 07:53:53 zero volumio[4662]: info: CorePlayQueue::getTrack 0 May 09 07:53:53 zero volumio[4662]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect May 09 07:53:53 zero volumio[4662]: info: CoreCommandRouter::servicePushState May 09 07:53:53 zero volumio[4662]: info: CoreStateMachine::pushState May 09 07:53:53 zero volumio[4662]: info: CorePlayQueue::getTrack 0 May 09 07:53:53 zero volumio[4662]: info: CoreCommandRouter::volumioPushState May 09 07:53:53 zero volumio[4662]: info: MRS: Pushing multiroomSync output update for this device May 09 07:53:53 zero volumio[4662]: info: MRS: Pushing multiroomSync output May 09 07:53:53 zero volumio[4662]: info: CoreCommandRouter::volumioGetState May 09 07:53:53 zero volumio[4662]: info: CorePlayQueue::getTrack 0 May 09 07:53:53 zero volumio[4662]: info: CorePlayQueue::getTrack 0 May 09 07:53:53 zero volumio[4662]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect May 09 07:53:53 zero volumio[4662]: SPOTIFY: RECEIVED VOLUMIO VOLUME 66 May 09 07:53:53 zero volumio[4662]: SPOTIFY: RECEIVED VOLUMIO VOLUME 66 May 09 07:53:53 zero mpd[5215]: May 09 07:53 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 09 07:53:53 zero volumio[4662]: info: MRS: Getting audio outputs on start May 09 07:53:53 zero volumio[4662]: info: MRS: Requesting all other devices output May 09 07:53:53 zero systemd[1]: Started Music Player Daemon. May 09 07:53:53 zero sudo[5159]: pam_unix(sudo:session): session closed for user root May 09 07:53:53 zero volumio[4662]: error: updateQueue error: null May 09 07:53:53 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 09 07:53:53 zero volumio[4662]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 May 09 07:53:53 zero volumio[4662]: info: CoreCommandRouter::volumioGetState May 09 07:53:53 zero volumio[4662]: info: CorePlayQueue::getTrack 0 May 09 07:53:55 zero volumio[4662]: info: TidalConnect service started! May 09 07:53:55 zero volumio[4662]: [Metrics] CommandRouter: 72s 690.08ms May 09 07:53:55 zero volumio[4662]: info: CoreCommandRouter::volumiosetStartupVolume May 09 07:53:55 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 07:53:55 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 07:53:55 zero volumio[4662]: info: CoreCommandRouter::Close All Modals sent May 09 07:53:55 zero volumio[4662]: info: CoreCommandRouter::Close All Modals sent May 09 07:53:55 zero volumio[4662]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 09 07:53:55 zero volumio[4662]: info: FusionDsp - ---- read samplerate from file: 44100 May 09 07:53:55 zero volumio[4662]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 09 07:53:55 zero volumio[4662]: info: FusionDsp - {"Reload":{"result":"Ok"}} May 09 07:53:56 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 09 07:53:56 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 09 07:53:56 zero volumio[4662]: xcb_connection_has_error() returned true May 09 07:53:56 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 09 07:53:58 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 07:53:58 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 07:53:58 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 09 07:53:58 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 09 07:53:58 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 09 07:53:58 zero volumio[4662]: info: CoreCommandRouter::volumioGetBrowseSources May 09 07:53:58 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 09 07:54:00 zero volumio[4662]: info: camilladsp spawned new process with pid 5279, instance 1, run: true May 09 07:54:02 zero volumio[4662]: info: BOOT COMPLETED May 09 07:54:06 zero volumio[4662]: info: CoreCommandRouter::volumioGetState May 09 07:54:06 zero volumio[4662]: info: CorePlayQueue::getTrack 0 May 09 07:54:08 zero volumio[4662]: info: Executing endpoint metavolumio May 09 07:54:08 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 09 07:54:08 zero volumio[4662]: info: Executing endpoint metavolumio May 09 07:54:08 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 09 07:54:08 zero volumio[4662]: info: Executing endpoint metavolumio May 09 07:54:08 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 09 07:54:09 zero volumio[4662]: info: VolumeController::SetAlsaVolume87 May 09 07:54:09 zero volumio[4662]: info: CoreCommandRouter::volumioGetState May 09 07:54:09 zero volumio[4662]: info: CorePlayQueue::getTrack 0 May 09 07:54:09 zero volumio[4662]: info: CoreStateMachine::pushState May 09 07:54:09 zero volumio[4662]: info: CorePlayQueue::getTrack 0 May 09 07:54:09 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 07:54:09 zero volumio[4662]: info: CoreCommandRouter::volumioPushState May 09 07:54:09 zero volumio[4662]: info: MRS: Pushing multiroomSync output update for this device May 09 07:54:09 zero volumio[4662]: info: MRS: Pushing multiroomSync output May 09 07:54:09 zero volumio[4662]: info: CoreCommandRouter::volumioGetState May 09 07:54:09 zero volumio[4662]: info: CorePlayQueue::getTrack 0 May 09 07:54:09 zero volumio[4662]: SPOTIFY: RECEIVED VOLUMIO VOLUME 87 May 09 07:54:09 zero volumio[4662]: SPOTIFY: SPOTIFY VOLUME 66 May 09 07:54:09 zero volumio[4662]: SPOTIFY: VOLUMIO VOLUME 87 May 09 07:54:09 zero volumio[4662]: SPOTIFY: DELTA VOLUME ENOUGH: true May 09 07:54:09 zero volumio[4662]: info: Setting Spotify Volume from Volumio: 87 May 09 07:54:11 zero volumio[4662]: SPOTIFY: SETTING SPOTIFY VOLUME 87 May 09 07:54:11 zero volumio[4662]: info: Sending Spotify command with payload to local API: /player/volume May 09 07:54:15 zero volumio[4662]: info: CoreCommandRouter::volumioGetState May 09 07:54:15 zero volumio[4662]: info: CorePlayQueue::getTrack 0 May 09 07:54:17 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 09 07:54:17 zero volumio[4662]: info: CURURI: music-library May 09 07:54:17 zero volumio[4662]: info: Preload queue cleared May 09 07:54:17 zero volumio[4662]: info: Executing endpoint metavolumio May 09 07:54:17 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 09 07:54:17 zero volumio[4662]: info: Executing endpoint metavolumio May 09 07:54:17 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 09 07:54:17 zero volumio[4662]: info: Executing endpoint metavolumio May 09 07:54:17 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 09 07:54:18 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 09 07:54:18 zero volumio[4662]: info: CURURI: music-library/INTERNAL May 09 07:54:18 zero volumio[4662]: info: Preload queue cleared May 09 07:54:19 zero volumio[4662]: error: Failed request for metavolumio API May 09 07:54:22 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 09 07:54:22 zero volumio[4662]: info: CURURI: music-library/INTERNAL/Spotify 2 May 09 07:54:22 zero volumio[4662]: info: Preload queue cleared May 09 07:54:25 zero volumio[4662]: info: Preload queue cleared May 09 07:54:25 zero volumio[4662]: info: CoreCommandRouter::volumioReplaceandPlayItems May 09 07:54:25 zero volumio[4662]: info: CoreStateMachine::ClearQueue May 09 07:54:25 zero volumio[4662]: info: CoreStateMachine::stop May 09 07:54:25 zero volumio[4662]: info: CoreStateMachine::setConsumeUpdateService undefined May 09 07:54:25 zero volumio[4662]: info: CoreStateMachine::stPlaybackTimer May 09 07:54:25 zero volumio[4662]: info: CoreStateMachine::updateTrackBlock May 09 07:54:25 zero volumio[4662]: info: CorePlayQueue::getTrackBlock May 09 07:54:25 zero volumio[4662]: info: CoreStateMachine::pushState May 09 07:54:25 zero volumio[4662]: info: CorePlayQueue::getTrack 0 May 09 07:54:25 zero volumio[4662]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 07:54:25 zero volumio[4662]: info: CoreCommandRouter::volumioPushState May 09 07:54:25 zero volumio[4662]: info: MRS: Pushing multiroomSync output update for this device May 09 07:54:25 zero volumio[4662]: info: MRS: Pushing multiroomSync output May 09 07:54:25 zero volumio[4662]: info: CoreCommandRouter::volumioGetState May 09 07:54:25 zero volumio[4662]: info: CorePlayQueue::getTrack 0 May 09 07:54:25 zero volumio[4662]: info: CoreStateMachine::serviceStop May 09 07:54:25 zero volumio[4662]: info: CorePlayQueue::getTrack 0 May 09 07:54:25 zero volumio[4662]: info: CoreCommandRouter::serviceStop May 09 07:54:25 zero volumio[4662]: info: ControllerMpd::stop May 09 07:54:25 zero volumio[4662]: verbose: ControllerMpd::sendMpdCommand stop May 09 07:54:25 zero volumio[4662]: info: CorePlayQueue::clearPlayQueue May 09 07:54:25 zero volumio[4662]: info: CorePlayQueue::saveQueue May 09 07:54:25 zero volumio[4662]: info: CoreCommandRouter::volumioPushQueue May 09 07:54:25 zero volumio[4662]: info: CoreStateMachine::addQueueItems May 09 07:54:25 zero volumio[4662]: info: CorePlayQueue::addQueueItems May 09 07:54:25 zero volumio[4662]: info: Preload queue cleared May 09 07:54:25 zero volumio[4662]: info: Adding Item to queue: music-library/INTERNAL/Spotify 2/Oldies spotify May 09 07:54:25 zero volumio[4662]: info: Exploding uri music-library/INTERNAL/Spotify 2/Oldies spotify in service mpd May 09 07:54:25 zero volumio[4662]: SPOTIFY: RECEIVED VOLUMIO VOLUME 87 May 09 07:54:25 zero volumio[4662]: info: ALBUMART /albumart?cacheid=381&web=John%20Lennon/Gimme%20Some%20Truth/extralarge&path=%2Fmnt%2FINTERNAL%2FSpotify%202%2FOldies%20spotify%2F(Just%20Like)%20Starting%20Over%20-%20Remastered%202010.mp3&metadata=false May 09 07:54:25 zero volumio[4662]: info: URI /mnt/INTERNAL/Spotify 2/Oldies spotify/(Just Like) Starting Over - Remastered 2010.mp3 May 09 07:54:25 zero volumio[4662]: info: ALBUMART /albumart?cacheid=381&web=KC%20%26%20The%20Sunshine%20Band/Part%203/extralarge&path=%2Fmnt%2FINTERNAL%2FSpotify%202%2FOldies%20spotify%2F(Shake%2C%20Shake%2C%20Shake)%20Shake%20Your%20Booty.mp3&metadata=false May 09 07:54:25 zero volumio[4662]: info: URI /mnt/INTERNAL/Spotify 2/Oldies spotify/(Shake, Shake, Shake) Shake Your Booty.mp3 May 09 07:54:25 zero volumio[4662]: info: ALBUMART /albumart?cacheid=381&web=George%20Harrison/Let%20It%20Roll%20-%20Songs%20Of%20George%20Harrison/extralarge&path=%2Fmnt%2FINTERNAL%2FSpotify%202%2FOldies%20spotify%2FAll%20Those%20Years%20Ago%20-%202009%20Mix.mp3&metadata=false May 09 07:54:25 zero volumio[4662]: info: URI /mnt/INTERNAL/Spotify 2/Oldies spotify/All Those Years Ago - 2009 Mix.mp3 May 09 07:54:25 zero volumio[4662]: info: ALBUMART /albumart?cacheid=381&web=The%20Beatles/The%20Beatles%20(Remastered)/extralarge&path=%2Fmnt%2FINTERNAL%2FSpotify%202%2FOldies%20spotify%2FBack%20In%20The%20U.S.S.R.%20-%20Remastered%202009.mp3&metadata=false May 09 07:54:25 zero volumio[4662]: info: URI /mnt/INTERNAL/Spotify 2/Oldies spotify/Back In The U.S.S.R. - Remastered 2009.mp3 May 09 07:54:25 zero volumio[4662]: info: ALBUMART /albumart?cacheid=381&web=Gerry%20Rafferty/City%20to%20City/extralarge&path=%2Fmnt%2FINTERNAL%2FSpotify%202%2FOldies%20spotify%2FBaker%20Street.mp3&metadata=false May 09 07:54:25 zero volumio[4662]: info: URI /mnt/INTERNAL/Spotify 2/Oldies spotify/Baker Street.mp3 May 09 07:54:25 zero volumio[4662]: info: ALBUMART /albumart?cacheid=381&web=Paul%20McCartney%2FWings/Band%20On%20The%20Run/extralarge&path=%2Fmnt%2FINTERNAL%2FSpotify%202%2FOldies%20spotify%2FBand%20On%20The%20Run%20-%202010%20Remaster.mp3&metadata=false May 09 07:54:25 zero volumio[4662]: info: URI /mnt/INTERNAL/Spotify 2/Oldies spotify/Band On The Run - 2010 Remaster.mp3 May 09 07:54:25 zero volumio[4662]: info: ALBUMART /albumart?cacheid=381&web=Joey%20Scarbury/America's%20Greatest%20Hero/extralarge&path=%2Fmnt%2FINTERNAL%2FSpotify%202%2FOldies%20spotify%2FBelieve%20It%20or%20Not%20(Theme%20from%20_Greatest%20American%20Hero_).mp3&metadata=false May 09 07:54:25 zero volumio[4662]: info: URI /mnt/INTERNAL/Spotify 2/Oldies spotify/Believe It or Not (Theme from _Greatest American Hero_).mp3 May 09 07:54:25 zero volumio[4662]: info: ALBUMART /albumart?cacheid=381&web=The%20Red%20One%20Rocketman/Benny%20and%20the%20Jets/extralarge&path=%2Fmnt%2FINTERNAL%2FSpotify%202%2FOldies%20spotify%2FBenny%20and%20the%20Jets.mp3&metadata=false May 09 07:54:25 zero volumio[4662]: info: URI /mnt/INTERNAL/Spotify 2/Oldies spotify/Benny and the Jets.mp3 May 09 07:54:25 zero volumio[4662]: info: ALBUMART /albumart?cacheid=381&web=Ambrosia/One%20Eighty/extralarge&path=%2Fmnt%2FINTERNAL%2FSpotify%202%2FOldies%20spotify%2FBiggest%20Part%20of%20Me.mp3&metadata=false May 09 07:54:25 zero volumio[4662]: info: URI /mnt/INTERNAL/Spotify 2/Oldies spotify/Biggest Part of Me.mp3 May 09 07:54:25 zero volumio[4662]: info: ALBUMART /albumart?cacheid=381&web=Steppenwolf/Steppenwolf/extralarge&path=%2Fmnt%2FINTERNAL%2FSpotify%202%2FOldies%20spotify%2FBorn%20To%20Be%20Wild.mp3&metadata=false May 09 07:54:25 zero volumio[4662]: info: URI /mnt/INTERNAL/Spotify 2/Oldies spotify/Born To Be Wild.mp3 May 09 07:54:25 zero volumio[4662]: info: ALBUMART /albumart?cacheid=381&web=Elton%20John/Diamonds%20(Deluxe)/extralarge&path=%2Fmnt%2FINTERNAL%2FSpotify%202%2FOldies%20spotify%2FCandle%20In%20The%20Wind%20-%20Remastered.mp3&metadata=false May 09 07:54:25 zero volumio[4662]: info: URI /mnt/INTERNAL/Spotify 2/Oldies spotify/Candle In The Wind - Remastered.mp3 May 09 07:54:25 zero volumio[4662]: info: ALBUMART /albumart?cacheid=381&web=Paul%20Davis/Cool%20Night/extralarge&path=%2Fmnt%2FINTERNAL%2FSpotify%202%2FOldies%20spotify%2FCool%20Night.mp3&metadata=false May 09 07:54:25 zero volumio[4662]: info: URI /mnt/INTERNAL/Spotify 2/Oldies spotify/Cool Night.mp3 May 09 07:54:25 zero volumio[4662]: info: ALBUMART /albumart?cacheid=381&web=Orleans/Dance%20With%20Me%3A%20The%20Best%20Of%20Orleans/extralarge&path=%2Fmnt%2FINTERNAL%2FSpotify%202%2FOldies%20spotify%2FDance%20with%20Me.mp3&metadata=false May 09 07:54:25 zero volumio[4662]: info: URI /mnt/INTERNAL/Spotify 2/Oldies spotify/Dance with Me.mp3 May 09 07:54:25 zero volumio[4662]: info: ALBUMART /albumart?cacheid=381&web=Elton%20John/Don't%20Shoot%20Me%20I'm%20Only%20The%20Piano%20Player/extralarge&path=%2Fmnt%2FINTERNAL%2FSpotify%202%2FOldies%20spotify%2FDaniel.mp3&metadata=false May 09 07:54:25 zero volumio[4662]: info: URI /mnt/INTERNAL/Spotify 2/Oldies spotify/Daniel.mp3 May 09 07:54:25 zero volumio[4662]: info: ALBUMART /albumart?cacheid=381&web=The%20Beatles/1%20(Remastered)/extralarge&path=%2Fmnt%2FINTERNAL%2FSpotify%202%2FOldies%20spotify%2FDay%20Tripper%20-%20Remastered%202015.mp3&metadata=false May 09 07:54:25 zero volumio[4662]: info: URI /mnt/INTERNAL/Spotify 2/Oldies spotify/Day Tripper - Remastered 2015.mp3 May 09 07:54:25 zero volumio[4662]: info: ALBUMART /albumart?cacheid=381&web=Frankie%20Valli%20%26%20The%20Four%20Seasons/Who%20Loves%20You/extralarge&path=%2Fmnt%2FINTERNAL%2FSpotify%202%2FOldies%20spotify%2FDecember%2C%201963%20(Oh%20What%20a%20Night!).mp3&metadata=false May 09 07:54:25 zero volumio[4662]: info: URI /mnt/INTERNAL/Spotify 2/Oldies spotify/December, 1963 (Oh What a Night!).mp3 May 09 07:54:25 zero volumio[4662]: info: ALBUMART /albumart?cacheid=381&web=Michael%20Jackson/Off%20the%20Wall/extralarge&path=%2Fmnt%2FINTERNAL%2FSpotify%202%2FOldies%20spotify%2FDon't%20Stop%20'Til%20You%20Get%20Enough.mp3&metadata=false May 09 07:54:25 zero volumio[4662]: info: URI /mnt/INTERNAL/Spotify 2/Oldies spotify/Don't Stop 'Til You Get Enough.mp3 May 09 07:54:25 zero volumio[4662]: info: ALBUMART /albumart?cacheid=381&web=Aerosmith/Aerosmith/extralarge&path=%2Fmnt%2FINTERNAL%2FSpotify%202%2FOldies%20spotify%2FDream%20On.mp3&metadata=false May 09 07:54:25 zero volumio[4662]: info: URI /mnt/INTERNAL/Spotify 2/Oldies spotify/Dream On.mp3 May 09 07:54:25 zero volumio[4662]: info: ALBUMART /albumart?cacheid=381&web=Rupert%20Holmes/'70s%20Pop%20%231's/extralarge&path=%2Fmnt%2FINTERNAL%2FSpotify%202%2FOldies%20spotify%2FEscape%20(The%20Pina%20Colada%20Song).mp3&metadata=false May 09 07:54:25 zero volumio[4662]: info: URI /mnt/INTERNAL/Spotify 2/Oldies spotify/Escape (The Pina Colada Song).mp3 May 09 07:54:25 zero volumio[4662]: info: ALBUMART /albumart?cacheid=381&web=The%20J.%20Geils%20Band/Freeze%20Frame/extralarge&path=%2Fmnt%2FINTERNAL%2FSpotify%202%2FOldies%20spotify%2FFreeze-Frame.mp3&metadata=false May 09 07:54:25 zero volumio[4662]: info: URI /mnt/INTERNAL/Spotify 2/Oldies spotify/Freeze-Frame.mp3 May 09 07:54:25 zero volumio[4662]: info: ALBUMART /albumart?cacheid=381&web=The%20Beatles%2FBilly%20Preston/1%20(Remastered)/extralarge&path=%2Fmnt%2FINTERNAL%2FSpotify%202%2FOldies%20spotify%2FGet%20Back%20-%20Remastered%202015.mp3&metadata=false May 09 07:54:25 zero volumio[4662]: info: URI /mnt/INTERNAL/Spotify 2/Oldies spotify/Get Back - Remastered 2015.mp3 May 09 07:54:25 zero volumio[4662]: info: ALBUMART /albumart?cacheid=381&web=The%20Beatles/Sgt.%20Pepper's%20Lonely%20Hearts%20Club%20Band%20(Remastered)/extralarge&path=%2Fmnt%2FINTERNAL%2FSpotify%202%2FOldies%20spotify%2FGetting%20Better%20-%20Remastered%202009.mp3&metadata=false May 09 07:54:25 zero volumio[4662]: info: URI /mnt/INTERNAL/Spotify 2/Oldies spotify/Getting Better - Remastered 2009.mp3 May 09 07:54:25 zero volumio[4662]: info: ALBUMART /albumart?cacheid=381&web=Smash%20Mouth/All%20Star%20Smash%20Hits/extralarge&path=%2Fmnt%2FINTERNAL%2FSpotify%202%2FOldies%20spotify%2FGetting%20Better%20-%20The%20Cat%20In%20The%20Hat%2FSoundtrack%20Version.mp3&metadata=false May 09 07:54:25 zero volumio[4662]: info: URI /mnt/INTERNAL/Spotify 2/Oldies spotify/Getting Better - The Cat In The Hat/Soundtrack Version.mp3 May 09 07:54:25 zero volumio[4662]: info: ALBUMART /albumart?cacheid=381&web=George%20Benson/The%20Best%20of%20George%20Benson/extralarge&path=%2Fmnt%2FINTERNAL%2FSpotify%202%2FOldies%20spotify%2FGive%20Me%20the%20Night%20-%20Edit.mp3&metadata=false May 09 07:54:25 zero volumio[4662]: info: URI /mnt/INTERNAL/Spotify 2/Oldies spotify/Give Me the Night - Edit.mp3 May 09 07:54:25 zero volumio[4662]: info: ALBUMART /albumart?cacheid=381&web=Supertramp/Breakfast%20In%20America%20(Deluxe%20Edition)/extralarge&path=%2Fmnt%2FINTERNAL%2FSpotify%202%2FOldies%20spotify%2FGoodbye%20Stranger%20-%202010%20Remastered.mp3&metadata=false May 09 07:54:25 zero volumio[4662]: info: URI /mnt/INTERNAL/Spotify 2/Oldies spotify/Goodbye Stranger - 2010 Remastered.mp3 May 09 07:54:25 zero volumio[4662]: info: ALBUMART /albumart?cacheid=381&web=Jerry%20Lee%20Lewis/The%20Golden%20Rock%20Hits%20Of%20Jerry%20Lee%20Lewis/extralarge&path=%2Fmnt%2FINTERNAL%2FSpotify%202%2FOldies%20spotify%2FGreat%20Balls%20Of%20Fire%20-%201964%20Version.mp3&metadata=false May 09 07:54:25 zero volumio[4662]: info: URI /mnt/INTERNAL/Spotify 2/Oldies spotify/Great Balls Of Fire - 1964 Version.mp3 May 09 07:54:25 zero volumio[4662]: info: ALBUMART /albumart?cacheid=381&web=Jerry%20Lee%20Lewis/Great%20Balls%20Of%20Fire/extralarge&path=%2Fmnt%2FINTERNAL%2FSpotify%202%2FOldies%20spotify%2FGreat%20Balls%20Of%20Fire.mp3&metadata=false May 09 07:54:25 zero volumio[4662]: info: URI /mnt/INTERNAL/Spotify 2/Oldies spotify/Great Balls Of Fire.mp3 May 09 07:54:25 zero volumio[4662]: info: ALBUMART /albumart?cacheid=381&web=Bread/Guitar%20Man/extralarge&path=%2Fmnt%2FINTERNAL%2FSpotify%202%2FOldies%20spotify%2FGuitar%20Man.mp3&metadata=false May 09 07:54:25 zero volumio[4662]: info: URI /mnt/INTERNAL/Spotify 2/Oldies spotify/Guitar Man.mp3 May 09 07:54:25 zero volumio[4662]: info: ALBUMART /albumart?cacheid=381&web=The%20Turtles/Happy%20Together/extralarge&path=%2Fmnt%2FINTERNAL%2FSpotify%202%2FOldies%20spotify%2FHappy%20Together.mp3&metadata=false May 09 07:54:25 zero volumio[4662]: info: URI /mnt/INTERNAL/Spotify 2/Oldies spotify/Happy Together.mp3 May 09 07:54:25 zero volumio[4662]: info: ALBUMART /albumart?cacheid=381&web=The%20Beatles/Abbey%20Road%20(Remastered)/extralarge&path=%2Fmnt%2FINTERNAL%2FSpotify%202%2FOldies%20spotify%2FHere%20Comes%20The%20Sun%20-%20Remastered%202009.mp3&metadata=false May 09 07:54:25 zero volumio[4662]: info: URI /mnt/INTERNAL/Spotify 2/Oldies spotify/Here Comes The Sun - Remastered 2009.mp3 May 09 07:54:25 zero volumio[4662]: info: ALBUMART /albumart?cacheid=381&web=Steely%20Dan/Gaucho/extralarge&path=%2Fmnt%2FINTERNAL%2FSpotify%202%2FOldies%20spotify%2FHey%20Nineteen.mp3&metadata=false May 09 07:54:25 zero volumio[4662]: info: URI /mnt/INTERNAL/Spotify 2/Oldies spotify/Hey Nineteen.mp3 May 09 07:54:25 zero volumio[4662]: info: ALBUMART /albumart?cacheid=381&web=Rupert%20Holmes/Throwback%20Tunes%3A%201980/extralarge&path=%2Fmnt%2FINTERNAL%2FSpotify%202%2FOldies%20spotify%2FHim.mp3&metadata=false May 09 07:54:25 zero volumio[4662]: info: URI /mnt/INTERNAL/Spotify 2/Oldies spotify/Him.mp3 May 09 07:54:25 zero volumio[4662]: info: ALBUMART /albumart?cacheid=381&web=Ace/Five-A-Side/extralarge&path=%2Fmnt%2FINTERNAL%2FSpotify%202%2FOldies%20spotify%2FHow%20Long.mp3&metadata=false May 09 07:54:25 zero volumio[4662]: info: URI /mnt/INTERNAL/Spotify 2/Oldies spotify/How Long.mp3 May 09 07:54:25 zero volumio[4662]: info: ALBUMART /albumart?cacheid=381&web=Ambrosia/Life%20Beyond%20L.A./extralarge&path=%2Fmnt%2FINTERNAL%2FSpotify%202%2FOldies%20spotify%2FHow%20Much%20I%20Feel.mp3&metadata=false May 09 07:54:25 zero volumio[4662]: info: URI /mnt/INTERNAL/Spotify 2/Oldies spotify/How Much I Feel.mp3 May 09 07:54:25 zero volumio[4662]: info: ALBUMART /albumart?cacheid=381&web=Paul%20Davis/Singer%20of%20Songs%2C%20Teller%20of%20Tales/extralarge&path=%2Fmnt%2FINTERNAL%2FSpotify%202%2FOldies%20spotify%2FI%20Go%20Crazy.mp3&metadata=false May 09 07:54:25 zero volumio[4662]: info: URI /mnt/INTERNAL/Spotify 2/Oldies spotify/I Go Crazy.mp3 May 09 07:54:25 zero volumio[4662]: info: ALBUMART /albumart?cacheid=381&web=Michael%20McDonald/If%20That's%20What%20It%20Takes/extralarge&path=%2Fmnt%2FINTERNAL%2FSpotify%202%2FOldies%20spotify%2FI%20Keep%20Forgettin'%20(Every%20Time%20You're%20Near).mp3&metadata=false May 09 07:54:25 zero volumio[4662]: info: URI /mnt/INTERNAL/Spotify 2/Oldies spotify/I Keep Forgettin' (Every Time You're Near).mp3 May 09 07:54:25 zero volumio[4662]: info: ALBUMART /albumart?cacheid=381&web=England%20Dan%20%26%20John%20Ford%20Coley/Nights%20Are%20Forever/extralarge&path=%2Fmnt%2FINTERNAL%2FSpotify%202%2FOldies%20spotify%2FI'd%20Really%20Love%20to%20See%20You%20Tonight.mp3&metadata=false May 09 07:54:25 zero volumio[4662]: info: URI /mnt/INTERNAL/Spotify 2/Oldies spotify/I'd Really Love to See You Tonight.mp3 May 09 07:54:25 zero volumio[4662]: info: ALBUMART /albumart?cacheid=381&web=10cc/The%20Original%20Soundtrack/extralarge&path=%2Fmnt%2FINTERNAL%2FSpotify%202%2FOldies%20spotify%2FI'm%20Not%20In%20Love.mp3&metadata=false May 09 07:54:25 zero volumio[4662]: info: URI /mnt/INTERNAL/Spotify 2/Oldies spotify/I'm Not In Love.mp3 May 09 07:54:25 zero volumio[4662]: info: ALBUMART /albumart?cacheid=381&web=The%20Beatles/Rubber%20Soul%20(Remastered%202009)/extralarge&path=%2Fmnt%2FINTERNAL%2FSpotify%202%2FOldies%20spotify%2FIn%20My%20Life%20-%20Remastered%202009.mp3&metadata=false May 09 07:54:25 zero volumio[4662]: info: URI /mnt/INTERNAL/Spotify 2/Oldies spotify/In My Life - Remastered 2009.mp3 May 09 07:54:25 zero volumio[4662]: info: ALBUMART /albumart?cacheid=381&web=John%20Lennon/Imagine/extralarge&path=%2Fmnt%2FINTERNAL%2FSpotify%202%2FOldies%20spotify%2FJealous%20Guy%20-%20Remastered%202010.mp3&metadata=false May 09 07:54:25 zero volumio[4662]: info: URI /mnt/INTERNAL/Spotify 2/Oldies spotify/Jealous Guy - Remastered 2010.mp3 May 09 07:54:25 zero volumio[4662]: info: ALBUMART /albumart?cacheid=381&web=Bee%20Gees/Main%20Course/extralarge&path=%2Fmnt%2FINTERNAL%2FSpotify%202%2FOldies%20spotify%2FJive%20Talkin'.mp3&metadata=false May 09 07:54:25 zero volumio[4662]: info: URI /mnt/INTERNAL/Spotify 2/Oldies spotify/Jive Talkin'.mp3 May 09 07:54:25 zero volumio[4662]: info: ALBUMART /albumart?cacheid=381&web=Daryl%20Hall%20%26%20John%20Oates/Voices/extralarge&path=%2Fmnt%2FINTERNAL%2FSpotify%202%2FOldies%20spotify%2FKiss%20on%20My%20List.mp3&metadata=false May 09 07:54:25 zero volumio[4662]: info: URI /mnt/INTERNAL/Spotify 2/Oldies spotify/Kiss on My List.mp3 May 09 07:54:25 zero volumio[4662]: info: ALBUMART /albumart?cacheid=381&web=Electric%20Light%20Orchestra/A%20New%20World%20Record/extralarge&path=%2Fmnt%2FINTERNAL%2FSpotify%202%2FOldies%20spotify%2FLivin'%20Thing.mp3&metadata=false May 09 07:54:25 zero volumio[4662]: info: URI /mnt/INTERNAL/Spotify 2/Oldies spotify/Livin' Thing.mp3 May 09 07:54:25 zero volumio[4662]: info: ALBUMART /albumart?cacheid=381&web=Nicolette%20Larson/Nicolette/extralarge&path=%2Fmnt%2FINTERNAL%2FSpotify%202%2FOldies%20spotify%2FLotta%20Love.mp3&metadata=false May 09 07:54:25 zero volumio[4662]: info: URI /mnt/INTERNAL/Spotify 2/Oldies spotify/Lotta Love.mp3 May 09 07:54:25 zero volumio[4662]: info: ALBUMART /albumart?cacheid=381&web=Captain%20%26%20Tennille/Love%20Will%20Keep%20Us%20Together/extralarge&path=%2Fmnt%2FINTERNAL%2FSpotify%202%2FOldies%20spotify%2FLove%20Will%20Keep%20Us%20Together.mp3&metadata=false May 09 07:54:25 zero volumio[4662]: info: URI /mnt/INTERNAL/Spotify 2/Oldies spotify/Love Will Keep Us Together.mp3 May 09 07:54:25 zero volumio[4662]: info: ALBUMART /albumart?cacheid=381&web=Boz%20Scaggs/My%20Time%3A%20A%20Boz%20Scaggs%20Anthology%20(1969-1997)/extralarge&path=%2Fmnt%2FINTERNAL%2FSpotify%202%2FOldies%20spotify%2FLowdown.mp3&metadata=false May 09 07:54:25 zero volumio[4662]: info: URI /mnt/INTERNAL/Spotify 2/Oldies spotify/Lowdown.mp3 May 09 07:54:25 zero volumio[4662]: info: ALBUMART /albumart?cacheid=381&web=Leo%20Sayer/Living%20In%20A%20Fantasy/extralarge&path=%2Fmnt%2FINTERNAL%2FSpotify%202%2FOldies%20spotify%2FMore%20Than%20I%20Can%20Say%20-%20Remastered.mp3&metadata=false May 09 07:54:25 zero volumio[4662]: info: URI /mnt/INTERNAL/Spotify 2/Oldies spotify/More Than I Can Say - Remastered.mp3 May 09 07:54:25 zero volumio[4662]: info: ALBUMART /albumart?cacheid=381&web=Electric%20Light%20Orchestra/Out%20of%20the%20Blue/extralarge&path=%2Fmnt%2FINTERNAL%2FSpotify%202%2FOldies%20spotify%2FMr.%20Blue%20Sky.mp3&metadata=false May 09 07:54:25 zero volumio[4662]: info: URI /mnt/INTERNAL/Spotify 2/Oldies spotify/Mr. Blue Sky.mp3 May 09 07:54:25 zero volumio[4662]: info: ALBUMART /albumart?cacheid=381&web=Billy%20Joel/52nd%20Street/extralarge&path=%2Fmnt%2FINTERNAL%2FSpotify%202%2FOldies%20spotify%2FMy%20Life.mp3&metadata=false May 09 07:54:25 zero volumio[4662]: info: URI /mnt/INTERNAL/Spotify 2/Oldies spotify/My Life.mp3 May 09 07:54:25 zero volumio[4662]: info: ALBUMART /albumart?cacheid=381&web=Eagles/Hotel%20California%20(2013%20Remaster)/extralarge&path=%2Fmnt%2FINTERNAL%2FSpotify%202%2FOldies%20spotify%2FNew%20Kid%20in%20Town%20-%202013%20Remaster.mp3&metadata=false May 09 07:54:25 zero volumio[4662]: info: URI /mnt/INTERNAL/Spotify 2/Oldies spotify/New Kid in Town - 2013 Remaster.mp3 May 09 07:54:25 zero volumio[4662]: info: ALBUMART /albumart?cacheid=381&web=Stephen%20Bishop/Careless/extralarge&path=%2Fmnt%2FINTERNAL%2FSpotify%202%2FOldies%20spotify%2FOn%20And%20On.mp3&metadata=false May 09 07:54:25 zero volumio[4662]: info: URI /mnt/INTERNAL/Spotify 2/Oldies spotify/On And On.mp3 May 09 07:54:25 zero volumio[4662]: info: ALBUMART /albumart?cacheid=381&web=Daryl%20Hall%20%26%20John%20Oates/Private%20Eyes%20(Expanded%20Edition)/extralarge&path=%2Fmnt%2FINTERNAL%2FSpotify%202%2FOldies%20spotify%2FPrivate%20Eyes.mp3&metadata=false May 09 07:54:25 zero volumio[4662]: info: URI /mnt/INTERNAL/Spotify 2/Oldies spotify/Private Eyes.mp3 May 09 07:54:25 zero volumio[4662]: info: ALBUMART /albumart?cacheid=381&web=B.J.%20Thomas/Raindrops%20Keep%20Fallin'%20On%20My%20Head/extralarge&path=%2Fmnt%2FINTERNAL%2FSpotify%202%2FOldies%20spotify%2FRaindrops%20Keep%20Fallin'%20On%20My%20Head.mp3&metadata=false May 09 07:54:25 zero volumio[4662]: info: URI /mnt/INTERNAL/Spotify 2/Oldies spotify/Raindrops Keep Fallin' On My Head.mp3 May 09 07:54:25 zero volumio[4662]: info: ALBUMART /albumart?cacheid=381&web=Little%20River%20Band/Sleeper%20Catcher%20(Remastered)/extralarge&path=%2Fmnt%2FINTERNAL%2FSpotify%202%2FOldies%20spotify%2FReminiscing%20-%20Remastered.mp3&metadata=false May 09 07:54:25 zero volumio[4662]: info: URI /mnt/INTERNAL/Spotify 2/Oldies spotify/Reminiscing - Remastered.mp3 May 09 07:54:25 zero volumio[4662]: info: ALBUMART /albumart?cacheid=381&web=Christopher%20Cross/Christopher%20Cross/extralarge&path=%2Fmnt%2FINTERNAL%2FSpotify%202%2FOldies%20spotify%2FRide%20Like%20the%20Wind.mp3&metadata=false May 09 07:54:25 zero volumio[4662]: info: URI /mnt/INTERNAL/Spotify 2/Oldies spotify/Ride Like the Wind.mp3 May 09 07:54:25 zero volumio[4662]: info: ALBUMART /albumart?cacheid=381&web=Gerry%20Rafferty/City%20to%20City/extralarge&path=%2Fmnt%2FINTERNAL%2FSpotify%202%2FOldies%20spotify%2FRight%20Down%20the%20Line.mp3&metadata=false May 09 07:54:25 zero volumio[4662]: info: URI /mnt/INTERNAL/Spotify 2/Oldies spotify/Right Down the Line.mp3 May 09 07:54:25 zero volumio[4662]: info: ALBUMART /albumart?cacheid=381&web=Elton%20John/Honky%20Chateau/extralarge&path=%2Fmnt%2FINTERNAL%2FSpotify%202%2FOldies%20spotify%2FRocket%20Man%20(I%20Think%20It's%20Going%20To%20Be%20A%20Long%2C%20Long%20Time).mp3&metadata=false May 09 07:54:25 zero volumio[4662]: info: URI /mnt/INTERNAL/Spotify 2/Oldies spotify/Rocket Man (I Think It's Going To Be A Long, Long Time).mp3 May 09 07:54:25 zero volumio[4662]: info: ALBUMART /albumart?cacheid=381&web=Steve%20Forbert/Jack%20Rabbit%20Slim/extralarge&path=%2Fmnt%2FINTERNAL%2FSpotify%202%2FOldies%20spotify%2FRomeo's%20Tune.mp3&metadata=false May 09 07:54:25 zero volumio[4662]: info: URI /mnt/INTERNAL/Spotify 2/Oldies spotify/Romeo's Tune.mp3 May 09 07:54:25 zero volumio[4662]: info: ALBUMART /albumart?cacheid=381&web=TOTO/Toto%20IV/extralarge&path=%2Fmnt%2FINTERNAL%2FSpotify%202%2FOldies%20spotify%2FRosanna.mp3&metadata=false May 09 07:54:25 zero volumio[4662]: info: URI /mnt/INTERNAL/Spotify 2/Oldies spotify/Rosanna.mp3 May 09 07:54:25 zero volumio[4662]: info: ALBUMART /albumart?cacheid=381&web=Christopher%20Cross/Christopher%20Cross/extralarge&path=%2Fmnt%2FINTERNAL%2FSpotify%202%2FOldies%20spotify%2FSailing.mp3&metadata=false May 09 07:54:25 zero volumio[4662]: info: URI /mnt/INTERNAL/Spotify 2/Oldies spotify/Sailing.mp3 May 09 07:54:25 zero volumio[4662]: info: ALBUMART /albumart?cacheid=381&web=Scott%20McKenzie/The%20Graduate%20-%20Music%20From%20The%20Broadway%20Comedy/extralarge&path=%2Fmnt%2FINTERNAL%2FSpotify%202%2FOldies%20spotify%2FSan%20Francisco%20(Be%20Sure%20to%20Wear%20Flowers%20in%20Your%20Hair).mp3&metadata=false May 09 07:54:25 zero volumio[4662]: info: URI /mnt/INTERNAL/Spotify 2/Oldies spotify/San Francisco (Be Sure to Wear Flowers in Your Hair).mp3 May 09 07:54:25 zero volumio[4662]: info: ALBUMART /albumart?cacheid=381&web=Daryl%20Hall%20%26%20John%20Oates/Daryl%20Hall%20%26%20John%20Oates%20(The%20Silver%20Album)/extralarge&path=%2Fmnt%2FINTERNAL%2FSpotify%202%2FOldies%20spotify%2FSara%20Smile.mp3&metadata=false May 09 07:54:25 zero volumio[4662]: info: URI /mnt/INTERNAL/Spotify 2/Oldies spotify/Sara Smile.mp3 May 09 07:54:25 zero volumio[4662]: info: ALBUMART /albumart?cacheid=381&web=Bob%20Welch/French%20Kiss/extralarge&path=%2Fmnt%2FINTERNAL%2FSpotify%202%2FOldies%20spotify%2FSentimental%20Lady.mp3&metadata=false May 09 07:54:25 zero volumio[4662]: info: URI /mnt/INTERNAL/Spotify 2/Oldies spotify/Sentimental Lady.mp3 May 09 07:54:25 zero volumio[4662]: info: ALBUMART /albumart?cacheid=381&web=Dr.%20Hook/Completely%20Hooked/extralarge&path=%2Fmnt%2FINTERNAL%2FSpotify%202%2FOldies%20spotify%2FSexy%20Eyes%20-%20Single%20Version.mp3&metadata=false May 09 07:54:25 zero volumio[4662]: info: URI /mnt/INTERNAL/Spotify 2/Oldies spotify/Sexy Eyes - Single Version.mp3 May 09 07:54:25 zero volumio[4662]: info: ALBUMART /albumart?cacheid=381&web=Dr.%20Hook/Pleasure%20%26%20Pain/extralarge&path=%2Fmnt%2FINTERNAL%2FSpotify%202%2FOldies%20spotify%2FSharing%20The%20Night%20Together.mp3&metadata=false May 09 07:54:25 zero volumio[4662]: info: URI /mnt/INTERNAL/Spotify 2/Oldies spotify/Sharing The Night Together.mp3 May 09 07:54:25 zero volumio[4662]: info: ALBUMART /albumart?cacheid=381&web=Daryl%20Hall%20%26%20John%20Oates/Abandoned%20Luncheonette/extralarge&path=%2Fmnt%2FINTERNAL%2FSpotify%202%2FOldies%20spotify%2FShe's%20Gone.mp3&metadata=false May 09 07:54:25 zero volumio[4662]: info: URI /mnt/INTERNAL/Spotify 2/Oldies spotify/She's Gone.mp3 May 09 07:54:25 zero volumio[4662]: info: ALBUMART /albumart?cacheid=381&web=Wings/Wings%20At%20The%20Speed%20Of%20Sound%20(Archive%20Collection)/extralarge&path=%2Fmnt%2FINTERNAL%2FSpotify%202%2FOldies%20spotify%2FSilly%20Love%20Songs%20-%202014%20Remaster.mp3&metadata=false May 09 07:54:25 zero volumio[4662]: info: URI /mnt/INTERNAL/Spotify 2/Oldies spotify/Silly Love Songs - 2014 Remaster.mp3 May 09 07:54:25 zero volumio[4662]: info: ALBUMART /albumart?cacheid=381&web=America/Hearts/extralarge&path=%2Fmnt%2FINTERNAL%2FSpotify%202%2FOldies%20spotify%2FSister%20Golden%20Hair.mp3&metadata=false May 09 07:54:25 zero volumio[4662]: info: URI /mnt/INTERNAL/Spotify 2/Oldies spotify/Sister Golden Hair.mp3 May 09 07:54:25 zero volumio[4662]: info: ALBUMART /albumart?cacheid=381&web=The%20Hit%20Co.%2F%20The%20Tribute%20Co.%2FThe%20Hit%20Co./Best%20of%20the%20Beatles%20Box%20Set%20Vol.%202/extralarge&path=%2Fmnt%2FINTERNAL%2FSpotify%202%2FOldies%20spotify%2FSomething%20(In%20the%20Way%20She%20Moves).mp3&metadata=false May 09 07:54:25 zero volumio[4662]: info: URI /mnt/INTERNAL/Spotify 2/Oldies spotify/Something (In the Way She Moves).mp3 May 09 07:54:25 zero volumio[4662]: info: ALBUMART /albumart?cacheid=381&web=Rupert%20Holmes/'70s%20Pop%20%231's/extralarge&path=%2Fmnt%2FINTERNAL%2FSpotify%202%2FOldies%20spotify%2FSpotiDownloader.com%20-%20Oldies.zip&metadata=false May 09 07:54:25 zero volumio[4662]: info: URI /mnt/INTERNAL/Spotify 2/Oldies spotify/SpotiDownloader.com - Oldies.zip May 09 07:54:25 zero volumio[4662]: info: ALBUMART /albumart?cacheid=381&web=The%20Beatles/1%20(Remastered)/extralarge&path=%2Fmnt%2FINTERNAL%2FSpotify%202%2FOldies%20spotify%2FSpotiDownloader.com%20-%20Oldies.zip&metadata=false May 09 07:54:25 zero volumio[4662]: info: URI /mnt/INTERNAL/Spotify 2/Oldies spotify/SpotiDownloader.com - Oldies.zip May 09 07:54:25 zero volumio[4662]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 09 07:54:25 zero volumio[4662]: Error: Unable to resolve or reject the same promise twice May 09 07:54:25 zero volumio[4662]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) May 09 07:54:25 zero volumio[4662]: at /volumio/app/plugins/music_service/mpd/index.js:2569:21 May 09 07:54:25 zero volumio[4662]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) May 09 07:54:25 zero volumio[4662]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) May 09 07:54:25 zero volumio[4662]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) May 09 07:54:25 zero volumio[4662]: at Socket.emit (events.js:315:20) May 09 07:54:25 zero volumio[4662]: at addChunk (internal/streams/readable.js:309:12) May 09 07:54:25 zero volumio[4662]: at readableAddChunk (internal/streams/readable.js:280:11) May 09 07:54:25 zero volumio[4662]: at Socket.Readable.push (internal/streams/readable.js:223:10) May 09 07:54:25 zero volumio[4662]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) May 09 07:54:25 zero volumio[4662]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 09 07:54:26 zero sudo[5384]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-09 07:53 May 09 07:54:26 zero sudo[5384]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 30 Apr 2025 11:47:57 AM CEST" VOLUMIO_VERSION="3.807" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fddd10196af80fdc784c726185a6abe4"