-- Logs begin at Thu 2019-02-14 10:11:59 GMT, end at Tue 2025-01-21 20:35:57 GMT. --
Jan 21 20:34:00 volumio volumio[1042]: error: !!!! WARNING !!!!
Jan 21 20:34:00 volumio volumio[1042]: error: The plugin music_service/controradio failed to load, setting it to stopped. Error: Error: Cannot find module 'fast-xml-parser'
Jan 21 20:34:00 volumio volumio[1042]: Require stack:
Jan 21 20:34:00 volumio volumio[1042]: - /data/plugins/music_service/controradio/index.js
Jan 21 20:34:00 volumio volumio[1042]: - /volumio/app/pluginmanager.js
Jan 21 20:34:00 volumio volumio[1042]: - /volumio/app/index.js
Jan 21 20:34:00 volumio volumio[1042]: - /volumio/index.js
Jan 21 20:34:00 volumio volumio[1042]: error: Stack trace: Error: Cannot find module 'fast-xml-parser'
Jan 21 20:34:00 volumio volumio[1042]: Require stack:
Jan 21 20:34:00 volumio volumio[1042]: - /data/plugins/music_service/controradio/index.js
Jan 21 20:34:00 volumio volumio[1042]: - /volumio/app/pluginmanager.js
Jan 21 20:34:00 volumio volumio[1042]: - /volumio/app/index.js
Jan 21 20:34:00 volumio volumio[1042]: - /volumio/index.js
Jan 21 20:34:00 volumio volumio[1042]:     at Function.Module._resolveFilename (internal/modules/cjs/loader.js:880:15)
Jan 21 20:34:00 volumio volumio[1042]:     at Function.Module._load (internal/modules/cjs/loader.js:725:27)
Jan 21 20:34:00 volumio volumio[1042]:     at Module.require (internal/modules/cjs/loader.js:952:19)
Jan 21 20:34:00 volumio volumio[1042]:     at require (internal/modules/cjs/helpers.js:88:18)
Jan 21 20:34:00 volumio volumio[1042]:     at Object. (/data/plugins/music_service/controradio/index.js:5:21)
Jan 21 20:34:00 volumio volumio[1042]:     at Module._compile (internal/modules/cjs/loader.js:1063:30)
Jan 21 20:34:00 volumio volumio[1042]:     at Object.Module._extensions..js (internal/modules/cjs/loader.js:1092:10)
Jan 21 20:34:00 volumio volumio[1042]:     at Module.load (internal/modules/cjs/loader.js:928:32)
Jan 21 20:34:00 volumio volumio[1042]:     at Function.Module._load (internal/modules/cjs/loader.js:769:14)
Jan 21 20:34:00 volumio volumio[1042]:     at Module.require (internal/modules/cjs/loader.js:952:19)
Jan 21 20:34:00 volumio volumio[1042]:     at require (internal/modules/cjs/helpers.js:88:18)
Jan 21 20:34:00 volumio volumio[1042]:     at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:235:29)
Jan 21 20:34:00 volumio volumio[1042]:     at /volumio/app/pluginmanager.js:338:34
Jan 21 20:34:00 volumio volumio[1042]:     at Array.forEach ()
Jan 21 20:34:00 volumio volumio[1042]:     at HashMap. (/volumio/app/pluginmanager.js:337:20)
Jan 21 20:34:00 volumio volumio[1042]:     at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10)
Jan 21 20:34:00 volumio volumio[1042]: error: !!!! WARNING !!!!
Jan 21 20:34:00 volumio volumio[1042]: info: Loading plugin "dmd2_music"...
Jan 21 20:34:01 volumio volumio[1042]: info: Loading plugin "dstmmix"...
Jan 21 20:34:01 volumio volumio[1042]: info: Loading plugin "minidlna"...
Jan 21 20:34:01 volumio volumio[1042]: info: Applying required configuration parameters for plugin minidlna
Jan 21 20:34:01 volumio volumio[1042]: info: Loading plugin "moosbox"...
Jan 21 20:34:01 volumio volumio[1042]: info: Applying required configuration parameters for plugin moosbox
Jan 21 20:34:01 volumio volumio[1042]: info: Loading plugin "motherearthradio"...
Jan 21 20:34:01 volumio volumio[1042]: info: Applying required configuration parameters for plugin motherearthradio
Jan 21 20:34:01 volumio volumio[1042]: info: [1737491641848] [MotherEarth] API delay: 5
Jan 21 20:34:01 volumio volumio[1042]: info: Loading plugin "myb"...
Jan 21 20:34:01 volumio sudo[1113]: pam_unix(sudo:session): session closed for user root
Jan 21 20:34:01 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation.
Jan 21 20:34:01 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -113
Jan 21 20:34:02 volumio volumio[1042]: info: Applying required configuration parameters for plugin myb
Jan 21 20:34:02 volumio volumio[1042]: info: Loading plugin "personal_radio"...
Jan 21 20:34:02 volumio volumio[1042]: info: ControllerPersonalRadio::constructor
Jan 21 20:34:02 volumio volumio[1042]: info: Loading plugin "podcast"...
Jan 21 20:34:02 volumio volumio[1042]: info: ControllerPodcast::constructor
Jan 21 20:34:02 volumio volumio[1042]: info: Loading plugin "rad357pl"...
Jan 21 20:34:02 volumio volumio[1042]: info: Applying required configuration parameters for plugin rad357pl
Jan 21 20:34:02 volumio volumio[1042]: info: [1737491642909] [rad357pl] API delay: 5
Jan 21 20:34:02 volumio volumio[1042]: info: Loading plugin "radio_paradise"...
Jan 21 20:34:03 volumio volumio[1042]: info: Applying required configuration parameters for plugin radio_paradise
Jan 21 20:34:03 volumio volumio[1042]: info: [1737491643103] [RadioParadise] API delay: 5
Jan 21 20:34:03 volumio volumio[1042]: info: Loading plugin "volusonic"...
Jan 21 20:34:03 volumio volumio[1042]: info: Applying required configuration parameters for plugin volusonic
Jan 21 20:34:03 volumio volumio[1042]: info: Loading i18n strings for locale en
Jan 21 20:34:03 volumio volumio[1042]: Updating browse sources language
Jan 21 20:34:03 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jan 21 20:34:03 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jan 21 20:34:03 volumio volumio[1042]: info: CoreCommandRouter::initPlayerControls
Jan 21 20:34:03 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 21 20:34:03 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 21 20:34:03 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 21 20:34:03 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 21 20:34:03 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 21 20:34:03 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 21 20:34:03 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 21 20:34:03 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 21 20:34:03 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jan 21 20:34:03 volumio volumio[1042]: Express server listening on port 3000
Jan 21 20:34:03 volumio volumio[1042]:  [Metrics]  WebUI:  17s 388.15ms 
Jan 21 20:34:04 volumio volumio[1042]: info: CoreStateMachine::resetVolumioState
Jan 21 20:34:04 volumio volumio[1042]: info: CoreStateMachine::getcurrentVolume
Jan 21 20:34:04 volumio volumio[1042]: info: CoreCommandRouter::volumioRetrievevolume
Jan 21 20:34:04 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 21 20:34:04 volumio sudo[1233]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Jan 21 20:34:04 volumio sudo[1233]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 21 20:34:04 volumio sudo[1233]: pam_unix(sudo:session): session closed for user root
Jan 21 20:34:04 volumio sudo[1236]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Jan 21 20:34:04 volumio sudo[1236]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 21 20:34:04 volumio sudo[1236]: pam_unix(sudo:session): session closed for user root
Jan 21 20:34:04 volumio volumio[1042]: info: Volumio Network Manager: Network status updated: 2
Jan 21 20:34:04 volumio volumio[1042]: info: Cannot mount NAS LOCAL at system boot, trial number 1 ,retrying in 5 seconds
Jan 21 20:34:04 volumio volumio[1042]: info: Cannot mount NAS extra_music at system boot, trial number 1 ,retrying in 5 seconds
Jan 21 20:34:04 volumio volumio[1042]: info: Cannot mount NAS Kharisstore at system boot, trial number 1 ,retrying in 5 seconds
Jan 21 20:34:04 volumio volumio[1042]: info: VolumeController:: Volume=77 Mute =false
Jan 21 20:34:04 volumio volumio[1042]: info: CoreStateMachine::pushState
Jan 21 20:34:04 volumio volumio[1042]: info: CorePlayQueue::getTrack 0
Jan 21 20:34:04 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jan 21 20:34:04 volumio volumio[1042]: info: CoreCommandRouter::volumioPushState
Jan 21 20:34:04 volumio volumio[1042]: info: CoreStateMachine::updateTrackBlock
Jan 21 20:34:04 volumio volumio[1042]: info: CorePlayQueue::getTrackBlock
Jan 21 20:34:04 volumio volumio[1042]: info: CoreCommandRouter::volumioRetrievevolume
Jan 21 20:34:04 volumio volumio[1042]: info: Reloading queue from file
Jan 21 20:34:04 volumio volumio[1042]: info: Setting Device type: Raspberry PI
Jan 21 20:34:04 volumio volumio[1042]: info: CoreStateMachine::setRepeat null single undefined
Jan 21 20:34:04 volumio volumio[1042]: info: CoreStateMachine::pushState
Jan 21 20:34:04 volumio volumio[1042]: info: CorePlayQueue::getTrack 0
Jan 21 20:34:04 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jan 21 20:34:04 volumio volumio[1042]: info: CoreCommandRouter::volumioPushState
Jan 21 20:34:04 volumio volumio[1042]: info: CoreStateMachine::setRandom null
Jan 21 20:34:04 volumio volumio[1042]: info: CoreStateMachine::pushState
Jan 21 20:34:04 volumio volumio[1042]: info: CorePlayQueue::getTrack 0
Jan 21 20:34:04 volumio volumio[1042]: info: CoreCommandRouter::volumioPushState
Jan 21 20:34:04 volumio volumio[1042]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb
Jan 21 20:34:04 volumio volumio[1042]: info: USB Boot Capable - System SBC Revision found in cpuinfo:  d03114
Jan 21 20:34:04 volumio volumio[1042]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI
Jan 21 20:34:04 volumio volumio[1042]: info: Discovery: adding efac66ae-a6cc-466e-adcc-7de75fc5aef6
Jan 21 20:34:04 volumio volumio[1042]: info: Discovery: Found device Volumio
Jan 21 20:34:04 volumio volumio[1042]: info: CoreCommandRouter::volumioGetState
Jan 21 20:34:04 volumio volumio[1042]: info: CorePlayQueue::getTrack 0
Jan 21 20:34:04 volumio volumio[1042]: info: VolumeController:: Volume=77 Mute =false
Jan 21 20:34:04 volumio volumio[1042]: info: CoreStateMachine::pushState
Jan 21 20:34:04 volumio volumio[1042]: info: CorePlayQueue::getTrack 0
Jan 21 20:34:04 volumio volumio[1042]: info: CoreCommandRouter::volumioPushState
Jan 21 20:34:04 volumio sudo[1247]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service
Jan 21 20:34:04 volumio sudo[1247]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 21 20:34:04 volumio volumio[1042]: info: Completed loading Core Plugins
Jan 21 20:34:04 volumio volumio[1042]: info: Preparing to generate the ALSA configuration file
Jan 21 20:34:04 volumio volumio[1042]: info: Asound.conf file unchanged, so no further update is needed
Jan 21 20:34:04 volumio volumio[1042]: info: Output device has changed, restarting MPD
Jan 21 20:34:04 volumio sudo[1251]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Jan 21 20:34:04 volumio sudo[1251]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 21 20:34:04 volumio volumio[1042]: info: Output device has changed, restarting Shairport Sync
Jan 21 20:34:04 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 21 20:34:04 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 21 20:34:04 volumio sudo[1251]: pam_unix(sudo:session): session closed for user root
Jan 21 20:34:04 volumio sudo[1254]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Jan 21 20:34:04 volumio sudo[1254]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 21 20:34:04 volumio systemd[1]: Started UPnP Renderer front-end to MPD.
Jan 21 20:34:04 volumio sudo[1247]: pam_unix(sudo:session): session closed for user root
Jan 21 20:34:04 volumio systemd[1]: Stopping Music Player Daemon...
Jan 21 20:34:04 volumio volumio[1042]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Jan 21 20:34:04 volumio volumio[1042]: info: ___________ START PLUGINS ___________
Jan 21 20:34:04 volumio volumio[1042]: info: ControllerMpd::onStart: Initializing MPD
Jan 21 20:34:04 volumio volumio[1042]: info: Creating MPD Configuration file
Jan 21 20:34:04 volumio sudo[1260]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Jan 21 20:34:04 volumio sudo[1260]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 21 20:34:04 volumio sudo[1260]: pam_unix(sudo:session): session closed for user root
Jan 21 20:34:04 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jan 21 20:34:04 volumio volumio[1042]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Jan 21 20:34:04 volumio volumio[1042]: info: [1737491644519] CoreMusicLibrary::Adding element Media Servers
Jan 21 20:34:04 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jan 21 20:34:04 volumio sudo[1263]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Jan 21 20:34:04 volumio sudo[1263]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 21 20:34:04 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 21 20:34:04 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 21 20:34:04 volumio systemd[1]: mpd.service: Succeeded.
Jan 21 20:34:04 volumio systemd[1]: Stopped Music Player Daemon.
Jan 21 20:34:04 volumio systemd[1]: Starting Music Player Daemon...
Jan 21 20:34:04 volumio volumio[1042]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Jan 21 20:34:04 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 21 20:34:04 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 21 20:34:04 volumio volumio-remote-updater[629]: [2025-01-21 20:34:04] [connect] Successful connection
Jan 21 20:34:04 volumio volumio[1042]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Jan 21 20:34:04 volumio sudo[1266]:     root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Jan 21 20:34:04 volumio sudo[1266]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 21 20:34:04 volumio volumio[1042]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Jan 21 20:34:04 volumio volumio[1042]: info: [1737491644629] CoreMusicLibrary::Adding element Last_100
Jan 21 20:34:04 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jan 21 20:34:04 volumio volumio[1042]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Jan 21 20:34:04 volumio volumio[1042]: info: [1737491644632] CoreMusicLibrary::Adding element Webradio
Jan 21 20:34:04 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jan 21 20:34:04 volumio sudo[1266]: pam_unix(sudo:session): session closed for user root
Jan 21 20:34:04 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jan 21 20:34:04 volumio volumio[1042]: info: Initializing BBC Radios
Jan 21 20:34:04 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jan 21 20:34:04 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 21 20:34:04 volumio volumio[1042]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Jan 21 20:34:04 volumio volumio[1042]: info: [1737491644720] CoreMusicLibrary::Adding element Bandcamp Discover
Jan 21 20:34:04 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jan 21 20:34:04 volumio volumio[1042]: Cannot find translation for source Bandcamp Discover
Jan 21 20:34:04 volumio volumio[1042]: info: Adding Calm Radio to Browse Sources
Jan 21 20:34:04 volumio volumio[1042]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Jan 21 20:34:04 volumio volumio[1042]: info: [1737491644724] CoreMusicLibrary::Adding element Calm Radio
Jan 21 20:34:04 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jan 21 20:34:04 volumio volumio[1042]: Cannot find translation for source Bandcamp Discover
Jan 21 20:34:04 volumio volumio[1042]: Cannot find translation for source Calm Radio
Jan 21 20:34:04 volumio volumio[1042]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Jan 21 20:34:04 volumio volumio[1042]: info: [1737491644733] CoreMusicLibrary::Adding element SoundCloud
Jan 21 20:34:04 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jan 21 20:34:04 volumio volumio[1042]: Cannot find translation for source Bandcamp Discover
Jan 21 20:34:04 volumio volumio[1042]: Cannot find translation for source Calm Radio
Jan 21 20:34:04 volumio volumio[1042]: Cannot find translation for source SoundCloud
Jan 21 20:34:04 volumio volumio[1042]: info: Creating Spotify config file
Jan 21 20:34:04 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 21 20:34:04 volumio volumio[1042]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Jan 21 20:34:04 volumio volumio[1042]: info: [1737491644844] CoreMusicLibrary::Adding element YouTube Music
Jan 21 20:34:04 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jan 21 20:34:04 volumio volumio[1042]: Cannot find translation for source Bandcamp Discover
Jan 21 20:34:04 volumio volumio[1042]: Cannot find translation for source Calm Radio
Jan 21 20:34:04 volumio volumio[1042]: Cannot find translation for source SoundCloud
Jan 21 20:34:04 volumio volumio[1042]: Cannot find translation for source YouTube Music
Jan 21 20:34:04 volumio volumio[1042]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Jan 21 20:34:04 volumio volumio[1042]: info: [1737491644852] CoreMusicLibrary::Adding element 80s80s Radio
Jan 21 20:34:04 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jan 21 20:34:04 volumio volumio[1042]: Cannot find translation for source Bandcamp Discover
Jan 21 20:34:04 volumio volumio[1042]: Cannot find translation for source Calm Radio
Jan 21 20:34:04 volumio volumio[1042]: Cannot find translation for source SoundCloud
Jan 21 20:34:04 volumio volumio[1042]: Cannot find translation for source YouTube Music
Jan 21 20:34:04 volumio volumio[1042]: Cannot find translation for source 80s80s Radio
Jan 21 20:34:04 volumio volumio[1042]: info: Stopping AccessToken refresher cron for DMD2 Music
Jan 21 20:34:04 volumio sudo[1285]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service
Jan 21 20:34:04 volumio sudo[1285]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 21 20:34:04 volumio volumio[1042]: info: AccessToken refresher cron started for DMD2 Music
Jan 21 20:34:04 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jan 21 20:34:04 volumio volumio[1042]: info: Loading i18n strings for locale en
Jan 21 20:34:04 volumio sudo[1289]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart logitechmediaserver
Jan 21 20:34:04 volumio sudo[1289]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 21 20:34:04 volumio systemd[1]: Started RoonBridge.
Jan 21 20:34:04 volumio volumio[1042]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Jan 21 20:34:04 volumio volumio[1042]: info: [1737491644984] CoreMusicLibrary::Adding element Mother Earth Radio
Jan 21 20:34:04 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jan 21 20:34:04 volumio volumio[1042]: Cannot find translation for source Bandcamp Discover
Jan 21 20:34:04 volumio volumio[1042]: Cannot find translation for source Calm Radio
Jan 21 20:34:04 volumio volumio[1042]: Cannot find translation for source SoundCloud
Jan 21 20:34:04 volumio volumio[1042]: Cannot find translation for source YouTube Music
Jan 21 20:34:04 volumio volumio[1042]: Cannot find translation for source 80s80s Radio
Jan 21 20:34:04 volumio volumio[1042]: Cannot find translation for source Mother Earth Radio
Jan 21 20:34:04 volumio sudo[1285]: pam_unix(sudo:session): session closed for user root
Jan 21 20:34:04 volumio systemd[1]: Stopping Logitech Media Server...
Jan 21 20:34:05 volumio volumio[1042]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Jan 21 20:34:05 volumio volumio[1042]: info: [1737491645014] CoreMusicLibrary::Adding element Personal Radio
Jan 21 20:34:05 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jan 21 20:34:05 volumio volumio[1042]: Cannot find translation for source Bandcamp Discover
Jan 21 20:34:05 volumio volumio[1042]: Cannot find translation for source Calm Radio
Jan 21 20:34:05 volumio volumio[1042]: Cannot find translation for source SoundCloud
Jan 21 20:34:05 volumio volumio[1042]: Cannot find translation for source YouTube Music
Jan 21 20:34:05 volumio volumio[1042]: Cannot find translation for source 80s80s Radio
Jan 21 20:34:05 volumio volumio[1042]: Cannot find translation for source Mother Earth Radio
Jan 21 20:34:05 volumio volumio[1042]: Cannot find translation for source Personal Radio
Jan 21 20:34:05 volumio volumio[1042]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Jan 21 20:34:05 volumio volumio[1042]: info: [1737491645052] CoreMusicLibrary::Adding element Podcast
Jan 21 20:34:05 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jan 21 20:34:05 volumio volumio[1042]: Cannot find translation for source Bandcamp Discover
Jan 21 20:34:05 volumio volumio[1042]: Cannot find translation for source Calm Radio
Jan 21 20:34:05 volumio volumio[1042]: Cannot find translation for source SoundCloud
Jan 21 20:34:05 volumio volumio[1042]: Cannot find translation for source YouTube Music
Jan 21 20:34:05 volumio volumio[1042]: Cannot find translation for source 80s80s Radio
Jan 21 20:34:05 volumio volumio[1042]: Cannot find translation for source Mother Earth Radio
Jan 21 20:34:05 volumio volumio[1042]: Cannot find translation for source Personal Radio
Jan 21 20:34:05 volumio volumio[1042]: Cannot find translation for source Podcast
Jan 21 20:34:05 volumio volumio[1042]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Jan 21 20:34:05 volumio volumio[1042]: info: [1737491645073] CoreMusicLibrary::Adding element RADIO 357
Jan 21 20:34:05 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jan 21 20:34:05 volumio volumio[1042]: Cannot find translation for source Bandcamp Discover
Jan 21 20:34:05 volumio volumio[1042]: Cannot find translation for source Calm Radio
Jan 21 20:34:05 volumio volumio[1042]: Cannot find translation for source SoundCloud
Jan 21 20:34:05 volumio volumio[1042]: Cannot find translation for source YouTube Music
Jan 21 20:34:05 volumio volumio[1042]: Cannot find translation for source 80s80s Radio
Jan 21 20:34:05 volumio volumio[1042]: Cannot find translation for source Mother Earth Radio
Jan 21 20:34:05 volumio volumio[1042]: Cannot find translation for source Personal Radio
Jan 21 20:34:05 volumio volumio[1042]: Cannot find translation for source Podcast
Jan 21 20:34:05 volumio volumio[1042]: Cannot find translation for source RADIO 357
Jan 21 20:34:05 volumio volumio[1042]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Jan 21 20:34:05 volumio volumio[1042]: info: [1737491645094] CoreMusicLibrary::Adding element Radio Paradise
Jan 21 20:34:05 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jan 21 20:34:05 volumio volumio[1042]: Cannot find translation for source Bandcamp Discover
Jan 21 20:34:05 volumio volumio[1042]: Cannot find translation for source Calm Radio
Jan 21 20:34:05 volumio volumio[1042]: Cannot find translation for source SoundCloud
Jan 21 20:34:05 volumio volumio[1042]: Cannot find translation for source YouTube Music
Jan 21 20:34:05 volumio volumio[1042]: Cannot find translation for source 80s80s Radio
Jan 21 20:34:05 volumio volumio[1042]: Cannot find translation for source Mother Earth Radio
Jan 21 20:34:05 volumio volumio[1042]: Cannot find translation for source Personal Radio
Jan 21 20:34:05 volumio volumio[1042]: Cannot find translation for source Podcast
Jan 21 20:34:05 volumio volumio[1042]: Cannot find translation for source RADIO 357
Jan 21 20:34:05 volumio volumio[1042]: Cannot find translation for source Radio Paradise
Jan 21 20:34:05 volumio systemd[1]: logitechmediaserver.service: Succeeded.
Jan 21 20:34:05 volumio systemd[1]: Stopped Logitech Media Server.
Jan 21 20:34:05 volumio systemd[1]: Started Logitech Media Server.
Jan 21 20:34:05 volumio sudo[1289]: pam_unix(sudo:session): session closed for user root
Jan 21 20:34:05 volumio volumio[1042]: info: Loading i18n strings for locale en
Jan 21 20:34:05 volumio volumio[1042]: Updating browse sources language
Jan 21 20:34:05 volumio volumio[1042]: Cannot find translation for source Bandcamp Discover
Jan 21 20:34:05 volumio volumio[1042]: Cannot find translation for source Calm Radio
Jan 21 20:34:05 volumio volumio[1042]: Cannot find translation for source SoundCloud
Jan 21 20:34:05 volumio volumio[1042]: Cannot find translation for source YouTube Music
Jan 21 20:34:05 volumio volumio[1042]: Cannot find translation for source 80s80s Radio
Jan 21 20:34:05 volumio volumio[1042]: Cannot find translation for source Mother Earth Radio
Jan 21 20:34:05 volumio volumio[1042]: Cannot find translation for source Personal Radio
Jan 21 20:34:05 volumio volumio[1042]: Cannot find translation for source Podcast
Jan 21 20:34:05 volumio volumio[1042]: Cannot find translation for source RADIO 357
Jan 21 20:34:05 volumio volumio[1042]: Cannot find translation for source Radio Paradise
Jan 21 20:34:05 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jan 21 20:34:05 volumio volumio[1042]: Cannot find translation for source Bandcamp Discover
Jan 21 20:34:05 volumio volumio[1042]: Cannot find translation for source Calm Radio
Jan 21 20:34:05 volumio volumio[1042]: Cannot find translation for source SoundCloud
Jan 21 20:34:05 volumio volumio[1042]: Cannot find translation for source YouTube Music
Jan 21 20:34:05 volumio volumio[1042]: Cannot find translation for source 80s80s Radio
Jan 21 20:34:05 volumio volumio[1042]: Cannot find translation for source Mother Earth Radio
Jan 21 20:34:05 volumio volumio[1042]: Cannot find translation for source Personal Radio
Jan 21 20:34:05 volumio volumio[1042]: Cannot find translation for source Podcast
Jan 21 20:34:05 volumio volumio[1042]: Cannot find translation for source RADIO 357
Jan 21 20:34:05 volumio volumio[1042]: Cannot find translation for source Radio Paradise
Jan 21 20:34:05 volumio volumio[1042]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Jan 21 20:34:05 volumio volumio[1042]: info: [1737491645177] CoreMusicLibrary::Adding element Volusonic
Jan 21 20:34:05 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jan 21 20:34:05 volumio volumio[1042]: Cannot find translation for source Bandcamp Discover
Jan 21 20:34:05 volumio volumio[1042]: Cannot find translation for source Calm Radio
Jan 21 20:34:05 volumio volumio[1042]: Cannot find translation for source SoundCloud
Jan 21 20:34:05 volumio volumio[1042]: Cannot find translation for source YouTube Music
Jan 21 20:34:05 volumio volumio[1042]: Cannot find translation for source 80s80s Radio
Jan 21 20:34:05 volumio volumio[1042]: Cannot find translation for source Mother Earth Radio
Jan 21 20:34:05 volumio volumio[1042]: Cannot find translation for source Personal Radio
Jan 21 20:34:05 volumio volumio[1042]: Cannot find translation for source Podcast
Jan 21 20:34:05 volumio volumio[1042]: Cannot find translation for source RADIO 357
Jan 21 20:34:05 volumio volumio[1042]: Cannot find translation for source Radio Paradise
Jan 21 20:34:05 volumio volumio[1042]: Cannot find translation for source Volusonic
Jan 21 20:34:05 volumio volumio[1042]: info: Volumio Calling Home
Jan 21 20:34:05 volumio volumio[1042]: info: minidlna: Found miniDLNA version 1.2.1
Jan 21 20:34:05 volumio volumio[1042]: info: minidlna: Starting minidlna.service
Jan 21 20:34:05 volumio volumio[1042]: info: logitechmediaserver started
Jan 21 20:34:05 volumio sudo[1306]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service
Jan 21 20:34:05 volumio sudo[1306]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 21 20:34:05 volumio volumio[1042]: info: MPD Permissions set
Jan 21 20:34:05 volumio volumio[1042]: info: Upmpdcli Daemon Started
Jan 21 20:34:05 volumio volumio-remote-updater[629]: [2025-01-21 20:34:05] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1737491644 101
Jan 21 20:34:05 volumio volumio[1042]: 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: 1
Jan 21 20:34:05 volumio volumio[1042]: info: Spotify config file written
Jan 21 20:34:05 volumio sudo[1316]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service
Jan 21 20:34:05 volumio systemd[1]: Started MiniDLNA lightweight DLNA/UPnP-AV server.
Jan 21 20:34:05 volumio sudo[1316]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 21 20:34:05 volumio volumio[1042]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2
Jan 21 20:34:05 volumio sudo[1306]: pam_unix(sudo:session): session closed for user root
Jan 21 20:34:05 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 21 20:34:05 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 21 20:34:05 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 21 20:34:05 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 21 20:34:05 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 21 20:34:05 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 21 20:34:05 volumio mpd[1273]: Jan 21 20:34 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Jan 21 20:34:05 volumio systemd[1]: Started go-librespot Daemon.
Jan 21 20:34:05 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 21 20:34:05 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 21 20:34:05 volumio systemd[1]: Started Music Player Daemon.
Jan 21 20:34:05 volumio sudo[1254]: pam_unix(sudo:session): session closed for user root
Jan 21 20:34:05 volumio sudo[1263]: pam_unix(sudo:session): session closed for user root
Jan 21 20:34:05 volumio go-librespot[1318]: Librespot-go daemon starting...
Jan 21 20:34:05 volumio sudo[1316]: pam_unix(sudo:session): session closed for user root
Jan 21 20:34:05 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 21 20:34:05 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 21 20:34:05 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 21 20:34:05 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 21 20:34:05 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 21 20:34:05 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 21 20:34:05 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 21 20:34:05 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 21 20:34:05 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 21 20:34:05 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 21 20:34:05 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 21 20:34:05 volumio volumio[1042]: info: No need to fix Spotify hosts
Jan 21 20:34:05 volumio volumio[1042]: info: minidlna: systemctl start minidlna.service succeeded.
Jan 21 20:34:05 volumio volumio[1042]: info: Starting Shairport Sync
Jan 21 20:34:05 volumio volumio[1042]: info: Starting Shairport Sync
Jan 21 20:34:05 volumio sudo[1344]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Jan 21 20:34:05 volumio sudo[1344]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 21 20:34:05 volumio volumio[1042]: info: Starting Shairport Sync
Jan 21 20:34:05 volumio sudo[1347]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Jan 21 20:34:05 volumio sudo[1347]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 21 20:34:05 volumio sudo[1350]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Jan 21 20:34:05 volumio sudo[1350]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 21 20:34:05 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Jan 21 20:34:05 volumio go-librespot[1318]: time="2025-01-21T20:34:05Z" level=info msg="generated new device id: 3a68f133392348a2582afe93ba2240da829df270"
Jan 21 20:34:06 volumio go-librespot[1318]: time="2025-01-21T20:34:06Z" level=debug msg="stored credentials found for g8r2fyu3io9e3yr0xe56j4nn7"
Jan 21 20:34:06 volumio systemd[1]: shairport-sync.service: Succeeded.
Jan 21 20:34:06 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Jan 21 20:34:06 volumio volumio[1042]: error: MPD error: The expression evaluated to a falsy value:
Jan 21 20:34:06 volumio volumio[1042]:   assert.ok(self.idling)
Jan 21 20:34:06 volumio volumio[1042]: error: The expression evaluated to a falsy value:
Jan 21 20:34:06 volumio volumio[1042]:   assert.ok(self.idling)
Jan 21 20:34:06 volumio volumio[1042]: info: CoreCommandRouter::volumioGetState
Jan 21 20:34:06 volumio volumio[1042]: info: CorePlayQueue::getTrack 0
Jan 21 20:34:06 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Jan 21 20:34:06 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Jan 21 20:34:06 volumio sudo[1347]: pam_unix(sudo:session): session closed for user root
Jan 21 20:34:06 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM
Jan 21 20:34:06 volumio systemd[1]: shairport-sync.service: Succeeded.
Jan 21 20:34:06 volumio sudo[1344]: pam_unix(sudo:session): session closed for user root
Jan 21 20:34:06 volumio volumio[1042]: info: MPD running with PID1273
Jan 21 20:34:06 volumio volumio[1042]:  ,establishing connection
Jan 21 20:34:06 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Jan 21 20:34:06 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Jan 21 20:34:06 volumio sudo[1350]: pam_unix(sudo:session): session closed for user root
Jan 21 20:34:06 volumio volumio[1042]: error: updateQueue error: null
Jan 21 20:34:06 volumio volumio[1042]: info: Shairport-Sync Started
Jan 21 20:34:06 volumio volumio[1042]: Error adding Membership: Error: addMembership EINVAL
Jan 21 20:34:06 volumio volumio[1042]: info: Shairport-Sync Started
Jan 21 20:34:06 volumio volumio[1042]: info: Shairport-Sync Started
Jan 21 20:34:06 volumio volumio[1042]: (node:1042) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead.
Jan 21 20:34:06 volumio volumio[1042]: (Use `node --trace-deprecation ...` to show where the warning was created)
Jan 21 20:34:06 volumio volumio[1042]: (node:1042) [DEP0106] DeprecationWarning: crypto.createDecipher is deprecated.
Jan 21 20:34:06 volumio volumio[1042]: error: updateQueue error: null
Jan 21 20:34:06 volumio volumio[1042]: info: Volumio called home
Jan 21 20:34:06 volumio volumio[1249]: Generating RSA private key, 4096 bit long modulus (2 primes)
Jan 21 20:34:06 volumio RoonBridge[1287]: 00:00:00.012 Warn:  get lock file path: /tmp/.rnbgem0-
Jan 21 20:34:06 volumio volumio[1042]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------
Jan 21 20:34:06 volumio volumio[1042]: SPOTIFY: BQDCYgNubXdqzB9Fkmc4uu3e2hf772NAIAQXLBEMx_-rql4zRya1xnEEN3buk4E5DSUVonwIQzVQuRW8nNziDaWmv6R_JZc76i8-1Umykd4cZvLY8F_eBtP26QzpCSDLsA2-NXBMkXRNVUbAmdY0LpWPDPVSYLUQmCMgsFmqJ4F0JqL6JTwZ02F68JaNe7B7efLsT4fDtE-xDlqJ4hvN2ajp0u4csbebEIu-67GXeW2qemuRgkt9jAs19iMqJxB4qfMb9nOaCV4
Jan 21 20:34:06 volumio volumio[1042]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------
Jan 21 20:34:06 volumio volumio[1042]: info: New Spotify access token = BQDCYgNubXdqzB9Fkmc4uu3e2hf772NAIAQXLBEMx_-rql4zRya1xnEEN3buk4E5DSUVonwIQzVQuRW8nNziDaWmv6R_JZc76i8-1Umykd4cZvLY8F_eBtP26QzpCSDLsA2-NXBMkXRNVUbAmdY0LpWPDPVSYLUQmCMgsFmqJ4F0JqL6JTwZ02F68JaNe7B7efLsT4fDtE-xDlqJ4hvN2ajp0u4csbebEIu-67GXeW2qemuRgkt9jAs19iMqJxB4qfMb9nOaCV4
Jan 21 20:34:06 volumio volumio[1042]: info: Spotify credentials grant success - running version from March 24, 2019
Jan 21 20:34:06 volumio RoonBridge[1287]: 00:00:00.416 Trace: [childprocess] using unix child process
Jan 21 20:34:06 volumio go-librespot[1318]: time="2025-01-21T20:34:06Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]"
Jan 21 20:34:06 volumio go-librespot[1318]: time="2025-01-21T20:34:06Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]"
Jan 21 20:34:06 volumio go-librespot[1318]: time="2025-01-21T20:34:06Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]"
Jan 21 20:34:06 volumio go-librespot[1318]: time="2025-01-21T20:34:06Z" level=debug msg="zeroconf server listening on port 36057"
Jan 21 20:34:06 volumio RoonBridge[1287]: 00:00:00.483 Debug: PathForResource, filename: ../.update
Jan 21 20:34:06 volumio RoonBridge[1287]: 00:00:00.569 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/../.update
Jan 21 20:34:06 volumio RoonBridge[1287]: 00:00:00.576 Debug: PathForResource, filename: RAATServer
Jan 21 20:34:06 volumio RoonBridge[1287]: 00:00:00.576 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer
Jan 21 20:34:06 volumio RoonBridge[1287]: 00:00:00.577 Debug: PathForResource, filename: RoonBridgeHelper
Jan 21 20:34:06 volumio RoonBridge[1287]: 00:00:00.577 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper
Jan 21 20:34:06 volumio RoonBridge[1287]: Initializing
Jan 21 20:34:06 volumio RoonBridge[1287]: 00:00:00.622 Info:  Starting /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper
Jan 21 20:34:06 volumio RoonBridge[1287]: 00:00:00.670 Info:  ConnectOrStartAndWaitForExit RAATServer, path: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer
Jan 21 20:34:07 volumio volumio[1042]: SPOTIFY: User informations: {"country":"GH","display_name":"kharis Home","email":"kharishome@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/g8r2fyu3io9e3yr0xe56j4nn7"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/g8r2fyu3io9e3yr0xe56j4nn7","id":"g8r2fyu3io9e3yr0xe56j4nn7","images":[],"product":"free","type":"user","uri":"spotify:user:g8r2fyu3io9e3yr0xe56j4nn7"}
Jan 21 20:34:07 volumio RoonBridge[1287]: Not Running (.o)
Jan 21 20:34:07 volumio volumio[1042]: info: Spotify Successfully logged in
Jan 21 20:34:07 volumio volumio[1042]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Jan 21 20:34:07 volumio volumio[1042]: info: [1737491647046] CoreMusicLibrary::Adding element Spotify
Jan 21 20:34:07 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jan 21 20:34:07 volumio volumio[1042]: Cannot find translation for source Bandcamp Discover
Jan 21 20:34:07 volumio volumio[1042]: Cannot find translation for source Calm Radio
Jan 21 20:34:07 volumio volumio[1042]: Cannot find translation for source SoundCloud
Jan 21 20:34:07 volumio volumio[1042]: Cannot find translation for source YouTube Music
Jan 21 20:34:07 volumio volumio[1042]: Cannot find translation for source 80s80s Radio
Jan 21 20:34:07 volumio volumio[1042]: Cannot find translation for source Mother Earth Radio
Jan 21 20:34:07 volumio volumio[1042]: Cannot find translation for source Personal Radio
Jan 21 20:34:07 volumio volumio[1042]: Cannot find translation for source Podcast
Jan 21 20:34:07 volumio volumio[1042]: Cannot find translation for source RADIO 357
Jan 21 20:34:07 volumio volumio[1042]: Cannot find translation for source Radio Paradise
Jan 21 20:34:07 volumio volumio[1042]: Cannot find translation for source Volusonic
Jan 21 20:34:07 volumio volumio[1042]: Cannot find translation for source Spotify
Jan 21 20:34:07 volumio RoonBridge[1287]: Running
Jan 21 20:34:07 volumio go-librespot[1318]: time="2025-01-21T20:34:07Z" level=debug msg="obtained new client token: AADA0m+Ea8UK9rMJsLM/3HGds2++sFDmtFRuDk3RAMPM8VhtFzU/Bgw67+9H9uI8injZ8zU7/pX+7+YbNzu6Rrz3woovaytomCoTVJbLWL4XlF/mQz1sgET4IROEQKdl7f8lWqz+2QIInBrqNFe8kq+Rfae1HsVb+XTQ43/f3EEJQHb34aQRnLs4497AVhHJ4YHjqpVmGMar4i8JijkbL8AdEyIaXZH54HwHyejTPzcXX0HSHvi96YKT/outOw=="
Jan 21 20:34:07 volumio volumio[1042]: info: [yt-cast-receiver] DIAL server listening on port 8098
Jan 21 20:34:07 volumio volumio[1042]: info: CoreCommandRouter::volumioRetrievevolume
Jan 21 20:34:07 volumio go-librespot[1318]: time="2025-01-21T20:34:07Z" level=info msg="connected to ap-gew1.spotify.com:4070"
Jan 21 20:34:07 volumio volumio[1042]: info: VolumeController:: Volume=77 Mute =false
Jan 21 20:34:07 volumio volumio[1042]: info: CoreCommandRouter::volumioGetState
Jan 21 20:34:07 volumio volumio[1042]: info: CorePlayQueue::getTrack 0
Jan 21 20:34:07 volumio volumio[1042]: info: CoreStateMachine::pushState
Jan 21 20:34:07 volumio volumio[1042]: info: CorePlayQueue::getTrack 0
Jan 21 20:34:07 volumio volumio[1042]: info: CoreCommandRouter::volumioPushState
Jan 21 20:34:07 volumio go-librespot[1318]: time="2025-01-21T20:34:07Z" level=debug msg="completed keyexchange"
Jan 21 20:34:07 volumio volumio[1042]: info: Completed starting Core Plugins
Jan 21 20:34:07 volumio volumio[1042]: info: -------------------------------------------
Jan 21 20:34:07 volumio volumio[1042]: info: -----    MyVolumio plugins startup     ----
Jan 21 20:34:07 volumio volumio[1042]: info: -------------------------------------------
Jan 21 20:34:07 volumio volumio[1042]: info: [MyVolumio PluginManager] Fetching plans data....
Jan 21 20:34:07 volumio volumio[1249]: ............++++
Jan 21 20:34:07 volumio RoonBridge[1287]: 00:00:00.054 Warn:  get lock file path: /tmp/.rnbhgem0-
Jan 21 20:34:08 volumio go-librespot[1318]: time="2025-01-21T20:34:08Z" level=debug msg="completed challenge"
Jan 21 20:34:08 volumio go-librespot[1318]: time="2025-01-21T20:34:08Z" level=debug msg="authenticated as g8r2fyu3io9e3yr0xe56j4nn7"
Jan 21 20:34:08 volumio go-librespot[1318]: time="2025-01-21T20:34:08Z" level=debug msg="authenticated as g8r2fyu3io9e3yr0xe56j4nn7"
Jan 21 20:34:08 volumio volumio[1042]: info: go-librespot daemon successfully initialized
Jan 21 20:34:09 volumio volumio[1249]: ..............++++
Jan 21 20:34:09 volumio volumio[1249]: e is 65537 (0x010001)
Jan 21 20:34:09 volumio volumio[1249]: writing RSA key
Jan 21 20:34:09 volumio sudo[1422]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //http://localhost/\\ /mnt/NAS/LOCAL
Jan 21 20:34:09 volumio sudo[1422]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 21 20:34:09 volumio go-librespot[1318]: time="2025-01-21T20:34:09Z" level=debug msg="dealer connection opened"
Jan 21 20:34:09 volumio go-librespot[1318]: time="2025-01-21T20:34:09Z" level=debug msg="initializing zeroconf session, username: g8r2fyu3io9e3yr0xe56j4nn7"
Jan 21 20:34:09 volumio go-librespot[1318]: time="2025-01-21T20:34:09Z" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336"
Jan 21 20:34:09 volumio go-librespot[1318]: time="2025-01-21T20:34:09Z" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2"
Jan 21 20:34:09 volumio go-librespot[1318]: time="2025-01-21T20:34:09Z" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17"
Jan 21 20:34:09 volumio go-librespot[1318]: time="2025-01-21T20:34:09Z" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0"
Jan 21 20:34:09 volumio go-librespot[1318]: time="2025-01-21T20:34:09Z" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481"
Jan 21 20:34:09 volumio go-librespot[1318]: time="2025-01-21T20:34:09Z" level=debug msg="autoplay enabled: false"
Jan 21 20:34:09 volumio go-librespot[1318]: time="2025-01-21T20:34:09Z" level=debug msg="received connection id: ZmExNDMxYWMtY2Y2Yi00OTUwLWFmMmEtZjc5ZjIxYTRkYTM5K2RlYWxlcit0Y3A6Ly8wYWNhNDFjMS5pcC5nZXcxLnNwb3RpZnkubmV0OjU3MDArMkU1RTE0MkYxMzU3OTYyNzE5MTg1NTY4RkEwMzRCNDI3OTdBNEFCNDRDRDhCQThFRkJFOTY4RDIyMjMyRTkwNQ=="
Jan 21 20:34:09 volumio sudo[1422]: pam_unix(sudo:session): session closed for user root
Jan 21 20:34:09 volumio volumio[1042]: info: Cannot mount NAS LOCAL at system boot, trial number 2 ,retrying in 5 seconds
Jan 21 20:34:09 volumio sudo[1427]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=admin,password=De_deziner1,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //172.16.1.252/extra_music/ /mnt/NAS/extra_music
Jan 21 20:34:09 volumio sudo[1427]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 21 20:34:09 volumio sudo[1436]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=admin,password=De_deziner1,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //172.16.1.252/multimedia/ /mnt/NAS/Kharisstore
Jan 21 20:34:09 volumio sudo[1436]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 21 20:34:09 volumio kernel: CIFS: Attempting to mount //172.16.1.252/extra_music
Jan 21 20:34:09 volumio go-librespot[1318]: time="2025-01-21T20:34:09Z" level=debug msg="put connect state because NEW_DEVICE"
Jan 21 20:34:10 volumio volumio[1042]: error: Failed LSINFO: Error:  [50@0] {lsinfo} No such directory
Jan 21 20:34:11 volumio volumio[1042]: info: Initializing connection to go-librespot Websocket
Jan 21 20:34:11 volumio volumio[1042]: info: Connection to go-librespot Websocket established
Jan 21 20:34:11 volumio go-librespot[1318]: time="2025-01-21T20:34:11Z" level=debug msg="new websocket client"
Jan 21 20:34:14 volumio sudo[1479]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //http://localhost/\\ /mnt/NAS/LOCAL
Jan 21 20:34:14 volumio sudo[1479]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 21 20:34:14 volumio sudo[1479]: pam_unix(sudo:session): session closed for user root
Jan 21 20:34:14 volumio volumio[1042]: info: Cannot mount NAS LOCAL at system boot, trial number 3 ,retrying in 5 seconds
Jan 21 20:34:14 volumio volumio[1042]: info: Getting Spotify volume
Jan 21 20:34:14 volumio volumio[1042]: info: Spotify volume: 100
Jan 21 20:34:14 volumio volumio[1042]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3
Jan 21 20:34:14 volumio volumio[1042]: info: CoreCommandRouter::volumioGetState
Jan 21 20:34:14 volumio volumio[1042]: info: CorePlayQueue::getTrack 0
Jan 21 20:34:14 volumio volumio[1042]: SPOTIFY: RECEIVED VOLUMIO VOLUME 77
Jan 21 20:34:14 volumio volumio[1042]: SPOTIFY: SPOTIFY VOLUME 100
Jan 21 20:34:14 volumio volumio[1042]: SPOTIFY: VOLUMIO VOLUME 77
Jan 21 20:34:14 volumio volumio[1042]: SPOTIFY: DELTA VOLUME ENOUGH: true
Jan 21 20:34:14 volumio volumio[1042]: info: Setting Spotify Volume from Volumio: 77
Jan 21 20:34:15 volumio sudo[1427]: pam_unix(sudo:session): session closed for user root
Jan 21 20:34:15 volumio volumio[1042]: info: Cannot mount NAS extra_music at system boot, trial number 2 ,retrying in 5 seconds
Jan 21 20:34:15 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation.
Jan 21 20:34:15 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -113
Jan 21 20:34:15 volumio kernel: CIFS: Attempting to mount //172.16.1.252/multimedia
Jan 21 20:34:16 volumio volumio[1042]: SPOTIFY: SETTING SPOTIFY VOLUME 77
Jan 21 20:34:16 volumio volumio[1042]: info: Sending Spotify command with payload to local API: /player/volume
Jan 21 20:34:16 volumio go-librespot[1318]: time="2025-01-21T20:34:16Z" level=debug msg="update volume to 50461/65535"
Jan 21 20:34:16 volumio go-librespot[1318]: time="2025-01-21T20:34:16Z" level=debug msg="put connect state because VOLUME_CHANGED"
Jan 21 20:34:16 volumio go-librespot[1318]: time="2025-01-21T20:34:16Z" level=trace msg="emitting websocket event: volume"
Jan 21 20:34:16 volumio volumio[1042]: SPOTIFY: received: {"type":"volume","data":{"value":77,"max":100}}
Jan 21 20:34:16 volumio volumio[1042]: SPOTIFY: RECEIVED SPOTIFY VOLUME 77
Jan 21 20:34:17 volumio volumio[1042]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium
Jan 21 20:34:17 volumio volumio[1042]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium
Jan 21 20:34:17 volumio volumio[1042]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium
Jan 21 20:34:17 volumio volumio[1042]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium
Jan 21 20:34:17 volumio volumio[1042]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium
Jan 21 20:34:17 volumio volumio[1042]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium
Jan 21 20:34:17 volumio volumio[1042]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium
Jan 21 20:34:17 volumio volumio[1042]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium
Jan 21 20:34:17 volumio volumio[1042]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium
Jan 21 20:34:17 volumio volumio[1042]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium
Jan 21 20:34:17 volumio volumio[1042]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar
Jan 21 20:34:17 volumio volumio[1042]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar
Jan 21 20:34:17 volumio volumio[1042]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar
Jan 21 20:34:17 volumio volumio[1042]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar
Jan 21 20:34:17 volumio volumio[1042]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar
Jan 21 20:34:17 volumio volumio[1042]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar
Jan 21 20:34:17 volumio volumio[1042]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar
Jan 21 20:34:17 volumio volumio[1042]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar
Jan 21 20:34:17 volumio volumio[1042]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar
Jan 21 20:34:17 volumio volumio[1042]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar
Jan 21 20:34:17 volumio volumio[1042]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso
Jan 21 20:34:17 volumio volumio[1042]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso
Jan 21 20:34:17 volumio volumio[1042]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso
Jan 21 20:34:17 volumio volumio[1042]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso
Jan 21 20:34:17 volumio volumio[1042]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso
Jan 21 20:34:17 volumio volumio[1042]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso
Jan 21 20:34:17 volumio volumio[1042]: info: Adding plugin bluetooth to MyMusic Plugins
Jan 21 20:34:17 volumio volumio[1042]: info: Adding plugin multiroom to MyMusic Plugins
Jan 21 20:34:17 volumio volumio[1042]: info: Adding plugin metavolumio to MyMusic Plugins
Jan 21 20:34:17 volumio volumio[1042]: info: Adding plugin cd_controller to MyMusic Plugins
Jan 21 20:34:17 volumio volumio[1042]: info: Adding plugin smart_inputs to MyMusic Plugins
Jan 21 20:34:17 volumio volumio[1042]: info: Adding plugin tidalconnect to MyMusic Plugins
Jan 21 20:34:17 volumio volumio[1042]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"...
Jan 21 20:34:18 volumio systemd[1]: systemd-timedated.service: Succeeded.
Jan 21 20:34:18 volumio volumio[1042]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded
Jan 21 20:34:18 volumio volumio[1042]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio
Jan 21 20:34:18 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 21 20:34:18 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 21 20:34:18 volumio volumio[1042]: info: Starting MyVolumio Remote Streaming Endpoints
Jan 21 20:34:18 volumio volumio[1042]: info: MyVolumio login type: Token
Jan 21 20:34:18 volumio volumio[1042]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started
Jan 21 20:34:18 volumio volumio[1042]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"...
Jan 21 20:34:18 volumio volumio[1042]: info: Starting Streaming Service Transparent Proxy
Jan 21 20:34:18 volumio volumio[1042]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded
Jan 21 20:34:18 volumio volumio[1042]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services
Jan 21 20:34:18 volumio volumio[1042]: info: Streaming services startup
Jan 21 20:34:18 volumio volumio[1042]: info: Starting Streaming Daemon
Jan 21 20:34:18 volumio sudo[1493]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Jan 21 20:34:18 volumio sudo[1493]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 21 20:34:18 volumio sudo[1493]: pam_unix(sudo:session): session closed for user root
Jan 21 20:34:18 volumio volumio[1042]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started
Jan 21 20:34:19 volumio volumio[1042]: error: Cannot start Volumio Streaming Daemon
Jan 21 20:34:19 volumio volumio[1042]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Jan 21 20:34:19 volumio volumio[1042]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Jan 21 20:34:19 volumio sudo[1515]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //http://localhost/\\ /mnt/NAS/LOCAL
Jan 21 20:34:19 volumio sudo[1515]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 21 20:34:19 volumio sudo[1515]: pam_unix(sudo:session): session closed for user root
Jan 21 20:34:19 volumio volumio[1042]: info: Cannot mount NAS LOCAL at system boot, trial number 4 ,retrying in 5 seconds
Jan 21 20:34:19 volumio sudo[1520]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Jan 21 20:34:19 volumio sudo[1520]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 21 20:34:19 volumio sudo[1520]: pam_unix(sudo:session): session closed for user root
Jan 21 20:34:19 volumio sudo[1523]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Jan 21 20:34:19 volumio sudo[1523]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 21 20:34:19 volumio sudo[1523]: pam_unix(sudo:session): session closed for user root
Jan 21 20:34:19 volumio volumio[1042]: verbose: New Socket.io Connection to 172.16.1.237 from 172.16.1.74 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:134.0) Gecko/20100101 Firefox/134.0 Engine version: 3 Transport: polling Total Clients: 4
Jan 21 20:34:19 volumio volumio[1042]: STREAMING PROXY: Starting server on port 3245
Jan 21 20:34:19 volumio volumio[1042]: Node JS runtime: 14
Jan 21 20:34:19 volumio sudo[1526]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Jan 21 20:34:19 volumio sudo[1526]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 21 20:34:19 volumio sudo[1526]: pam_unix(sudo:session): session closed for user root
Jan 21 20:34:19 volumio sudo[1529]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Jan 21 20:34:19 volumio sudo[1529]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 21 20:34:19 volumio sudo[1529]: pam_unix(sudo:session): session closed for user root
Jan 21 20:34:20 volumio volumio[1042]: verbose: New Socket.io Connection to 172.16.1.237 from 172.16.1.74 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:134.0) Gecko/20100101 Firefox/134.0 Engine version: 3 Transport: polling Total Clients: 5
Jan 21 20:34:20 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Jan 21 20:34:20 volumio volumio[1042]: info: CoreCommandRouter::volumioGetVisibleSources
Jan 21 20:34:20 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jan 21 20:34:20 volumio volumio[1042]: info: CoreCommandRouter::volumioGetState
Jan 21 20:34:20 volumio volumio[1042]: info: CorePlayQueue::getTrack 0
Jan 21 20:34:20 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Jan 21 20:34:20 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Jan 21 20:34:20 volumio volumio[1042]: info: Received Get System Info
Jan 21 20:34:20 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jan 21 20:34:20 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jan 21 20:34:20 volumio volumio[1042]: info: Discovery: Getting this device information
Jan 21 20:34:20 volumio volumio[1042]: info: CoreCommandRouter::volumioGetState
Jan 21 20:34:20 volumio volumio[1042]: info: CorePlayQueue::getTrack 0
Jan 21 20:34:20 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jan 21 20:34:20 volumio volumio[1042]: info: CoreCommandRouter::volumioGetState
Jan 21 20:34:20 volumio volumio[1042]: info: CorePlayQueue::getTrack 0
Jan 21 20:34:20 volumio volumio[1042]: info: Listing playlists
Jan 21 20:34:20 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
Jan 21 20:34:20 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Jan 21 20:34:20 volumio volumio[1042]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN
Jan 21 20:34:20 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache
Jan 21 20:34:20 volumio sudo[1533]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=admin,password=De_deziner1,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //172.16.1.252/extra_music/ /mnt/NAS/extra_music
Jan 21 20:34:20 volumio sudo[1533]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 21 20:34:21 volumio volumio[1042]: info: CoreCommandRouter::volumioGetState
Jan 21 20:34:21 volumio volumio[1042]: info: CorePlayQueue::getTrack 0
Jan 21 20:34:21 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard
Jan 21 20:34:21 volumio sudo[1436]: pam_unix(sudo:session): session closed for user root
Jan 21 20:34:21 volumio volumio[1042]: info: Cannot mount NAS Kharisstore at system boot, trial number 2 ,retrying in 5 seconds
Jan 21 20:34:21 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation.
Jan 21 20:34:21 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -113
Jan 21 20:34:21 volumio kernel: CIFS: Attempting to mount //172.16.1.252/extra_music
Jan 21 20:34:21 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Jan 21 20:34:21 volumio volumio[1042]: info: Received Get System Info
Jan 21 20:34:21 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jan 21 20:34:21 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jan 21 20:34:21 volumio volumio[1042]: info: Discovery: Getting this device information
Jan 21 20:34:21 volumio volumio[1042]: info: CoreCommandRouter::volumioGetState
Jan 21 20:34:21 volumio volumio[1042]: info: CorePlayQueue::getTrack 0
Jan 21 20:34:21 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jan 21 20:34:21 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken
Jan 21 20:34:22 volumio volumio[1042]: info: MyVolumio token set successfully
Jan 21 20:34:22 volumio volumio[1042]: info: MYVOLUMIO: Adding device
Jan 21 20:34:22 volumio volumio[1042]: info: MYVOLUMIO: Evaluating Server
Jan 21 20:34:22 volumio volumio[1042]: info: MyVolumio status changed
Jan 21 20:34:22 volumio volumio[1042]: info: Streaming services startup
Jan 21 20:34:22 volumio volumio[1042]: info: Starting Streaming Daemon
Jan 21 20:34:22 volumio volumio[1042]: info: Removing browser output: myVolumio user plan is not superstar
Jan 21 20:34:22 volumio volumio[1042]: info: Removing audio output:
Jan 21 20:34:22 volumio volumio[1042]: info: Stoppping Tunnel 1
Jan 21 20:34:23 volumio sudo[1559]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Jan 21 20:34:23 volumio sudo[1559]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 21 20:34:23 volumio sudo[1559]: pam_unix(sudo:session): session closed for user root
Jan 21 20:34:23 volumio volumio[1042]: error: Cannot start Volumio Streaming Daemon
Jan 21 20:34:23 volumio volumio[1042]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Jan 21 20:34:23 volumio volumio[1042]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Jan 21 20:34:23 volumio sudo[1562]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service
Jan 21 20:34:23 volumio sudo[1562]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 21 20:34:23 volumio sudo[1562]: pam_unix(sudo:session): session closed for user root
Jan 21 20:34:23 volumio volumio[1042]: info: Remote SSH Stopped
Jan 21 20:34:24 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Jan 21 20:34:24 volumio volumio[1042]: info: Received Get System Info
Jan 21 20:34:24 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jan 21 20:34:24 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jan 21 20:34:24 volumio volumio[1042]: info: Discovery: Getting this device information
Jan 21 20:34:24 volumio volumio[1042]: info: CoreCommandRouter::volumioGetState
Jan 21 20:34:24 volumio volumio[1042]: info: CorePlayQueue::getTrack 0
Jan 21 20:34:24 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jan 21 20:34:24 volumio sudo[1565]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //http://localhost/\\ /mnt/NAS/LOCAL
Jan 21 20:34:24 volumio sudo[1565]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 21 20:34:24 volumio sudo[1565]: pam_unix(sudo:session): session closed for user root
Jan 21 20:34:24 volumio volumio[1042]: info: Cannot mount NAS at system boot, trial number 4 ,stopping
Jan 21 20:34:25 volumio volumio[1042]: info: Setting Geolocation for MyVolumio to eu2
Jan 21 20:34:25 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 21 20:34:25 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 21 20:34:25 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 21 20:34:25 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Jan 21 20:34:25 volumio volumio[1042]: info: Received Get System Info
Jan 21 20:34:25 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jan 21 20:34:25 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jan 21 20:34:25 volumio volumio[1042]: info: Discovery: Getting this device information
Jan 21 20:34:25 volumio volumio[1042]: info: CoreCommandRouter::volumioGetState
Jan 21 20:34:25 volumio volumio[1042]: info: CorePlayQueue::getTrack 0
Jan 21 20:34:25 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jan 21 20:34:26 volumio volumio[1042]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"}
Jan 21 20:34:26 volumio volumio[1042]: info: Updating MyVolumio device info
Jan 21 20:34:26 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 21 20:34:26 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 21 20:34:26 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 21 20:34:26 volumio volumio[1042]: info: CoreCommandRouter::volumioGetQueue
Jan 21 20:34:26 volumio volumio[1042]: info: CoreStateMachine::getQueue
Jan 21 20:34:26 volumio volumio[1042]: info: CorePlayQueue::getQueue
Jan 21 20:34:26 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Jan 21 20:34:26 volumio volumio[1042]: info: Received Get System Info
Jan 21 20:34:26 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jan 21 20:34:26 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jan 21 20:34:26 volumio volumio[1042]: info: Discovery: Getting this device information
Jan 21 20:34:26 volumio volumio[1042]: info: CoreCommandRouter::volumioGetState
Jan 21 20:34:26 volumio volumio[1042]: info: CorePlayQueue::getTrack 0
Jan 21 20:34:26 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jan 21 20:34:26 volumio sudo[1610]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=admin,password=De_deziner1,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //172.16.1.252/multimedia/ /mnt/NAS/Kharisstore
Jan 21 20:34:26 volumio sudo[1610]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 21 20:34:27 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Jan 21 20:34:27 volumio volumio[1042]: info: CURURI: music-library
Jan 21 20:34:27 volumio volumio[1042]: error: Failed LSINFO: null
Jan 21 20:34:27 volumio volumio[1042]: info: Preload queue cleared
Jan 21 20:34:27 volumio volumio[1042]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"}
Jan 21 20:34:28 volumio sudo[1533]: pam_unix(sudo:session): session closed for user root
Jan 21 20:34:28 volumio volumio[1042]: info: Cannot mount NAS extra_music at system boot, trial number 3 ,retrying in 5 seconds
Jan 21 20:34:28 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation.
Jan 21 20:34:28 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -113
Jan 21 20:34:28 volumio kernel: CIFS: Attempting to mount //172.16.1.252/multimedia
Jan 21 20:34:30 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
Jan 21 20:34:30 volumio volumio[1042]: info: MYVOLUMIO: Adding device
Jan 21 20:34:30 volumio volumio[1042]: info: MYVOLUMIO: Evaluating Server
Jan 21 20:34:30 volumio volumio[1042]: info: CoreCommandRouter::volumioGetState
Jan 21 20:34:30 volumio volumio[1042]: info: CorePlayQueue::getTrack 0
Jan 21 20:34:32 volumio volumio[1042]: info: CoreCommandRouter::getUIConfigOnPlugin
Jan 21 20:34:32 volumio volumio[1042]: info: CoreCommandRouter::getUIConfigOnPlugin
Jan 21 20:34:32 volumio volumio[1042]: info: Retrieving Cloud Streaming UI
Jan 21 20:34:32 volumio volumio[1042]: info: Getting Tidal Cloud Configuration
Jan 21 20:34:32 volumio volumio[1042]: info: CoreCommandRouter::getUIConfigOnPlugin
Jan 21 20:34:32 volumio volumio[1042]: info: Getting Qobuz Cloud Configuration
Jan 21 20:34:32 volumio volumio[1042]: info: Asking plugin for UI Config
Jan 21 20:34:32 volumio volumio[1042]: info: CoreCommandRouter::getUIConfigOnPlugin
Jan 21 20:34:32 volumio volumio[1042]: info: Getting Spotify Cloud Configuration
Jan 21 20:34:32 volumio volumio[1042]: info: Asking plugin for UI Config
Jan 21 20:34:32 volumio volumio[1042]: info: CoreCommandRouter::getUIConfigOnPlugin
Jan 21 20:34:32 volumio volumio[1042]: info: Saving Spotify Acccount
Jan 21 20:34:32 volumio volumio[1042]: info: Got Tidal Cloud Configuration
Jan 21 20:34:32 volumio volumio[1042]: info: Got it
Jan 21 20:34:32 volumio volumio[1042]: info: Got it
Jan 21 20:34:32 volumio volumio[1042]: error: Could not retrieve Spotify Config from plugin Spotify: no section found
Jan 21 20:34:32 volumio volumio[1042]: info: CoreCommandRouter::getUIConfigOnPlugin
Jan 21 20:34:32 volumio volumio[1042]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined
Jan 21 20:34:32 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus
Jan 21 20:34:32 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam
Jan 21 20:34:32 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam
Jan 21 20:34:32 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam
Jan 21 20:34:32 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jan 21 20:34:32 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jan 21 20:34:32 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jan 21 20:34:32 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jan 21 20:34:32 volumio volumio[1042]: info: CoreCommandRouter::volumioGetBrowseSources
Jan 21 20:34:32 volumio volumio[1042]: info: CoreCommandRouter::volumioGetBrowseSources
Jan 21 20:34:32 volumio volumio[1042]: info: CoreCommandRouter::volumioGetBrowseSources
Jan 21 20:34:32 volumio volumio[1042]: info: Setting Geolocation for MyVolumio to eu2
Jan 21 20:34:32 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 21 20:34:32 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 21 20:34:32 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 21 20:34:32 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jan 21 20:34:32 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares
Jan 21 20:34:33 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Jan 21 20:34:33 volumio volumio[1042]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6
Jan 21 20:34:33 volumio sudo[1668]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=admin,password=De_deziner1,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //172.16.1.252/extra_music/ /mnt/NAS/extra_music
Jan 21 20:34:33 volumio sudo[1668]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 21 20:34:33 volumio volumio[1042]: info: CoreCommandRouter::volumioGetState
Jan 21 20:34:33 volumio volumio[1042]: info: CorePlayQueue::getTrack 0
Jan 21 20:34:33 volumio volumio[1042]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"}
Jan 21 20:34:33 volumio volumio[1042]: info: Updating MyVolumio device info
Jan 21 20:34:33 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 21 20:34:34 volumio volumio[1042]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"}
Jan 21 20:34:34 volumio sudo[1610]: pam_unix(sudo:session): session closed for user root
Jan 21 20:34:34 volumio volumio[1042]: info: Cannot mount NAS Kharisstore at system boot, trial number 3 ,retrying in 5 seconds
Jan 21 20:34:34 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation.
Jan 21 20:34:34 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -113
Jan 21 20:34:34 volumio kernel: CIFS: Attempting to mount //172.16.1.252/extra_music
Jan 21 20:34:34 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jan 21 20:34:34 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 21 20:34:34 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Jan 21 20:34:35 volumio volumio-remote-updater[629]: Test mode enabled
Jan 21 20:34:35 volumio volumio-remote-updater[629]: No alpha test mode
Jan 21 20:34:35 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
Jan 21 20:34:35 volumio volumio[1042]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
\n\nImproved translations
\nFix for Allo Piano DAC on RPi with kernel 6.6.x
\n
\nNEW ADDITIONS
\n\nAdd support for Raspberry Pi Display V2 on RPi
\nBump kernel to 6.6.62 on RPi
\nAdd DSD-quirk for Luxman DA-250 on Rivo and Primo2
\nAdd DSD-quirk for Musical Fidelity M6X on Rivo and Primo2
\nAdd DSD-quirk for several USB DACs on RPi
\nEnable install-to-NVME on CM5 and newer RPi5 revisions
\n\n","title":"Update v3.788","updateavailable":true}
Jan 21 20:34:35 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Jan 21 20:34:35 volumio ntpd[844]: Soliciting pool server 162.159.200.123
Jan 21 20:34:36 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jan 21 20:34:36 volumio ntpd[844]: Soliciting pool server 162.159.200.1
Jan 21 20:34:36 volumio ntpd[844]: Soliciting pool server 2606:4700:f1::123
Jan 21 20:34:37 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb
Jan 21 20:34:37 volumio volumio[1042]: verbose: ControllerMpd::sendMpdCommand update
Jan 21 20:34:37 volumio volumio[1042]: verbose: ControllerMpd::sendMpdCommand status
Jan 21 20:34:37 volumio volumio[1042]: info:
Jan 21 20:34:37 volumio volumio[1042]: ---------------------------- MPD announces state update: update
Jan 21 20:34:37 volumio volumio[1042]: info: sendMpdCommand update took 5 milliseconds
Jan 21 20:34:37 volumio volumio[1042]: info: ControllerMpd::getState
Jan 21 20:34:37 volumio volumio[1042]: verbose: ControllerMpd::sendMpdCommand status
Jan 21 20:34:37 volumio volumio[1042]: verbose: ControllerMpd::sendMpdCommand status
Jan 21 20:34:37 volumio volumio[1042]: info:
Jan 21 20:34:37 volumio volumio[1042]: ---------------------------- MPD announces state update: update
Jan 21 20:34:37 volumio volumio[1042]: info: ControllerMpd::getState
Jan 21 20:34:37 volumio volumio[1042]: verbose: ControllerMpd::sendMpdCommand status
Jan 21 20:34:37 volumio volumio[1042]: verbose: ControllerMpd::sendMpdCommand status
Jan 21 20:34:37 volumio volumio[1042]: info:
Jan 21 20:34:37 volumio volumio[1042]: ---------------------------- MPD announces state update: update
Jan 21 20:34:37 volumio volumio[1042]: info: ControllerMpd::getState
Jan 21 20:34:37 volumio volumio[1042]: verbose: ControllerMpd::sendMpdCommand status
Jan 21 20:34:37 volumio volumio[1042]: info: sendMpdCommand status took 8 milliseconds
Jan 21 20:34:37 volumio volumio[1042]: info: sendMpdCommand status took 6 milliseconds
Jan 21 20:34:37 volumio volumio[1042]: info: sendMpdCommand status took 5 milliseconds
Jan 21 20:34:37 volumio volumio[1042]: info: sendMpdCommand status took 4 milliseconds
Jan 21 20:34:37 volumio volumio[1042]: info: sendMpdCommand status took 4 milliseconds
Jan 21 20:34:37 volumio volumio[1042]: info: sendMpdCommand status took 3 milliseconds
Jan 21 20:34:37 volumio volumio[1042]: verbose: ControllerMpd::parseState
Jan 21 20:34:37 volumio volumio[1042]: info: Command Router : Notfying DB Updatetrue
Jan 21 20:34:37 volumio volumio[1042]: verbose: ControllerMpd::parseState
Jan 21 20:34:37 volumio volumio[1042]: verbose: ControllerMpd::parseState
Jan 21 20:34:37 volumio volumio[1042]: info: Command Router : Notfying DB Updatetrue
Jan 21 20:34:37 volumio volumio[1042]: info: CoreCommandRouter::Close All Modals sent
Jan 21 20:34:37 volumio volumio[1042]: verbose: ControllerMpd::parseState
Jan 21 20:34:37 volumio volumio[1042]: verbose: ControllerMpd::parseState
Jan 21 20:34:37 volumio volumio[1042]: info: Command Router : Notfying DB Updatetrue
Jan 21 20:34:37 volumio volumio[1042]: info: CoreCommandRouter::Close All Modals sent
Jan 21 20:34:37 volumio volumio[1042]: verbose: ControllerMpd::parseState
Jan 21 20:34:37 volumio volumio[1042]: info: ControllerMpd::pushState
Jan 21 20:34:37 volumio volumio[1042]: info: CoreCommandRouter::servicePushState
Jan 21 20:34:37 volumio volumio[1042]: info: CoreStateMachine::pushState
Jan 21 20:34:37 volumio volumio[1042]: info: CorePlayQueue::getTrack 0
Jan 21 20:34:37 volumio volumio[1042]: info: CoreCommandRouter::volumioPushState
Jan 21 20:34:37 volumio volumio[1042]: info: CorePlayQueue::getTrack 0
Jan 21 20:34:37 volumio volumio[1042]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current ytmusic Received mpd
Jan 21 20:34:37 volumio volumio[1042]: info: ControllerMpd::pushState
Jan 21 20:34:37 volumio volumio[1042]: info: CoreCommandRouter::servicePushState
Jan 21 20:34:37 volumio volumio[1042]: info: CoreStateMachine::pushState
Jan 21 20:34:37 volumio volumio[1042]: info: CorePlayQueue::getTrack 0
Jan 21 20:34:37 volumio volumio[1042]: info: CoreCommandRouter::volumioPushState
Jan 21 20:34:37 volumio volumio[1042]: info: CorePlayQueue::getTrack 0
Jan 21 20:34:37 volumio volumio[1042]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current ytmusic Received mpd
Jan 21 20:34:37 volumio volumio[1042]: info: ControllerMpd::pushState
Jan 21 20:34:37 volumio volumio[1042]: info: CoreCommandRouter::servicePushState
Jan 21 20:34:37 volumio volumio[1042]: info: CoreStateMachine::pushState
Jan 21 20:34:37 volumio volumio[1042]: info: CorePlayQueue::getTrack 0
Jan 21 20:34:37 volumio volumio[1042]: info: CoreCommandRouter::volumioPushState
Jan 21 20:34:37 volumio volumio[1042]: info: CorePlayQueue::getTrack 0
Jan 21 20:34:37 volumio volumio[1042]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current ytmusic Received mpd
Jan 21 20:34:37 volumio volumio[1042]: info: ------------------------------ 138ms
Jan 21 20:34:37 volumio volumio[1042]: info: ------------------------------ 134ms
Jan 21 20:34:37 volumio volumio[1042]: info: ------------------------------ 133ms
Jan 21 20:34:37 volumio volumio[1042]: verbose: ControllerMpd::sendMpdCommand status
Jan 21 20:34:37 volumio volumio[1042]: info:
Jan 21 20:34:37 volumio volumio[1042]: ---------------------------- MPD announces state update: update
Jan 21 20:34:37 volumio volumio[1042]: info: ControllerMpd::getState
Jan 21 20:34:37 volumio volumio[1042]: verbose: ControllerMpd::sendMpdCommand status
Jan 21 20:34:37 volumio volumio[1042]: verbose: ControllerMpd::sendMpdCommand status
Jan 21 20:34:37 volumio volumio[1042]: info:
Jan 21 20:34:37 volumio volumio[1042]: ---------------------------- MPD announces state update: update
Jan 21 20:34:37 volumio volumio[1042]: info: ControllerMpd::getState
Jan 21 20:34:37 volumio volumio[1042]: verbose: ControllerMpd::sendMpdCommand status
Jan 21 20:34:37 volumio volumio[1042]: verbose: ControllerMpd::sendMpdCommand status
Jan 21 20:34:37 volumio volumio[1042]: info:
Jan 21 20:34:37 volumio volumio[1042]: ---------------------------- MPD announces state update: update
Jan 21 20:34:37 volumio volumio[1042]: info: ControllerMpd::getState
Jan 21 20:34:37 volumio volumio[1042]: verbose: ControllerMpd::sendMpdCommand status
Jan 21 20:34:37 volumio volumio[1042]: SPOTIFY: RECEIVED VOLUMIO VOLUME 77
Jan 21 20:34:37 volumio volumio[1042]: SPOTIFY: RECEIVED VOLUMIO VOLUME 77
Jan 21 20:34:37 volumio volumio[1042]: SPOTIFY: RECEIVED VOLUMIO VOLUME 77
Jan 21 20:34:37 volumio volumio[1042]: info: sendMpdCommand status took 12 milliseconds
Jan 21 20:34:37 volumio volumio[1042]: info: sendMpdCommand status took 11 milliseconds
Jan 21 20:34:37 volumio volumio[1042]: info: sendMpdCommand status took 10 milliseconds
Jan 21 20:34:37 volumio volumio[1042]: info: sendMpdCommand status took 9 milliseconds
Jan 21 20:34:37 volumio volumio[1042]: info: sendMpdCommand status took 9 milliseconds
Jan 21 20:34:37 volumio volumio[1042]: info: sendMpdCommand status took 9 milliseconds
Jan 21 20:34:37 volumio volumio[1042]: verbose: ControllerMpd::parseState
Jan 21 20:34:37 volumio volumio[1042]: info: Command Router : Notfying DB Updatefalse
Jan 21 20:34:37 volumio volumio[1042]: info: CoreCommandRouter::Close All Modals sent
Jan 21 20:34:37 volumio volumio[1042]: verbose: ControllerMpd::parseState
Jan 21 20:34:37 volumio volumio[1042]: verbose: ControllerMpd::parseState
Jan 21 20:34:37 volumio volumio[1042]: info: Command Router : Notfying DB Updatefalse
Jan 21 20:34:37 volumio volumio[1042]: info: CoreCommandRouter::Close All Modals sent
Jan 21 20:34:37 volumio volumio[1042]: verbose: ControllerMpd::parseState
Jan 21 20:34:37 volumio volumio[1042]: verbose: ControllerMpd::parseState
Jan 21 20:34:37 volumio volumio[1042]: info: Command Router : Notfying DB Updatefalse
Jan 21 20:34:37 volumio volumio[1042]: info: CoreCommandRouter::Close All Modals sent
Jan 21 20:34:37 volumio volumio[1042]: verbose: ControllerMpd::parseState
Jan 21 20:34:37 volumio volumio[1042]: info: ControllerMpd::pushState
Jan 21 20:34:37 volumio volumio[1042]: info: CoreCommandRouter::servicePushState
Jan 21 20:34:37 volumio volumio[1042]: info: CoreStateMachine::pushState
Jan 21 20:34:37 volumio volumio[1042]: info: CorePlayQueue::getTrack 0
Jan 21 20:34:37 volumio volumio[1042]: info: CoreCommandRouter::volumioPushState
Jan 21 20:34:37 volumio volumio[1042]: info: CorePlayQueue::getTrack 0
Jan 21 20:34:37 volumio volumio[1042]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current ytmusic Received mpd
Jan 21 20:34:37 volumio volumio[1042]: info: ControllerMpd::pushState
Jan 21 20:34:37 volumio volumio[1042]: info: CoreCommandRouter::servicePushState
Jan 21 20:34:37 volumio volumio[1042]: info: CoreStateMachine::pushState
Jan 21 20:34:37 volumio volumio[1042]: info: CorePlayQueue::getTrack 0
Jan 21 20:34:37 volumio volumio[1042]: info: CoreCommandRouter::volumioPushState
Jan 21 20:34:37 volumio volumio[1042]: info: CorePlayQueue::getTrack 0
Jan 21 20:34:37 volumio volumio[1042]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current ytmusic Received mpd
Jan 21 20:34:37 volumio volumio[1042]: info: ControllerMpd::pushState
Jan 21 20:34:37 volumio volumio[1042]: info: CoreCommandRouter::servicePushState
Jan 21 20:34:37 volumio volumio[1042]: info: CoreStateMachine::pushState
Jan 21 20:34:37 volumio volumio[1042]: info: CorePlayQueue::getTrack 0
Jan 21 20:34:37 volumio volumio[1042]: info: CoreCommandRouter::volumioPushState
Jan 21 20:34:37 volumio volumio[1042]: info: CorePlayQueue::getTrack 0
Jan 21 20:34:37 volumio volumio[1042]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current ytmusic Received mpd
Jan 21 20:34:37 volumio volumio[1042]: info: ------------------------------ 99ms
Jan 21 20:34:37 volumio volumio[1042]: info: ------------------------------ 98ms
Jan 21 20:34:37 volumio volumio[1042]: info: ------------------------------ 97ms
Jan 21 20:34:37 volumio volumio[1042]: SPOTIFY: RECEIVED VOLUMIO VOLUME 77
Jan 21 20:34:37 volumio volumio[1042]: SPOTIFY: RECEIVED VOLUMIO VOLUME 77
Jan 21 20:34:37 volumio volumio[1042]: SPOTIFY: RECEIVED VOLUMIO VOLUME 77
Jan 21 20:34:38 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 21 20:34:38 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 21 20:34:38 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
Jan 21 20:34:38 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Jan 21 20:34:38 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
Jan 21 20:34:38 volumio volumio[1042]: info: CoreCommandRouter::volumioGetBrowseSources
Jan 21 20:34:38 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jan 21 20:34:38 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb
Jan 21 20:34:38 volumio volumio[1042]: verbose: ControllerMpd::sendMpdCommand rescan
Jan 21 20:34:38 volumio volumio[1042]: verbose: ControllerMpd::sendMpdCommand status
Jan 21 20:34:38 volumio volumio[1042]: info:
Jan 21 20:34:38 volumio volumio[1042]: ---------------------------- MPD announces state update: update
Jan 21 20:34:38 volumio volumio[1042]: info: sendMpdCommand rescan took 3 milliseconds
Jan 21 20:34:38 volumio volumio[1042]: info: ControllerMpd::getState
Jan 21 20:34:38 volumio volumio[1042]: verbose: ControllerMpd::sendMpdCommand status
Jan 21 20:34:38 volumio volumio[1042]: verbose: ControllerMpd::sendMpdCommand status
Jan 21 20:34:38 volumio volumio[1042]: info:
Jan 21 20:34:38 volumio volumio[1042]: ---------------------------- MPD announces state update: update
Jan 21 20:34:38 volumio volumio[1042]: info: ControllerMpd::getState
Jan 21 20:34:38 volumio volumio[1042]: verbose: ControllerMpd::sendMpdCommand status
Jan 21 20:34:38 volumio volumio[1042]: verbose: ControllerMpd::sendMpdCommand status
Jan 21 20:34:38 volumio volumio[1042]: info:
Jan 21 20:34:38 volumio volumio[1042]: ---------------------------- MPD announces state update: update
Jan 21 20:34:38 volumio volumio[1042]: info: ControllerMpd::getState
Jan 21 20:34:38 volumio volumio[1042]: verbose: ControllerMpd::sendMpdCommand status
Jan 21 20:34:38 volumio volumio[1042]: verbose: ControllerMpd::sendMpdCommand status
Jan 21 20:34:38 volumio volumio[1042]: info:
Jan 21 20:34:38 volumio volumio[1042]: ---------------------------- MPD announces state update: update
Jan 21 20:34:38 volumio volumio[1042]: info: sendMpdCommand status took 5 milliseconds
Jan 21 20:34:38 volumio volumio[1042]: info: ControllerMpd::getState
Jan 21 20:34:38 volumio volumio[1042]: verbose: ControllerMpd::sendMpdCommand status
Jan 21 20:34:38 volumio volumio[1042]: info: sendMpdCommand status took 5 milliseconds
Jan 21 20:34:38 volumio volumio[1042]: info: sendMpdCommand status took 4 milliseconds
Jan 21 20:34:38 volumio volumio[1042]: info: sendMpdCommand status took 4 milliseconds
Jan 21 20:34:38 volumio volumio[1042]: info: sendMpdCommand status took 4 milliseconds
Jan 21 20:34:38 volumio volumio[1042]: info: sendMpdCommand status took 3 milliseconds
Jan 21 20:34:38 volumio volumio[1042]: verbose: ControllerMpd::parseState
Jan 21 20:34:38 volumio volumio[1042]: info: Command Router : Notfying DB Updatefalse
Jan 21 20:34:38 volumio volumio[1042]: info: CoreCommandRouter::Close All Modals sent
Jan 21 20:34:38 volumio volumio[1042]: verbose: ControllerMpd::parseState
Jan 21 20:34:38 volumio volumio[1042]: verbose: ControllerMpd::parseState
Jan 21 20:34:38 volumio volumio[1042]: info: Command Router : Notfying DB Updatefalse
Jan 21 20:34:38 volumio volumio[1042]: info: CoreCommandRouter::Close All Modals sent
Jan 21 20:34:38 volumio volumio[1042]: verbose: ControllerMpd::parseState
Jan 21 20:34:38 volumio volumio[1042]: verbose: ControllerMpd::parseState
Jan 21 20:34:38 volumio volumio[1042]: info: Command Router : Notfying DB Updatefalse
Jan 21 20:34:38 volumio volumio[1042]: info: CoreCommandRouter::Close All Modals sent
Jan 21 20:34:38 volumio volumio[1042]: verbose: ControllerMpd::parseState
Jan 21 20:34:38 volumio volumio[1042]: info: ControllerMpd::pushState
Jan 21 20:34:38 volumio volumio[1042]: info: CoreCommandRouter::servicePushState
Jan 21 20:34:38 volumio volumio[1042]: info: CoreStateMachine::pushState
Jan 21 20:34:38 volumio volumio[1042]: info: CorePlayQueue::getTrack 0
Jan 21 20:34:38 volumio volumio[1042]: info: CoreCommandRouter::volumioPushState
Jan 21 20:34:38 volumio volumio[1042]: info: CorePlayQueue::getTrack 0
Jan 21 20:34:38 volumio volumio[1042]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current ytmusic Received mpd
Jan 21 20:34:38 volumio volumio[1042]: info: ControllerMpd::pushState
Jan 21 20:34:38 volumio volumio[1042]: info: CoreCommandRouter::servicePushState
Jan 21 20:34:38 volumio volumio[1042]: info: CoreStateMachine::pushState
Jan 21 20:34:38 volumio volumio[1042]: info: CorePlayQueue::getTrack 0
Jan 21 20:34:38 volumio volumio[1042]: info: CoreCommandRouter::volumioPushState
Jan 21 20:34:38 volumio volumio[1042]: info: CorePlayQueue::getTrack 0
Jan 21 20:34:38 volumio volumio[1042]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current ytmusic Received mpd
Jan 21 20:34:38 volumio volumio[1042]: info: ControllerMpd::pushState
Jan 21 20:34:38 volumio volumio[1042]: info: CoreCommandRouter::servicePushState
Jan 21 20:34:38 volumio volumio[1042]: info: CoreStateMachine::pushState
Jan 21 20:34:38 volumio volumio[1042]: info: CorePlayQueue::getTrack 0
Jan 21 20:34:38 volumio volumio[1042]: info: CoreCommandRouter::volumioPushState
Jan 21 20:34:38 volumio volumio[1042]: info: CorePlayQueue::getTrack 0
Jan 21 20:34:38 volumio volumio[1042]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current ytmusic Received mpd
Jan 21 20:34:38 volumio volumio[1042]: info: ------------------------------ 94ms
Jan 21 20:34:38 volumio volumio[1042]: info: ------------------------------ 93ms
Jan 21 20:34:38 volumio volumio[1042]: info: ------------------------------ 92ms
Jan 21 20:34:38 volumio volumio[1042]: verbose: ControllerMpd::sendMpdCommand status
Jan 21 20:34:38 volumio volumio[1042]: info:
Jan 21 20:34:38 volumio volumio[1042]: ---------------------------- MPD announces state update: update
Jan 21 20:34:38 volumio volumio[1042]: info: ControllerMpd::getState
Jan 21 20:34:38 volumio volumio[1042]: verbose: ControllerMpd::sendMpdCommand status
Jan 21 20:34:38 volumio volumio[1042]: verbose: ControllerMpd::sendMpdCommand status
Jan 21 20:34:38 volumio volumio[1042]: info:
Jan 21 20:34:38 volumio volumio[1042]: ---------------------------- MPD announces state update: update
Jan 21 20:34:38 volumio volumio[1042]: info: ControllerMpd::getState
Jan 21 20:34:38 volumio volumio[1042]: verbose: ControllerMpd::sendMpdCommand status
Jan 21 20:34:38 volumio volumio[1042]: info: sendMpdCommand status took 95 milliseconds
Jan 21 20:34:38 volumio volumio[1042]: info: sendMpdCommand status took 94 milliseconds
Jan 21 20:34:38 volumio volumio[1042]: info: sendMpdCommand status took 4 milliseconds
Jan 21 20:34:38 volumio volumio[1042]: info: sendMpdCommand status took 4 milliseconds
Jan 21 20:34:38 volumio volumio[1042]: info: sendMpdCommand status took 3 milliseconds
Jan 21 20:34:38 volumio volumio[1042]: info: sendMpdCommand status took 2 milliseconds
Jan 21 20:34:38 volumio volumio[1042]: verbose: ControllerMpd::parseState
Jan 21 20:34:38 volumio volumio[1042]: info: Command Router : Notfying DB Updatefalse
Jan 21 20:34:38 volumio volumio[1042]: info: CoreCommandRouter::Close All Modals sent
Jan 21 20:34:38 volumio volumio[1042]: verbose: ControllerMpd::parseState
Jan 21 20:34:38 volumio volumio[1042]: verbose: ControllerMpd::parseState
Jan 21 20:34:38 volumio volumio[1042]: info: Command Router : Notfying DB Updatefalse
Jan 21 20:34:38 volumio volumio[1042]: info: CoreCommandRouter::Close All Modals sent
Jan 21 20:34:38 volumio volumio[1042]: verbose: ControllerMpd::parseState
Jan 21 20:34:38 volumio volumio[1042]: verbose: ControllerMpd::parseState
Jan 21 20:34:38 volumio volumio[1042]: info: Command Router : Notfying DB Updatefalse
Jan 21 20:34:38 volumio volumio[1042]: info: CoreCommandRouter::Close All Modals sent
Jan 21 20:34:38 volumio volumio[1042]: verbose: ControllerMpd::parseState
Jan 21 20:34:38 volumio volumio[1042]: info: ControllerMpd::pushState
Jan 21 20:34:38 volumio volumio[1042]: info: CoreCommandRouter::servicePushState
Jan 21 20:34:38 volumio volumio[1042]: info: CoreStateMachine::pushState
Jan 21 20:34:38 volumio volumio[1042]: info: CorePlayQueue::getTrack 0
Jan 21 20:34:38 volumio volumio[1042]: info: CoreCommandRouter::volumioPushState
Jan 21 20:34:38 volumio volumio[1042]: info: CorePlayQueue::getTrack 0
Jan 21 20:34:38 volumio volumio[1042]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current ytmusic Received mpd
Jan 21 20:34:38 volumio volumio[1042]: info: ControllerMpd::pushState
Jan 21 20:34:38 volumio volumio[1042]: info: CoreCommandRouter::servicePushState
Jan 21 20:34:38 volumio volumio[1042]: info: CoreStateMachine::pushState
Jan 21 20:34:38 volumio volumio[1042]: info: CorePlayQueue::getTrack 0
Jan 21 20:34:38 volumio volumio[1042]: info: CoreCommandRouter::volumioPushState
Jan 21 20:34:38 volumio volumio[1042]: info: CorePlayQueue::getTrack 0
Jan 21 20:34:38 volumio volumio[1042]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current ytmusic Received mpd
Jan 21 20:34:38 volumio volumio[1042]: info: ControllerMpd::pushState
Jan 21 20:34:38 volumio volumio[1042]: info: CoreCommandRouter::servicePushState
Jan 21 20:34:38 volumio volumio[1042]: info: CoreStateMachine::pushState
Jan 21 20:34:38 volumio volumio[1042]: info: CorePlayQueue::getTrack 0
Jan 21 20:34:38 volumio volumio[1042]: info: CoreCommandRouter::volumioPushState
Jan 21 20:34:38 volumio volumio[1042]: info: CorePlayQueue::getTrack 0
Jan 21 20:34:38 volumio volumio[1042]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current ytmusic Received mpd
Jan 21 20:34:38 volumio volumio[1042]: info: ------------------------------ 183ms
Jan 21 20:34:38 volumio volumio[1042]: info: ------------------------------ 91ms
Jan 21 20:34:38 volumio volumio[1042]: info: ------------------------------ 91ms
Jan 21 20:34:38 volumio volumio[1042]: SPOTIFY: RECEIVED VOLUMIO VOLUME 77
Jan 21 20:34:38 volumio volumio[1042]: SPOTIFY: RECEIVED VOLUMIO VOLUME 77
Jan 21 20:34:38 volumio volumio[1042]: SPOTIFY: RECEIVED VOLUMIO VOLUME 77
Jan 21 20:34:38 volumio volumio[1042]: SPOTIFY: RECEIVED VOLUMIO VOLUME 77
Jan 21 20:34:38 volumio volumio[1042]: SPOTIFY: RECEIVED VOLUMIO VOLUME 77
Jan 21 20:34:38 volumio volumio[1042]: SPOTIFY: RECEIVED VOLUMIO VOLUME 77
Jan 21 20:34:38 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Jan 21 20:34:38 volumio volumio[1042]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined
Jan 21 20:34:38 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates
Jan 21 20:34:38 volumio volumio[1042]: info: Received Get System Version
Jan 21 20:34:38 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jan 21 20:34:38 volumio volumio[1042]: info: Received Get System Info
Jan 21 20:34:38 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jan 21 20:34:38 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jan 21 20:34:38 volumio volumio[1042]: info: Discovery: Getting this device information
Jan 21 20:34:38 volumio volumio[1042]: info: CoreCommandRouter::volumioGetState
Jan 21 20:34:38 volumio volumio[1042]: info: CorePlayQueue::getTrack 0
Jan 21 20:34:38 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jan 21 20:34:38 volumio volumio[1042]: error: MyVolumio Plugin failed to authenticate in a timely fashion
Jan 21 20:34:38 volumio volumio[1042]: info: Completed starting MyVolumio Plugin
Jan 21 20:34:38 volumio volumio[1042]:  [Metrics]  CommandRouter:  51s 827.24ms 
Jan 21 20:34:38 volumio volumio[1042]: info: CoreCommandRouter::volumiosetStartupVolume
Jan 21 20:34:38 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 21 20:34:38 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 21 20:34:38 volumio volumio[1042]: info: CoreCommandRouter::Close All Modals sent
Jan 21 20:34:38 volumio volumio[1042]: info: CoreCommandRouter::Close All Modals sent
Jan 21 20:34:39 volumio sudo[1704]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=admin,password=De_deziner1,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //172.16.1.252/multimedia/ /mnt/NAS/Kharisstore
Jan 21 20:34:39 volumio sudo[1704]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 21 20:34:39 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
Jan 21 20:34:39 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Jan 21 20:34:40 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
Jan 21 20:34:40 volumio sudo[1668]: pam_unix(sudo:session): session closed for user root
Jan 21 20:34:40 volumio volumio[1042]: info: Cannot mount NAS extra_music at system boot, trial number 4 ,retrying in 5 seconds
Jan 21 20:34:40 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation.
Jan 21 20:34:40 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -113
Jan 21 20:34:40 volumio kernel: CIFS: Attempting to mount //172.16.1.252/multimedia
Jan 21 20:34:40 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jan 21 20:34:45 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jan 21 20:34:46 volumio sudo[1732]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=admin,password=De_deziner1,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //172.16.1.252/extra_music/ /mnt/NAS/extra_music
Jan 21 20:34:46 volumio sudo[1732]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 21 20:34:46 volumio volumio[1042]: info: BOOT COMPLETED
Jan 21 20:34:47 volumio sudo[1704]: pam_unix(sudo:session): session closed for user root
Jan 21 20:34:47 volumio volumio[1042]: info: Cannot mount NAS Kharisstore at system boot, trial number 4 ,retrying in 5 seconds
Jan 21 20:34:47 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation.
Jan 21 20:34:47 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -113
Jan 21 20:34:47 volumio kernel: CIFS: Attempting to mount //172.16.1.252/extra_music
Jan 21 20:34:49 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jan 21 20:34:52 volumio sudo[1754]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=admin,password=De_deziner1,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //172.16.1.252/multimedia/ /mnt/NAS/Kharisstore
Jan 21 20:34:52 volumio sudo[1754]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 21 20:34:53 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jan 21 20:34:53 volumio sudo[1732]: pam_unix(sudo:session): session closed for user root
Jan 21 20:34:53 volumio volumio[1042]: info: Cannot mount NAS at system boot, trial number 4 ,stopping
Jan 21 20:34:53 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation.
Jan 21 20:34:53 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -113
Jan 21 20:34:53 volumio kernel: CIFS: Attempting to mount //172.16.1.252/multimedia
Jan 21 20:34:57 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jan 21 20:34:59 volumio volumio[1042]: info: CoreCommandRouter::volumioGetState
Jan 21 20:34:59 volumio volumio[1042]: info: CorePlayQueue::getTrack 0
Jan 21 20:34:59 volumio sudo[1754]: pam_unix(sudo:session): session closed for user root
Jan 21 20:34:59 volumio volumio[1042]: info: Cannot mount NAS at system boot, trial number 4 ,stopping
Jan 21 20:34:59 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation.
Jan 21 20:34:59 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -113
Jan 21 20:35:10 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Jan 21 20:35:10 volumio volumio[1042]: info: CURURI: music-library
Jan 21 20:35:10 volumio volumio[1042]: info: Preload queue cleared
Jan 21 20:35:12 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Jan 21 20:35:12 volumio volumio[1042]: info: CURURI: music-library/USB
Jan 21 20:35:12 volumio volumio[1042]: error: Failed LSINFO: null
Jan 21 20:35:12 volumio volumio[1042]: info: Preload queue cleared
Jan 21 20:35:16 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Jan 21 20:35:16 volumio volumio[1042]: info: CURURI: music-library/INTERNAL
Jan 21 20:35:16 volumio volumio[1042]: info: Preload queue cleared
Jan 21 20:35:18 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Jan 21 20:35:18 volumio volumio[1042]: info: CURURI: music-library/INTERNAL/NowPlayingPlugin
Jan 21 20:35:18 volumio volumio[1042]: info: Preload queue cleared
Jan 21 20:35:20 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Jan 21 20:35:20 volumio volumio[1042]: info: CURURI: music-library/INTERNAL/NowPlayingPlugin/My Backgrounds
Jan 21 20:35:20 volumio volumio[1042]: error: Failed LSINFO: null
Jan 21 20:35:20 volumio volumio[1042]: info: Preload queue cleared
Jan 21 20:35:24 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Jan 21 20:35:24 volumio volumio[1042]: info: CURURI: music-library/INTERNAL/NowPlayingPlugin/Settings Backups
Jan 21 20:35:24 volumio volumio[1042]: error: Failed LSINFO: null
Jan 21 20:35:24 volumio volumio[1042]: info: Preload queue cleared
Jan 21 20:35:28 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Jan 21 20:35:28 volumio volumio[1042]: info: CURURI: music-library/USB
Jan 21 20:35:28 volumio volumio[1042]: error: Failed LSINFO: null
Jan 21 20:35:28 volumio volumio[1042]: info: Preload queue cleared
Jan 21 20:35:42 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Jan 21 20:35:42 volumio volumio[1042]: info: CURURI: music-library/USB
Jan 21 20:35:42 volumio volumio[1042]: error: Failed LSINFO: null
Jan 21 20:35:42 volumio volumio[1042]: info: Preload queue cleared
Jan 21 20:35:44 volumio ntpd[844]: Soliciting pool server 2606:4700:f1::1
Jan 21 20:35:48 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: ytmusic , handleBrowseUri
Jan 21 20:35:48 volumio volumio[1042]: info: [ytmusic-browse] browseUri: ytmusic
Jan 21 20:35:48 volumio volumio[1042]: info: Preload queue cleared
Jan 21 20:35:51 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri
Jan 21 20:35:51 volumio volumio[1042]: info: Preload queue cleared
Jan 21 20:35:52 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri
Jan 21 20:35:53 volumio volumio[1042]: info: Preload queue cleared
Jan 21 20:35:54 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri
Jan 21 20:35:54 volumio volumio[1042]: info: Preload queue cleared
Jan 21 20:35:56 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri
Jan 21 20:35:56 volumio volumio[1042]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Jan 21 20:35:56 volumio volumio[1042]: TypeError: Cannot read property 'length' of undefined
Jan 21 20:35:56 volumio volumio[1042]:     at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25
Jan 21 20:35:56 volumio volumio[1042]:     at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13
Jan 21 20:35:56 volumio volumio[1042]:     at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18)
Jan 21 20:35:56 volumio volumio[1042]:     at Parser.emit (events.js:315:20)
Jan 21 20:35:56 volumio volumio[1042]:     at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26)
Jan 21 20:35:56 volumio volumio[1042]:     at emit (/volumio/node_modules/sax/lib/sax.js:624:35)
Jan 21 20:35:56 volumio volumio[1042]:     at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5)
Jan 21 20:35:56 volumio volumio[1042]:     at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7)
Jan 21 20:35:56 volumio volumio[1042]:     at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13)
Jan 21 20:35:56 volumio volumio[1042]:     at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31)
Jan 21 20:35:56 volumio volumio[1042]:     at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59)
Jan 21 20:35:56 volumio volumio[1042]:     at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19)
Jan 21 20:35:56 volumio volumio[1042]:     at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15)
Jan 21 20:35:56 volumio volumio[1042]:     at IncomingMessage.emit (events.js:327:22)
Jan 21 20:35:56 volumio volumio[1042]:     at endReadableNT (internal/streams/readable.js:1327:12)
Jan 21 20:35:56 volumio volumio[1042]:     at processTicksAndRejections (internal/process/task_queues.js:80:21)
Jan 21 20:35:56 volumio volumio[1042]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Jan 21 20:35:57 volumio sudo[1927]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-21 20:34
Jan 21 20:35:57 volumio sudo[1927]: 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="f6610a1100d2380eef33bd3004af43153c786f96"
VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6"
VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a"
VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET"
VOLUMIO_VERSION="3.785"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"