Apr 15 22:06:31 volumio ntpd[898]: CLOCK: time stepped by 279413.232455
Apr 15 22:06:31 volumio ntpd[898]: CLOCK: time changed from 2025-04-12 to 2025-04-15
Apr 15 22:06:31 volumio ntpd[898]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes
Apr 15 22:06:31 volumio systemd[1]: Starting dpkg-db-backup.service - Daily dpkg database backup service...
Apr 15 22:06:31 volumio systemd[1]: Starting e2scrub_all.service - Online ext4 Metadata Check for All Filesystems...
Apr 15 22:06:31 volumio systemd[1]: Started ntpsec-rotate-stats.service - Rotate ntpd stats.
Apr 15 22:06:32 volumio systemd[1]: e2scrub_all.service: Deactivated successfully.
Apr 15 22:06:32 volumio systemd[1]: Finished e2scrub_all.service - Online ext4 Metadata Check for All Filesystems.
Apr 15 22:06:32 volumio systemd[1]: ntpsec-rotate-stats.service: Deactivated successfully.
Apr 15 22:06:32 volumio systemd[1]: dpkg-db-backup.service: Deactivated successfully.
Apr 15 22:06:32 volumio systemd[1]: Finished dpkg-db-backup.service - Daily dpkg database backup service.
Apr 15 22:06:33 volumio sudo[1835]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Apr 15 22:06:33 volumio sudo[1834]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Apr 15 22:06:33 volumio sudo[1834]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 15 22:06:33 volumio sudo[1835]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 15 22:06:33 volumio sudo[1834]: pam_unix(sudo:session): session closed for user root
Apr 15 22:06:33 volumio sudo[1835]: pam_unix(sudo:session): session closed for user root
Apr 15 22:06:33 volumio volumio[1095]: verbose: New Socket.io Connection to 192.168.1.27 from 192.168.1.106 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7
Apr 15 22:06:33 volumio volumio[1095]: info: CoreCommandRouter::volumioGetVisibleSources
Apr 15 22:06:33 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 15 22:06:33 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps
Apr 15 22:06:33 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode
Apr 15 22:06:34 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages
Apr 15 22:06:34 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones
Apr 15 22:06:34 volumio volumio[1095]: info: Received Get System Info
Apr 15 22:06:34 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Apr 15 22:06:34 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Apr 15 22:06:34 volumio volumio[1095]: info: Discovery: Getting this device information
Apr 15 22:06:34 volumio volumio[1095]: info: CoreCommandRouter::volumioGetState
Apr 15 22:06:34 volumio volumio[1095]: info: CorePlayQueue::getTrack 0
Apr 15 22:06:34 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Apr 15 22:06:34 volumio volumio-remote-updater[1745]: No test mode
Apr 15 22:06:34 volumio volumio-remote-updater[1745]: No alpha test mode
Apr 15 22:06:34 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
Apr 15 22:06:34 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Apr 15 22:06:34 volumio volumio[1095]: info: CoreCommandRouter::getUIConfigOnPlugin
Apr 15 22:06:34 volumio volumio[1095]: info: Retrieving Cloud Streaming UI
Apr 15 22:06:34 volumio volumio[1095]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
- Improved translations
NEW ADDITIONS
- Update to TIDAL Connect
- Add Install-to-disk option for the new RPi5 HW revisions
","title":"Update v3.799","updateavailable":true}
Apr 15 22:06:34 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Apr 15 22:06:34 volumio volumio[1095]: info: Getting Tidal Cloud Configuration
Apr 15 22:06:34 volumio volumio[1095]: info: CoreCommandRouter::getUIConfigOnPlugin
Apr 15 22:06:34 volumio volumio[1095]: info: Getting Qobuz Cloud Configuration
Apr 15 22:06:34 volumio volumio[1095]: info: Asking plugin for UI Config
Apr 15 22:06:34 volumio volumio[1095]: info: CoreCommandRouter::getUIConfigOnPlugin
Apr 15 22:06:34 volumio volumio[1095]: info: Getting Spotify Cloud Configuration
Apr 15 22:06:34 volumio volumio[1095]: info: Asking plugin for UI Config
Apr 15 22:06:34 volumio volumio[1095]: info: CoreCommandRouter::getUIConfigOnPlugin
Apr 15 22:06:34 volumio volumio[1095]: info: Saving Spotify Acccount
Apr 15 22:06:34 volumio volumio[1095]: info: Got Tidal Cloud Configuration
Apr 15 22:06:34 volumio volumio[1095]: info: Got it
Apr 15 22:06:34 volumio volumio[1095]: info: Got it
Apr 15 22:06:34 volumio volumio[1095]: error: Could not retrieve Spotify Config from plugin Spotify: no section found
Apr 15 22:06:34 volumio volumio[1095]: info: CoreCommandRouter::getUIConfigOnPlugin
Apr 15 22:06:34 volumio volumio[1095]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave')
Apr 15 22:06:35 volumio volumio[1095]: info: Discovery: A device disappeared from network
Apr 15 22:06:35 volumio volumio[1095]: info: Discovery: Device volumio disappeared from network
Apr 15 22:06:35 volumio volumio[1095]: info: Discovery: A device disappeared from network
Apr 15 22:06:35 volumio volumio[1095]: info: Discovery: A device disappeared from network
Apr 15 22:06:35 volumio volumio[1095]: info: Discovery: Device primo disappeared from network
Apr 15 22:06:35 volumio volumio[1095]: info: Discovery: Disconnected from remote: 192.168.1.96
Apr 15 22:06:35 volumio volumio[1095]: info: Discovery: A device disappeared from network
Apr 15 22:06:35 volumio volumio[1095]: info: Discovery: Device primo-marco disappeared from network
Apr 15 22:06:35 volumio volumio[1095]: info: Discovery: Disconnected from remote: 192.168.1.155
Apr 15 22:06:35 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Apr 15 22:06:35 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage
Apr 15 22:06:35 volumio volumio[1095]: info: Loading i18n strings for locale en
Apr 15 22:06:35 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone
Apr 15 22:06:35 volumio volumio[1095]: info: Setting timezone to Europe/Amsterdam
Apr 15 22:06:35 volumio sudo[1846]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime
Apr 15 22:06:35 volumio sudo[1846]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 15 22:06:35 volumio sudo[1846]: pam_unix(sudo:session): session closed for user root
Apr 15 22:06:35 volumio sudo[1850]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/Amsterdam /etc/localtime
Apr 15 22:06:35 volumio sudo[1850]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 15 22:06:35 volumio sudo[1850]: pam_unix(sudo:session): session closed for user root
Apr 15 22:06:35 volumio sudo[1854]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime
Apr 15 22:06:35 volumio sudo[1854]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 15 22:06:35 volumio sudo[1854]: pam_unix(sudo:session): session closed for user root
Apr 15 22:06:35 volumio sudo[1858]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/Amsterdam
Apr 15 22:06:35 volumio sudo[1858]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 15 22:06:35 volumio sudo[1858]: pam_unix(sudo:session): session closed for user root
Apr 15 22:06:35 volumio volumio[1095]: info: Discovery: A device disappeared from network
Apr 15 22:06:35 volumio volumio[1095]: Updating browse sources language
Apr 15 22:06:35 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 15 22:06:35 volumio volumio[1095]: info: Fetching Streaming Services browse cache
Apr 15 22:06:35 volumio volumio[1095]: info: Discovery: adding 46637c89-32a4-4ae4-a5c1-09caf2e7469d
Apr 15 22:06:35 volumio volumio[1095]: info: Discovery: Found device Primo-Marco
Apr 15 22:06:35 volumio volumio[1095]: info: Discovery: Connecting to remote: 192.168.1.155
Apr 15 22:06:36 volumio volumio[1095]: info: Discovery: adding e043b1d1-beae-4e79-8145-a065e25ca46c
Apr 15 22:06:36 volumio volumio[1095]: info: Discovery: Found device Volumio
Apr 15 22:06:36 volumio volumio[1095]: info: CoreCommandRouter::volumioGetState
Apr 15 22:06:36 volumio volumio[1095]: info: CorePlayQueue::getTrack 0
Apr 15 22:06:36 volumio volumio[1095]: info: Discovery: this is already registered, e043b1d1-beae-4e79-8145-a065e25ca46c
Apr 15 22:06:36 volumio volumio[1095]: info: Discovery: Found device Volumio
Apr 15 22:06:36 volumio volumio[1095]: info: CoreCommandRouter::volumioGetState
Apr 15 22:06:36 volumio volumio[1095]: info: CorePlayQueue::getTrack 0
Apr 15 22:06:36 volumio volumio[1095]: info: Discovery: this is already registered, e043b1d1-beae-4e79-8145-a065e25ca46c
Apr 15 22:06:36 volumio volumio[1095]: info: Discovery: Found device Volumio
Apr 15 22:06:36 volumio volumio[1095]: info: CoreCommandRouter::volumioGetState
Apr 15 22:06:36 volumio volumio[1095]: info: CorePlayQueue::getTrack 0
Apr 15 22:06:36 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken
Apr 15 22:06:36 volumio volumio[1095]: info: MyVolumio login type: Token
Apr 15 22:06:36 volumio volumio[1095]: info: Discovery: adding d6026ebd-3a3d-482c-ac7d-054a97232f49
Apr 15 22:06:36 volumio volumio[1095]: info: Discovery: Found device Primo
Apr 15 22:06:36 volumio volumio[1095]: info: Discovery: Connecting to remote: 192.168.1.96
Apr 15 22:06:36 volumio volumio[1095]: info: Discovery: Connected to remote: 192.168.1.155
Apr 15 22:06:36 volumio volumio[1095]: info: Discovery: Connected to remote: 192.168.1.96
Apr 15 22:06:36 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Apr 15 22:06:37 volumio volumio[1095]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN
Apr 15 22:06:37 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken
Apr 15 22:06:37 volumio volumio[1095]: info: MyVolumio login type: Token
Apr 15 22:06:38 volumio volumio[1095]: info: MyVolumio token set successfully
Apr 15 22:06:38 volumio volumio[1095]: info: MYVOLUMIO: Adding device
Apr 15 22:06:38 volumio volumio[1095]: info: MYVOLUMIO: Evaluating Server
Apr 15 22:06:38 volumio volumio[1095]: info: MyVolumio Plan changed: premium
Apr 15 22:06:38 volumio volumio[1095]: info: [MyVolumio PluginManager] Subscribed plan changed to premium
Apr 15 22:06:38 volumio volumio[1095]: info: Removing browser output: myVolumio user plan is not superstar
Apr 15 22:06:38 volumio volumio[1095]: info: Removing audio output:
Apr 15 22:06:38 volumio volumio[1095]: info: MYVOLUMIO: Adding device
Apr 15 22:06:38 volumio volumio[1095]: info: MYVOLUMIO: Evaluating Server
Apr 15 22:06:38 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Apr 15 22:06:38 volumio volumio[1095]: info: Remote config written successfully
Apr 15 22:06:38 volumio volumio[1095]: info: Starting Tunnel 1
Apr 15 22:06:38 volumio volumio[1095]: info: Starting Tunnel Connection Checker
Apr 15 22:06:38 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage
Apr 15 22:06:38 volumio volumio[1095]: info: Loading i18n strings for locale en
Apr 15 22:06:38 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone
Apr 15 22:06:38 volumio volumio[1095]: info: Setting timezone to Europe/Amsterdam
Apr 15 22:06:38 volumio sudo[1902]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime
Apr 15 22:06:38 volumio sudo[1902]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 15 22:06:38 volumio sudo[1902]: pam_unix(sudo:session): session closed for user root
Apr 15 22:06:39 volumio sudo[1906]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/Amsterdam /etc/localtime
Apr 15 22:06:39 volumio sudo[1906]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 15 22:06:39 volumio sudo[1906]: pam_unix(sudo:session): session closed for user root
Apr 15 22:06:39 volumio systemd[1]: Starting apt-daily-upgrade.service - Daily apt upgrade and clean activities...
Apr 15 22:06:39 volumio sudo[1911]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime
Apr 15 22:06:39 volumio sudo[1911]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 15 22:06:39 volumio sudo[1911]: pam_unix(sudo:session): session closed for user root
Apr 15 22:06:39 volumio sudo[1916]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/Amsterdam
Apr 15 22:06:39 volumio sudo[1916]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 15 22:06:39 volumio sudo[1916]: pam_unix(sudo:session): session closed for user root
Apr 15 22:06:39 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache
Apr 15 22:06:39 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks
Apr 15 22:06:39 volumio sudo[1923]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Apr 15 22:06:39 volumio sudo[1923]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 15 22:06:39 volumio volumio[1095]: info: MYVolumio Device enabled
Apr 15 22:06:39 volumio volumio[1095]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins...
Apr 15 22:06:39 volumio volumio[1095]: info: MyVolumio status changed
Apr 15 22:06:39 volumio volumio[1095]: info: Streaming services startup
Apr 15 22:06:39 volumio volumio[1095]: info: Starting Streaming Daemon
Apr 15 22:06:39 volumio volumio[1095]: info: Fetching Streaming Services browse cache
Apr 15 22:06:39 volumio sudo[1973]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Apr 15 22:06:39 volumio sudo[1973]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 15 22:06:39 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Apr 15 22:06:39 volumio sudo[1973]: pam_unix(sudo:session): session closed for user root
Apr 15 22:06:39 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Apr 15 22:06:39 volumio volumio[1095]: error: [MyVolumio PluginManager] Could not read cache file: ENOENT: no such file or directory, open '/data/myvolumio/cache.data'
Apr 15 22:06:39 volumio volumio[1095]: info: Setting Geolocation for MyVolumio to eu2
Apr 15 22:06:39 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 15 22:06:39 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 15 22:06:39 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 15 22:06:39 volumio systemd[1]: apt-daily-upgrade.service: Deactivated successfully.
Apr 15 22:06:39 volumio systemd[1]: Finished apt-daily-upgrade.service - Daily apt upgrade and clean activities.
Apr 15 22:06:40 volumio volumio[1095]: error: Cannot start Volumio Streaming Daemon
Apr 15 22:06:40 volumio volumio[1095]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Apr 15 22:06:40 volumio volumio[1095]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Apr 15 22:06:40 volumio volumio[1095]: Updating browse sources language
Apr 15 22:06:40 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 15 22:06:40 volumio volumio[1095]: info: Setting Geolocation for MyVolumio to eu8
Apr 15 22:06:40 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 15 22:06:40 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 15 22:06:40 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 15 22:06:40 volumio volumio[1095]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8
Apr 15 22:06:40 volumio volumio[1095]: info: Successfully Added MyVolumio device
Apr 15 22:06:40 volumio volumio[1095]: info: Updating MyVolumio device info
Apr 15 22:06:40 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 15 22:06:40 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 15 22:06:40 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 15 22:06:41 volumio volumio[1095]: info: CoreCommandRouter::volumioGetState
Apr 15 22:06:41 volumio volumio[1095]: info: CorePlayQueue::getTrack 0
Apr 15 22:06:41 volumio volumio[1095]: info: Updating MyVolumio device info
Apr 15 22:06:41 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 15 22:06:41 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 15 22:06:41 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 15 22:06:41 volumio volumio[1095]: info: Successfully Updated MyVolumio device
Apr 15 22:06:41 volumio volumio[1095]: info: Successfully Added MyVolumio device
Apr 15 22:06:41 volumio volumio[1095]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"...
Apr 15 22:06:42 volumio volumio[1095]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart
Apr 15 22:06:42 volumio volumio[1095]: error: [MyVolumio PluginManager] ATTENTION!!!: Plugin bluetooth does not return adequate promise from onVolumioStart: please update!
Apr 15 22:06:42 volumio volumio[1095]: info: [MyVolumio PluginManager] Loading plugin "multiroom"...
Apr 15 22:06:42 volumio sudo[1923]: pam_unix(sudo:session): session closed for user root
Apr 15 22:06:43 volumio sudo[1998]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom
Apr 15 22:06:43 volumio sudo[1998]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 15 22:06:43 volumio sudo[1998]: pam_unix(sudo:session): session closed for user root
Apr 15 22:06:43 volumio volumio[1095]: info: MRS: MultiRoom plugin initialized
Apr 15 22:06:43 volumio volumio[1095]: info: MRS: STOPPING SNAPCLIENT
Apr 15 22:06:43 volumio volumio[1095]: info: MRS: Snap server stop
Apr 15 22:06:43 volumio volumio[1095]: info: MRS: STOPPING volumioStreaming
Apr 15 22:06:43 volumio volumio[1095]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"...
Apr 15 22:06:43 volumio sudo[2016]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver
Apr 15 22:06:43 volumio sudo[2016]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 15 22:06:43 volumio sudo[2017]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient
Apr 15 22:06:43 volumio sudo[2019]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming
Apr 15 22:06:43 volumio sudo[2019]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 15 22:06:43 volumio volumio[1095]: info: [MyVolumio PluginManager] Loading plugin "manifestui"...
Apr 15 22:06:43 volumio sudo[2021]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/*
Apr 15 22:06:43 volumio sudo[2017]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 15 22:06:43 volumio volumio[1095]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"...
Apr 15 22:06:43 volumio sudo[2021]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 15 22:06:43 volumio sudo[2021]: pam_unix(sudo:session): session closed for user root
Apr 15 22:06:43 volumio volumio[1095]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"...
Apr 15 22:06:43 volumio volumio[1095]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"...
Apr 15 22:06:43 volumio sudo[2017]: pam_unix(sudo:session): session closed for user root
Apr 15 22:06:43 volumio volumio[1095]: info: [MyVolumio PluginManager] Loading plugin "tidal"...
Apr 15 22:06:43 volumio sudo[2019]: pam_unix(sudo:session): session closed for user root
Apr 15 22:06:43 volumio sudo[2016]: pam_unix(sudo:session): session closed for user root
Apr 15 22:06:44 volumio volumio[1095]: info: [MyVolumio PluginManager] Loading plugin "qobuz"...
Apr 15 22:06:44 volumio volumio[1095]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"...
Apr 15 22:06:44 volumio volumio[1095]: info: Preparing to generate the ALSA configuration file
Apr 15 22:06:44 volumio volumio[1095]: info: Successfully Updated MyVolumio device
Apr 15 22:06:44 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid
Apr 15 22:06:44 volumio volumio[1095]: info: MRS: Removed streaming files
Apr 15 22:06:44 volumio volumio[1095]: info: MRS: volumioStreaming STOPPED
Apr 15 22:06:44 volumio volumio[1095]: info: MRS: SNAPSERVER STOPPED
Apr 15 22:06:44 volumio volumio[1095]: info: MRS: SNAPCLIENT STOPPED
Apr 15 22:06:44 volumio volumio[1095]: info: Asound.conf file unchanged, so no further update is needed
Apr 15 22:06:44 volumio volumio[1095]: info: Output device has changed, restarting MPD
Apr 15 22:06:44 volumio volumio[1095]: info: Output device has changed, restarting Shairport Sync
Apr 15 22:06:44 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 15 22:06:44 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 15 22:06:44 volumio sudo[2034]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Apr 15 22:06:44 volumio sudo[2034]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 15 22:06:44 volumio sudo[2036]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Apr 15 22:06:44 volumio volumio[1095]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Apr 15 22:06:44 volumio volumio[1095]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration
Apr 15 22:06:44 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 15 22:06:44 volumio sudo[2036]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 15 22:06:44 volumio volumio[1095]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth
Apr 15 22:06:44 volumio volumio[1095]: ------------------------------------ BT MESSAGE: [FUNC] onStart
Apr 15 22:06:44 volumio volumio[1095]: ------------------------------------ BT MESSAGE: Starting Volumio Bluetooth Service
Apr 15 22:06:44 volumio sudo[2034]: pam_unix(sudo:session): session closed for user root
Apr 15 22:06:44 volumio volumio[1095]: ------------------------------------ BT MESSAGE: Boot config /etc/bluetooth/volumio.conf: cache mode = tmp
Apr 15 22:06:44 volumio volumio[1095]: ------------------------------------ BT MESSAGE: [metaCache] Created directory: /tmp/bluetooth-cache/
Apr 15 22:06:44 volumio volumio[1095]: ------------------------------------ BT MESSAGE: [metaCache] Directory exists and is ready.
Apr 15 22:06:44 volumio systemd[1]: Stopping mpd.service - Music Player Daemon...
Apr 15 22:06:44 volumio volumio[1095]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom
Apr 15 22:06:44 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus
Apr 15 22:06:44 volumio volumio[1095]: info: MRS: Pushing multiroomSync output for this device
Apr 15 22:06:44 volumio volumio[1095]: info: MRS: Pushing multiroomSync output
Apr 15 22:06:44 volumio volumio[1095]: info: Adding audio output:
Apr 15 22:06:44 volumio volumio[1095]: info: Adding audio output:
Apr 15 22:06:44 volumio volumio[1095]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration
Apr 15 22:06:44 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 15 22:06:44 volumio volumio[1095]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio
Apr 15 22:06:44 volumio volumio[1095]: info: Adding METAVOLUMIO REST API Endpoints
Apr 15 22:06:44 volumio bluetoothd[680]: Adv Monitor app :1.32 disconnected from D-Bus
Apr 15 22:06:44 volumio volumio[1095]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio
Apr 15 22:06:44 volumio volumio[1095]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio
Apr 15 22:06:44 volumio volumio[1095]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio
Apr 15 22:06:44 volumio volumio[1095]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio
Apr 15 22:06:44 volumio volumio[1095]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui
Apr 15 22:06:44 volumio volumio[1095]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller
Apr 15 22:06:44 volumio volumio[1095]: info: Preparing CD Folders
Apr 15 22:06:44 volumio volumio[1095]: info: Adding CD REST API Endpoints
Apr 15 22:06:44 volumio volumio[1095]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller
Apr 15 22:06:44 volumio volumio[1095]: info: Starting UDEV Watcher for CD
Apr 15 22:06:44 volumio volumio[1095]: info: Detecting CD presence with UDEV
Apr 15 22:06:44 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices
Apr 15 22:06:45 volumio volumio[1095]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs
Apr 15 22:06:45 volumio volumio[1095]: info: Adding inputs REST Endpoints
Apr 15 22:06:45 volumio volumio[1095]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs
Apr 15 22:06:45 volumio volumio[1095]: info: Scanning Audio Inputs
Apr 15 22:06:46 volumio volumio[1095]: info: Checking against Known Cards name
Apr 15 22:06:46 volumio volumio[1095]: info: Adding Server instance for streaming
Apr 15 22:06:46 volumio volumio[1095]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio
Apr 15 22:06:46 volumio volumio[1095]: error: Hi Res Audio Failed Login: Missing Login Data
Apr 15 22:06:46 volumio volumio[1095]: info: Adding HIGHRESAUDIO REST API Endpoints
Apr 15 22:06:46 volumio volumio[1095]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal
Apr 15 22:06:46 volumio volumio[1095]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz
Apr 15 22:06:46 volumio volumio[1095]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect
Apr 15 22:06:46 volumio systemd[1]: mpd.service: Deactivated successfully.
Apr 15 22:06:46 volumio systemd[1]: Stopped mpd.service - Music Player Daemon.
Apr 15 22:06:46 volumio systemd[1]: mpd.service: Consumed 2.497s CPU time.
Apr 15 22:06:46 volumio systemd[1]: mpd.socket: Deactivated successfully.
Apr 15 22:06:46 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket.
Apr 15 22:06:46 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket...
Apr 15 22:06:46 volumio volumio[1095]: info: Stopping AccessToken refresher cron
Apr 15 22:06:46 volumio sudo[2068]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service
Apr 15 22:06:46 volumio sudo[2068]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 15 22:06:46 volumio volumio[1095]: info: AccessToken refresher cron started
Apr 15 22:06:46 volumio volumio[1095]: info: Stopping AccessToken refresher cron for QOBUZ
Apr 15 22:06:46 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket.
Apr 15 22:06:46 volumio systemd[1]: Starting mpd.service - Music Player Daemon...
Apr 15 22:06:46 volumio volumio[1095]: info: AccessToken refresher cron started for QOBUZ
Apr 15 22:06:46 volumio volumio[1095]: info: Adding TIDAL REST API Endpoints
Apr 15 22:06:46 volumio volumio[1095]: info: Adding QOBUZ REST API Endpoints
Apr 15 22:06:46 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 15 22:06:46 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 15 22:06:46 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
Apr 15 22:06:46 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Apr 15 22:06:46 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
Apr 15 22:06:46 volumio volumio[1095]: info: CoreCommandRouter::volumioGetBrowseSources
Apr 15 22:06:46 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Apr 15 22:06:46 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule
Apr 15 22:06:46 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Apr 15 22:06:46 volumio sudo[2068]: pam_unix(sudo:session): session closed for user root
Apr 15 22:06:46 volumio sudo[2071]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Apr 15 22:06:46 volumio sudo[2071]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Apr 15 22:06:46 volumio sudo[2071]: pam_unix(sudo:session): session closed for user root
Apr 15 22:06:46 volumio volumio[1095]: ------------------------------------ BT MESSAGE: Bluetooth adapter powered on
Apr 15 22:06:46 volumio volumio[1095]: info: MPD Permissions set
Apr 15 22:06:46 volumio volumio-remote-updater[1745]: No test mode
Apr 15 22:06:46 volumio volumio-remote-updater[1745]: No alpha test mode
Apr 15 22:06:46 volumio sudo[2078]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service
Apr 15 22:06:46 volumio sudo[2078]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 15 22:06:46 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
Apr 15 22:06:46 volumio sudo[2076]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service
Apr 15 22:06:46 volumio sudo[2076]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 15 22:06:46 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 15 22:06:46 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 15 22:06:46 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 15 22:06:46 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 15 22:06:46 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Apr 15 22:06:46 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Apr 15 22:06:46 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 15 22:06:46 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 15 22:06:46 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 15 22:06:46 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 15 22:06:46 volumio systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel.
Apr 15 22:06:46 volumio autossh[2089]: port set to 0, monitoring disabled
Apr 15 22:06:46 volumio autossh[2089]: starting ssh (count 1)
Apr 15 22:06:46 volumio autossh[2089]: ssh child pid is 2093
Apr 15 22:06:46 volumio systemd[1]: Started volumiobt.service - Volumio Bluetooth Module.
Apr 15 22:06:46 volumio sudo[2078]: pam_unix(sudo:session): session closed for user root
Apr 15 22:06:46 volumio sudo[2076]: pam_unix(sudo:session): session closed for user root
Apr 15 22:06:46 volumio volumiobt[2094]: INFO [BTSTART] Ensuring Bluetooth directory exists...
Apr 15 22:06:46 volumio sudo[2095]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth
Apr 15 22:06:46 volumio sudo[2095]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 15 22:06:46 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 15 22:06:46 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 15 22:06:46 volumio sudo[2095]: pam_unix(sudo:session): session closed for user root
Apr 15 22:06:46 volumio volumio[1095]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
- Improved translations
NEW ADDITIONS
- Update to TIDAL Connect
- Add Install-to-disk option for the new RPi5 HW revisions
","title":"Update v3.799","updateavailable":true}
Apr 15 22:06:46 volumio sudo[2097]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth
Apr 15 22:06:46 volumio sudo[2097]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 15 22:06:46 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Apr 15 22:06:46 volumio volumio[1095]: ------------------------------------ BT MESSAGE: volumiobt.service started successfully
Apr 15 22:06:46 volumio volumio[1095]: info: CoreCommandRouter::volumioStop
Apr 15 22:06:46 volumio volumio[1095]: info: CoreStateMachine::stop
Apr 15 22:06:46 volumio volumio[1095]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 15 22:06:46 volumio volumio[1095]: ------------------------------------ BT MESSAGE: [FUNC] dbusStart
Apr 15 22:06:46 volumio volumio[1095]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 15 22:06:46 volumio volumio[1095]: info: Remote SSH Started
Apr 15 22:06:46 volumio sudo[2097]: pam_unix(sudo:session): session closed for user root
Apr 15 22:06:46 volumio volumiobt[2099]: INFO [BTSTART] Powering on Bluetooth if needed...
Apr 15 22:06:46 volumio volumiossh-tunnel[2093]: Warning: Permanently added '[eu8.myvolumio.org]:2222' (RSA) to the list of known hosts.
Apr 15 22:06:47 volumio bluetoothd[680]: Adv Monitor app :1.33 disconnected from D-Bus
Apr 15 22:06:47 volumio volumiobt[2102]: INFO [BTSTART] Making Bluetooth discoverable and pairable...
Apr 15 22:06:47 volumio volumiobt[2103]: [75B blob data]
Apr 15 22:06:47 volumio volumiobt[2103]: [bluetooth]# pairable on
Apr 15 22:06:47 volumio volumiobt[2103]: [54B blob data]
Apr 15 22:06:47 volumio bluetoothd[680]: Adv Monitor app :1.34 disconnected from D-Bus
Apr 15 22:06:47 volumio volumiobt[2103]: [bluetooth]#
Apr 15 22:06:47 volumio volumiobt[2104]: INFO [BTSTART] Registering Bluetooth agent...
Apr 15 22:06:47 volumio bluetoothd[680]: Adv Monitor app :1.35 disconnected from D-Bus
Apr 15 22:06:47 volumio volumiobt[2106]: No agent is registered
Apr 15 22:06:47 volumio bluetoothd[680]: Adv Monitor app :1.36 disconnected from D-Bus
Apr 15 22:06:47 volumio volumiobt[2107]: INFO [BTSTART] Agent registered successfully.
Apr 15 22:06:47 volumio volumiobt[2108]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)...
Apr 15 22:06:47 volumio volumio[1095]: info: Starting Shairport Sync
Apr 15 22:06:47 volumio volumio[1095]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9
Apr 15 22:06:47 volumio sudo[2111]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Apr 15 22:06:47 volumio sudo[2111]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 15 22:06:47 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver...
Apr 15 22:06:47 volumio systemd[1]: shairport-sync.service: Deactivated successfully.
Apr 15 22:06:47 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Apr 15 22:06:47 volumio systemd[1]: shairport-sync.service: Consumed 1.825s CPU time.
Apr 15 22:06:47 volumio mpd[2074]: Apr 15 22:06 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Apr 15 22:06:47 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Apr 15 22:06:47 volumio systemd[1]: Started mpd.service - Music Player Daemon.
Apr 15 22:06:47 volumio sudo[2036]: pam_unix(sudo:session): session closed for user root
Apr 15 22:06:47 volumio volumio[1095]: info: Preparing to generate the ALSA configuration file
Apr 15 22:06:47 volumio sudo[2111]: pam_unix(sudo:session): session closed for user root
Apr 15 22:06:47 volumio volumio[1095]: info: Shairport-Sync Started
Apr 15 22:06:47 volumio volumio[1095]: info: Asound.conf file unchanged, so no further update is needed
Apr 15 22:06:47 volumio volumio[1095]: info: Output device has changed, restarting MPD
Apr 15 22:06:47 volumio volumio[1095]: info: Output device has changed, restarting Shairport Sync
Apr 15 22:06:47 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 15 22:06:47 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 15 22:06:48 volumio volumio[1095]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Apr 15 22:06:48 volumio volumio[1095]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration
Apr 15 22:06:48 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 15 22:06:48 volumio sudo[2131]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Apr 15 22:06:48 volumio sudo[2133]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Apr 15 22:06:48 volumio sudo[2133]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 15 22:06:48 volumio sudo[2131]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 15 22:06:48 volumio volumio[1095]: info: Preparing to generate the ALSA configuration file
Apr 15 22:06:48 volumio sudo[2131]: pam_unix(sudo:session): session closed for user root
Apr 15 22:06:48 volumio systemd[1]: Stopping mpd.service - Music Player Daemon...
Apr 15 22:06:48 volumio systemd[1]: mpd.service: Deactivated successfully.
Apr 15 22:06:48 volumio systemd[1]: Stopped mpd.service - Music Player Daemon.
Apr 15 22:06:48 volumio systemd[1]: mpd.service: Consumed 1.160s CPU time.
Apr 15 22:06:48 volumio systemd[1]: mpd.socket: Deactivated successfully.
Apr 15 22:06:48 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket.
Apr 15 22:06:48 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket...
Apr 15 22:06:48 volumio volumio[1095]: error: updateQueue error: null
Apr 15 22:06:48 volumio volumio[1095]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf
Apr 15 22:06:48 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket.
Apr 15 22:06:48 volumio volumio[1095]: info: Reading ALSA contributions from plugins.
Apr 15 22:06:48 volumio systemd[1]: Starting mpd.service - Music Player Daemon...
Apr 15 22:06:48 volumio volumio[1095]: info: MPD Permissions set
Apr 15 22:06:48 volumio volumio[1095]: info: CoreCommandRouter::volumioGetState
Apr 15 22:06:48 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 15 22:06:48 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 15 22:06:48 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 15 22:06:48 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 15 22:06:48 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 15 22:06:48 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 15 22:06:48 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 15 22:06:48 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 15 22:06:48 volumio sudo[2143]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Apr 15 22:06:48 volumio sudo[2143]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Apr 15 22:06:48 volumio sudo[2143]: pam_unix(sudo:session): session closed for user root
Apr 15 22:06:48 volumio volumiobt[2109]: INFO:a2dp-agent:Connecting to system bus.
Apr 15 22:06:48 volumio volumiobt[2109]: INFO:a2dp-agent:Found Bluetooth adapter at: /org/bluez/hci0
Apr 15 22:06:48 volumio volumiobt[2109]: INFO:a2dp-agent:Enabling infinite discovery on adapter.
Apr 15 22:06:48 volumio volumiobt[2109]: INFO:a2dp-agent:Registering agent.
Apr 15 22:06:48 volumio volumiobt[2109]: INFO:a2dp-agent:Searching for MediaPlayer1 interface...
Apr 15 22:06:48 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 15 22:06:48 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 15 22:06:48 volumio volumiobt[2109]: WARNING:a2dp-agent:No active MediaPlayer1 found. Metadata updates won't work.
Apr 15 22:06:48 volumio volumio[1095]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.5CvWEqrMC5R8R7GzBFAP50Q8G0t1.c53014a2eddf6c6f60df78f54a52e40f.state.status'
Apr 15 22:06:48 volumio volumio[1095]: info: Starting Shairport Sync
Apr 15 22:06:48 volumio sudo[2153]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Apr 15 22:06:48 volumio sudo[2153]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 15 22:06:48 volumio volumio[1095]: info: Asound.conf file written
Apr 15 22:06:48 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver...
Apr 15 22:06:48 volumio systemd[1]: shairport-sync.service: Deactivated successfully.
Apr 15 22:06:48 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Apr 15 22:06:48 volumio systemd[1]: shairport-sync.service: Consumed 1.061s CPU time.
Apr 15 22:06:48 volumio sudo[2157]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf
Apr 15 22:06:48 volumio sudo[2157]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 15 22:06:48 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Apr 15 22:06:48 volumio sudo[2157]: pam_unix(sudo:session): session closed for user root
Apr 15 22:06:48 volumio sudo[2153]: pam_unix(sudo:session): session closed for user root
Apr 15 22:06:49 volumio volumio[1095]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2
Apr 15 22:06:49 volumio volumio[1095]: No state is present for card Headphones
Apr 15 22:06:49 volumio volumio[1095]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2
Apr 15 22:06:49 volumio volumio[1095]: /usr/share/alsa/init/bcm2835:20: control element not found
Apr 15 22:06:49 volumio volumio[1095]: No state is present for card Headphones
Apr 15 22:06:49 volumio volumio[1095]: info: Output device has changed, restarting MPD
Apr 15 22:06:49 volumio volumio[1095]: info: Output device has changed, restarting Shairport Sync
Apr 15 22:06:49 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 15 22:06:49 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 15 22:06:49 volumio sudo[2181]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Apr 15 22:06:49 volumio sudo[2181]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 15 22:06:49 volumio volumio[1095]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Apr 15 22:06:49 volumio volumio[1095]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration
Apr 15 22:06:49 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 15 22:06:49 volumio sudo[2179]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Apr 15 22:06:49 volumio sudo[2181]: pam_unix(sudo:session): session closed for user root
Apr 15 22:06:49 volumio sudo[2179]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 15 22:06:49 volumio volumio[1095]: info: MPD Permissions set
Apr 15 22:06:49 volumio volumio[1095]: info: Shairport-Sync Started
Apr 15 22:06:49 volumio systemd[1]: mpd.service: Deactivated successfully.
Apr 15 22:06:49 volumio systemd[1]: Stopped mpd.service - Music Player Daemon.
Apr 15 22:06:49 volumio systemd[1]: mpd.socket: Deactivated successfully.
Apr 15 22:06:49 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket.
Apr 15 22:06:49 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket...
Apr 15 22:06:49 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 15 22:06:49 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 15 22:06:49 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 15 22:06:49 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 15 22:06:49 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 15 22:06:49 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 15 22:06:49 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 15 22:06:49 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 15 22:06:49 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket.
Apr 15 22:06:49 volumio systemd[1]: Starting mpd.service - Music Player Daemon...
Apr 15 22:06:49 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 15 22:06:49 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 15 22:06:49 volumio volumio[1095]: info: TidalConnect service stoped!
Apr 15 22:06:49 volumio sudo[2192]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Apr 15 22:06:49 volumio sudo[2192]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Apr 15 22:06:49 volumio sudo[2192]: pam_unix(sudo:session): session closed for user root
Apr 15 22:06:49 volumio volumio[1095]: info: Starting Shairport Sync
Apr 15 22:06:49 volumio sudo[2203]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Apr 15 22:06:49 volumio sudo[2203]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 15 22:06:49 volumio volumio[1095]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect
Apr 15 22:06:49 volumio volumio[1095]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect
Apr 15 22:06:49 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver...
Apr 15 22:06:49 volumio systemd[1]: shairport-sync.service: Deactivated successfully.
Apr 15 22:06:49 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Apr 15 22:06:49 volumio volumio[1095]: info: MRS: Getting audio outputs on start
Apr 15 22:06:49 volumio volumio[1095]: info: MRS: Requesting all other devices output
Apr 15 22:06:49 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Apr 15 22:06:49 volumio sudo[2203]: pam_unix(sudo:session): session closed for user root
Apr 15 22:06:49 volumio sudo[2224]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service
Apr 15 22:06:49 volumio sudo[2224]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 15 22:06:49 volumio volumio[1095]: info: Shairport-Sync Started
Apr 15 22:06:50 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service.
Apr 15 22:06:50 volumio sudo[2224]: pam_unix(sudo:session): session closed for user root
Apr 15 22:06:50 volumio volumio[1095]: info: Executing endpoint tc_getconfig
Apr 15 22:06:50 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig
Apr 15 22:06:50 volumio vtcs[2228]: STARTING TidalConnect services, version: 1.5.0.45
Apr 15 22:06:50 volumio vtcs[2228]: STARTED TidalConnect services.
Apr 15 22:06:50 volumio volumio[1095]: info: Executing endpoint tc_connect
Apr 15 22:06:50 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect
Apr 15 22:06:50 volumio volumio[1095]: info: Connecting to TidalConnect
Apr 15 22:06:50 volumio volumio[1095]: info: CoreCommandRouter::servicePushState
Apr 15 22:06:50 volumio mpd[2198]: Apr 15 22:06 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Apr 15 22:06:50 volumio systemd[1]: Started mpd.service - Music Player Daemon.
Apr 15 22:06:50 volumio volumio[1095]: info: CoreStateMachine::pushState
Apr 15 22:06:50 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 15 22:06:50 volumio volumio[1095]: info: CoreCommandRouter::volumioPushState
Apr 15 22:06:50 volumio sudo[2133]: pam_unix(sudo:session): session closed for user root
Apr 15 22:06:50 volumio volumio[1095]: info: MRS: Pushing multiroomSync output update for this device
Apr 15 22:06:50 volumio sudo[2179]: pam_unix(sudo:session): session closed for user root
Apr 15 22:06:50 volumio volumio[1095]: info: MRS: Pushing multiroomSync output
Apr 15 22:06:50 volumio volumio[1095]: info: CorePlayQueue::getTrack 0
Apr 15 22:06:50 volumio volumio[1095]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false}
Apr 15 22:06:50 volumio volumio[1095]: verbose: CURRENT POSITION 0
Apr 15 22:06:50 volumio volumio[1095]: info: CoreStateMachine::syncState stateService stop
Apr 15 22:06:50 volumio volumio[1095]: info: CoreStateMachine::syncState currentStatus stop
Apr 15 22:06:50 volumio volumio[1095]: info: CoreStateMachine::pushState
Apr 15 22:06:50 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 15 22:06:50 volumio volumio[1095]: info: CoreCommandRouter::volumioPushState
Apr 15 22:06:50 volumio volumio[1095]: info: MRS: Pushing multiroomSync output update for this device
Apr 15 22:06:50 volumio volumio[1095]: info: MRS: Pushing multiroomSync output
Apr 15 22:06:50 volumio volumio[1095]: info: No code
Apr 15 22:06:50 volumio volumio[1095]: info: CoreStateMachine::pushState
Apr 15 22:06:50 volumio volumio[1095]: info: CoreCommandRouter::volumioPushState
Apr 15 22:06:50 volumio volumio[1095]: info: MRS: Pushing multiroomSync output update for this device
Apr 15 22:06:50 volumio volumio[1095]: info: MRS: Pushing multiroomSync output
Apr 15 22:06:50 volumio volumio[1095]: info: CoreCommandRouter::servicePushState
Apr 15 22:06:50 volumio volumio[1095]: info: CoreStateMachine::pushState
Apr 15 22:06:50 volumio volumio[1095]: info: CoreCommandRouter::volumioPushState
Apr 15 22:06:50 volumio volumio[1095]: info: MRS: Pushing multiroomSync output update for this device
Apr 15 22:06:50 volumio volumio[1095]: info: MRS: Pushing multiroomSync output
Apr 15 22:06:50 volumio volumio[1095]: info: CorePlayQueue::getTrack 0
Apr 15 22:06:50 volumio volumio[1095]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false}
Apr 15 22:06:50 volumio volumio[1095]: verbose: CURRENT POSITION 0
Apr 15 22:06:50 volumio volumio[1095]: info: CoreStateMachine::syncState stateService stop
Apr 15 22:06:50 volumio volumio[1095]: info: CoreStateMachine::syncState currentStatus stop
Apr 15 22:06:50 volumio volumio[1095]: info: CoreStateMachine::pushState
Apr 15 22:06:50 volumio volumio[1095]: info: CoreCommandRouter::volumioPushState
Apr 15 22:06:50 volumio volumio[1095]: info: MRS: Pushing multiroomSync output update for this device
Apr 15 22:06:50 volumio volumio[1095]: info: MRS: Pushing multiroomSync output
Apr 15 22:06:50 volumio volumio[1095]: info: No code
Apr 15 22:06:50 volumio volumio[1095]: info: CoreStateMachine::pushState
Apr 15 22:06:50 volumio volumio[1095]: info: CoreCommandRouter::volumioPushState
Apr 15 22:06:50 volumio volumio[1095]: info: MRS: Pushing multiroomSync output update for this device
Apr 15 22:06:50 volumio volumio[1095]: info: MRS: Pushing multiroomSync output
Apr 15 22:06:51 volumio volumio[1095]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.5CvWEqrMC5R8R7GzBFAP50Q8G0t1.c53014a2eddf6c6f60df78f54a52e40f.state.status'
Apr 15 22:06:51 volumio volumio[1095]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.5CvWEqrMC5R8R7GzBFAP50Q8G0t1.c53014a2eddf6c6f60df78f54a52e40f.state.status'
Apr 15 22:06:51 volumio volumio[1095]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.5CvWEqrMC5R8R7GzBFAP50Q8G0t1.c53014a2eddf6c6f60df78f54a52e40f.state.status'
Apr 15 22:06:51 volumio volumio[1095]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.5CvWEqrMC5R8R7GzBFAP50Q8G0t1.c53014a2eddf6c6f60df78f54a52e40f.state.status'
Apr 15 22:06:51 volumio volumio[1095]: error: MPD error: The expression evaluated to a falsy value:
Apr 15 22:06:51 volumio volumio[1095]: assert.ok(self.idling)
Apr 15 22:06:51 volumio volumio[1095]: error: The expression evaluated to a falsy value:
Apr 15 22:06:51 volumio volumio[1095]: assert.ok(self.idling)
Apr 15 22:06:51 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Apr 15 22:06:51 volumio volumio[1095]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined
Apr 15 22:06:51 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates
Apr 15 22:06:51 volumio volumio[1095]: info: Received Get System Version
Apr 15 22:06:51 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Apr 15 22:06:51 volumio volumio[1095]: info: Received Get System Info
Apr 15 22:06:51 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Apr 15 22:06:51 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Apr 15 22:06:51 volumio volumio[1095]: info: Discovery: Getting this device information
Apr 15 22:06:51 volumio volumio[1095]: info: CoreCommandRouter::volumioGetState
Apr 15 22:06:51 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Apr 15 22:06:51 volumio volumio[1095]: error: updateQueue error: null
Apr 15 22:06:51 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Apr 15 22:06:51 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Apr 15 22:06:51 volumio volumio[1095]: info: Discovery: Getting this device information
Apr 15 22:06:51 volumio volumio[1095]: info: CoreCommandRouter::volumioGetState
Apr 15 22:06:51 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Apr 15 22:06:53 volumio volumio[1095]: info: TidalConnect service started!
Apr 15 22:06:55 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings
Apr 15 22:06:55 volumio volumio[1095]: info: System name has changed, restarting Shairport Sync
Apr 15 22:06:55 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 15 22:06:55 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 15 22:06:55 volumio volumio[1095]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Apr 15 22:06:55 volumio volumio[1095]: info: Updating MyVolumio device info
Apr 15 22:06:55 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 15 22:06:55 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 15 22:06:55 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 15 22:06:55 volumio vtcs[2228]: [2025-04-15 22:06:55.985] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE
Apr 15 22:06:55 volumio vtcs[2228]: [2025-04-15 22:06:55.986] [tisoc] [error] [SpkconServer.cpp:380] recv error. client fd=8 errorno=104 error=Connection reset by peer
Apr 15 22:06:55 volumio vtcs[2228]: [2025-04-15 22:06:55.986] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected
Apr 15 22:06:56 volumio volumio[1095]: info: Discovery: Restarting Advertising due to device name change
Apr 15 22:06:56 volumio sudo[2264]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service
Apr 15 22:06:56 volumio sudo[2264]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 15 22:06:56 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAudioDevices
Apr 15 22:06:56 volumio sudo[2267]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli
Apr 15 22:06:56 volumio sudo[2267]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 15 22:06:56 volumio sudo[2269]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service
Apr 15 22:06:56 volumio sudo[2269]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 15 22:06:56 volumio systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service...
Apr 15 22:06:56 volumio systemd[1]: vtcs.service: Deactivated successfully.
Apr 15 22:06:56 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service.
Apr 15 22:06:56 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions
Apr 15 22:06:56 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 15 22:06:56 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Apr 15 22:06:56 volumio sudo[2264]: pam_unix(sudo:session): session closed for user root
Apr 15 22:06:56 volumio sudo[2267]: pam_unix(sudo:session): session closed for user root
Apr 15 22:06:56 volumio sudo[2269]: pam_unix(sudo:session): session closed for user root
Apr 15 22:06:56 volumio volumio[1095]: info: Starting Shairport Sync
Apr 15 22:06:56 volumio sudo[2279]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hosts
Apr 15 22:06:56 volumio sudo[2279]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 15 22:06:56 volumio sudo[2281]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Apr 15 22:06:56 volumio sudo[2281]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 15 22:06:56 volumio sudo[2279]: pam_unix(sudo:session): session closed for user root
Apr 15 22:06:56 volumio volumio[1095]: info: Permissions for /etc/hosts set
Apr 15 22:06:56 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver...
Apr 15 22:06:56 volumio systemd[1]: shairport-sync.service: Deactivated successfully.
Apr 15 22:06:56 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Apr 15 22:06:56 volumio systemd[1]: shairport-sync.service: Consumed 1.410s CPU time.
Apr 15 22:06:56 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Apr 15 22:06:56 volumio sudo[2281]: pam_unix(sudo:session): session closed for user root
Apr 15 22:06:56 volumio sudo[2286]: volumio : unable to resolve host volumio: System error
Apr 15 22:06:56 volumio sudo[2286]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/hostname rpi4-argon
Apr 15 22:06:56 volumio sudo[2286]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 15 22:06:56 rpi4-argon sudo[2286]: pam_unix(sudo:session): session closed for user root
Apr 15 22:06:56 rpi4-argon volumio[1095]: info: Hostname now is rpi4-argon
Apr 15 22:06:56 rpi4-argon volumio[1095]: info: Shairport-Sync Started
Apr 15 22:06:56 rpi4-argon volumio[1095]: info: Successfully Updated MyVolumio device
Apr 15 22:06:56 rpi4-argon sudo[2303]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /etc/avahi/services/
Apr 15 22:06:56 rpi4-argon sudo[2303]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 15 22:06:56 rpi4-argon sudo[2303]: pam_unix(sudo:session): session closed for user root
Apr 15 22:06:56 rpi4-argon volumio[1095]: info: New hostname set
Apr 15 22:06:56 rpi4-argon volumio[1095]: info: Permissions for /etc/avahi/services/volumio.service
Apr 15 22:06:56 rpi4-argon avahi-daemon[678]: Files changed, reloading.
Apr 15 22:06:56 rpi4-argon avahi-daemon[678]: Loading service file /services/volumio.service.
Apr 15 22:06:56 rpi4-argon volumio[1095]: info: Avahi name changed to rpi4-argon
Apr 15 22:06:57 rpi4-argon volumio[1095]: info: Discovery: A device disappeared from network
Apr 15 22:06:57 rpi4-argon volumio[1095]: info: Discovery: Device volumio disappeared from network
Apr 15 22:06:57 rpi4-argon volumio[1095]: info: Discovery: A device disappeared from network
Apr 15 22:06:57 rpi4-argon volumio[1095]: info: Discovery: A device disappeared from network
Apr 15 22:06:57 rpi4-argon avahi-daemon[678]: Service "rPi4-Argon" (/services/volumio.service) successfully established.
Apr 15 22:06:59 rpi4-argon volumio[1095]: info: TidalConnect service stoped!
Apr 15 22:06:59 rpi4-argon volumio[1095]: info: TidalConnect service stoped!
Apr 15 22:06:59 rpi4-argon volumio[1095]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect
Apr 15 22:06:59 rpi4-argon volumio[1095]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect
Apr 15 22:06:59 rpi4-argon sudo[2314]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service
Apr 15 22:06:59 rpi4-argon sudo[2314]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 15 22:06:59 rpi4-argon systemd[1]: Started vtcs.service - Volumio Tidal Connect Service.
Apr 15 22:06:59 rpi4-argon sudo[2314]: pam_unix(sudo:session): session closed for user root
Apr 15 22:06:59 rpi4-argon volumio[1095]: info: Executing endpoint tc_getconfig
Apr 15 22:06:59 rpi4-argon volumio[1095]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig
Apr 15 22:06:59 rpi4-argon vtcs[2317]: STARTING TidalConnect services, version: 1.5.0.45
Apr 15 22:06:59 rpi4-argon vtcs[2317]: STARTED TidalConnect services.
Apr 15 22:06:59 rpi4-argon volumio[1095]: info: Executing endpoint tc_connect
Apr 15 22:06:59 rpi4-argon volumio[1095]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect
Apr 15 22:06:59 rpi4-argon volumio[1095]: info: Connecting to TidalConnect
Apr 15 22:06:59 rpi4-argon volumio[1095]: info: CoreCommandRouter::servicePushState
Apr 15 22:06:59 rpi4-argon volumio[1095]: info: CoreStateMachine::pushState
Apr 15 22:06:59 rpi4-argon volumio[1095]: info: CoreCommandRouter::volumioPushState
Apr 15 22:06:59 rpi4-argon volumio[1095]: info: MRS: Pushing multiroomSync output update for this device
Apr 15 22:06:59 rpi4-argon volumio[1095]: info: MRS: Pushing multiroomSync output
Apr 15 22:06:59 rpi4-argon volumio[1095]: info: CorePlayQueue::getTrack 0
Apr 15 22:06:59 rpi4-argon volumio[1095]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false}
Apr 15 22:06:59 rpi4-argon volumio[1095]: verbose: CURRENT POSITION 0
Apr 15 22:06:59 rpi4-argon volumio[1095]: info: CoreStateMachine::syncState stateService stop
Apr 15 22:06:59 rpi4-argon volumio[1095]: info: CoreStateMachine::syncState currentStatus stop
Apr 15 22:06:59 rpi4-argon volumio[1095]: info: CoreStateMachine::pushState
Apr 15 22:06:59 rpi4-argon volumio[1095]: info: CoreCommandRouter::volumioPushState
Apr 15 22:06:59 rpi4-argon volumio[1095]: info: MRS: Pushing multiroomSync output update for this device
Apr 15 22:06:59 rpi4-argon volumio[1095]: info: MRS: Pushing multiroomSync output
Apr 15 22:06:59 rpi4-argon volumio[1095]: info: No code
Apr 15 22:06:59 rpi4-argon volumio[1095]: info: CoreStateMachine::pushState
Apr 15 22:06:59 rpi4-argon volumio[1095]: info: CoreCommandRouter::volumioPushState
Apr 15 22:06:59 rpi4-argon volumio[1095]: info: MRS: Pushing multiroomSync output update for this device
Apr 15 22:06:59 rpi4-argon volumio[1095]: info: MRS: Pushing multiroomSync output
Apr 15 22:06:59 rpi4-argon volumio[1095]: info: CoreCommandRouter::servicePushState
Apr 15 22:06:59 rpi4-argon volumio[1095]: info: CoreStateMachine::pushState
Apr 15 22:06:59 rpi4-argon volumio[1095]: info: CoreCommandRouter::volumioPushState
Apr 15 22:06:59 rpi4-argon volumio[1095]: info: MRS: Pushing multiroomSync output update for this device
Apr 15 22:06:59 rpi4-argon volumio[1095]: info: MRS: Pushing multiroomSync output
Apr 15 22:06:59 rpi4-argon volumio[1095]: info: CorePlayQueue::getTrack 0
Apr 15 22:06:59 rpi4-argon volumio[1095]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false}
Apr 15 22:06:59 rpi4-argon volumio[1095]: verbose: CURRENT POSITION 0
Apr 15 22:06:59 rpi4-argon volumio[1095]: info: CoreStateMachine::syncState stateService stop
Apr 15 22:06:59 rpi4-argon volumio[1095]: info: CoreStateMachine::syncState currentStatus stop
Apr 15 22:06:59 rpi4-argon volumio[1095]: info: CoreStateMachine::pushState
Apr 15 22:06:59 rpi4-argon volumio[1095]: info: CoreCommandRouter::volumioPushState
Apr 15 22:06:59 rpi4-argon volumio[1095]: info: MRS: Pushing multiroomSync output update for this device
Apr 15 22:06:59 rpi4-argon volumio[1095]: info: MRS: Pushing multiroomSync output
Apr 15 22:06:59 rpi4-argon volumio[1095]: info: No code
Apr 15 22:06:59 rpi4-argon volumio[1095]: info: CoreStateMachine::pushState
Apr 15 22:06:59 rpi4-argon volumio[1095]: info: CoreCommandRouter::volumioPushState
Apr 15 22:06:59 rpi4-argon volumio[1095]: info: MRS: Pushing multiroomSync output update for this device
Apr 15 22:06:59 rpi4-argon volumio[1095]: info: MRS: Pushing multiroomSync output
Apr 15 22:07:00 rpi4-argon volumio[1095]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.5CvWEqrMC5R8R7GzBFAP50Q8G0t1.c53014a2eddf6c6f60df78f54a52e40f.state.status'
Apr 15 22:07:00 rpi4-argon volumio[1095]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.5CvWEqrMC5R8R7GzBFAP50Q8G0t1.c53014a2eddf6c6f60df78f54a52e40f.state.status'
Apr 15 22:07:00 rpi4-argon volumio[1095]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.5CvWEqrMC5R8R7GzBFAP50Q8G0t1.c53014a2eddf6c6f60df78f54a52e40f.state.status'
Apr 15 22:07:00 rpi4-argon volumio[1095]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.5CvWEqrMC5R8R7GzBFAP50Q8G0t1.c53014a2eddf6c6f60df78f54a52e40f.state.status'
Apr 15 22:07:00 rpi4-argon volumio[1095]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.5CvWEqrMC5R8R7GzBFAP50Q8G0t1.c53014a2eddf6c6f60df78f54a52e40f.state.status'
Apr 15 22:07:00 rpi4-argon volumio[1095]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.5CvWEqrMC5R8R7GzBFAP50Q8G0t1.c53014a2eddf6c6f60df78f54a52e40f.state.status'
Apr 15 22:07:01 rpi4-argon volumio[1095]: info: Discovery: Started advertising with name: rPi4-Argon
Apr 15 22:07:02 rpi4-argon volumio[1095]: compat.c: read() failed: Resource temporarily unavailable
Apr 15 22:07:02 rpi4-argon volumio[1095]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 15 22:07:02 rpi4-argon volumio[1095]: Error: dns service error: unknown
Apr 15 22:07:02 rpi4-argon volumio[1095]: at MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) {
Apr 15 22:07:02 rpi4-argon volumio[1095]: errorCode: -65537
Apr 15 22:07:02 rpi4-argon volumio[1095]: }
Apr 15 22:07:02 rpi4-argon volumio[1095]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 15 22:07:03 rpi4-argon sudo[2349]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-04-15 22:06'
Apr 15 22:07:03 rpi4-argon sudo[2349]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)"
NAME="Raspbian GNU/Linux"
VERSION_ID="12"
VERSION="12 (bookworm)"
VERSION_CODENAME=bookworm
ID=raspbian
ID_LIKE=debian
HOME_URL="http://www.raspbian.org/"
SUPPORT_URL="http://www.raspbian.org/RaspbianForums"
BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs"
VOLUMIO_BUILD_VERSION="8a3b6fcf0dbd7d42207332f6d209906dd2daab56"
VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6"
VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a"
VOLUMIO_BE_VERSION="e32c2c71ae78852844c70357816bdd871c155ab8"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Sat Apr 12 14:26:54 UTC 2025"
VOLUMIO_VERSION="0.054"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="25d223077b0ad14151d6c5b08157bae8"