-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Tue 2024-04-02 19:09:10 CEST. --
Apr 02 19:08:00 volumio wireless.js[496]: Cleaning previous...
Apr 02 19:08:00 volumio sudo[713]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0
Apr 02 19:08:00 volumio sudo[713]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 02 19:08:00 volumio sudo[713]: pam_unix(sudo:session): session closed for user root
Apr 02 19:08:00 volumio sudo[715]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down
Apr 02 19:08:00 volumio sudo[715]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 02 19:08:00 volumio systemd[1]: systemd-rfkill.service: Succeeded.
Apr 02 19:08:00 volumio ntpd[682]: error resolving pool 1.debian.pool.ntp.org: System error (-11)
Apr 02 19:08:01 volumio sudo[715]: pam_unix(sudo:session): session closed for user root
Apr 02 19:08:01 volumio wireless.js[496]: Stopped aP
Apr 02 19:08:01 volumio wireless.js[496]: Wireless Networking DISABLED, not starting wireless flow
Apr 02 19:08:01 volumio systemd[1]: wireless.service: Succeeded.
Apr 02 19:08:01 volumio systemd[1]: Started Wireless Services.
Apr 02 19:08:01 volumio systemd[1]: Started Volumio Backend Module.
Apr 02 19:08:01 volumio systemd[1]: Started Volumio Cpu Tweaker.
Apr 02 19:08:01 volumio volumio-cpu-tweak[724]: Setting RT Priority for mpd
Apr 02 19:08:01 volumio volumio-cpu-tweak[724]: pid 35's current scheduling policy: SCHED_OTHER
Apr 02 19:08:01 volumio volumio-cpu-tweak[724]: pid 35's current scheduling priority: 0
Apr 02 19:08:01 volumio volumio-cpu-tweak[724]: Setting MPD Affinity
Apr 02 19:08:01 volumio volumio-cpu-tweak[724]: pid 3's current affinity mask: f
Apr 02 19:08:01 volumio volumio-cpu-tweak[724]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input
Apr 02 19:08:01 volumio volumio-cpu-tweak[724]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance
Apr 02 19:08:01 volumio systemd[1]: volumio_cpu_tweak.service: Succeeded.
Apr 02 19:08:01 volumio ntpd[682]: error resolving pool 2.debian.pool.ntp.org: System error (-11)
Apr 02 19:08:02 volumio bthelper[564]: Changing power off succeeded
Apr 02 19:08:02 volumio bthelper[564]: [CHG] Controller B8:27:EB:3B:BE:7A Class: 0x0000041c
Apr 02 19:08:02 volumio bthelper[564]: Changing power on succeeded
Apr 02 19:08:02 volumio ntpd[682]: error resolving pool 3.debian.pool.ntp.org: System error (-11)
Apr 02 19:08:03 volumio volumio-remote-updater[506]: [2024-04-02 19:08:03] [connect] Successful connection
Apr 02 19:08:03 volumio dhcpcd[582]: eth0: using static address 192.168.100.4/24
Apr 02 19:08:03 volumio avahi-daemon[494]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.100.4.
Apr 02 19:08:03 volumio dhcpcd[582]: eth0: adding route to 192.168.100.0/24
Apr 02 19:08:03 volumio avahi-daemon[494]: New relevant interface eth0.IPv4 for mDNS.
Apr 02 19:08:03 volumio dhcpcd[582]: eth0: adding default route via 192.168.100.1
Apr 02 19:08:03 volumio avahi-daemon[494]: Registering new address record for 192.168.100.4 on eth0.IPv4.
Apr 02 19:08:03 volumio volumio[723]: info: -------------------------------------------
Apr 02 19:08:03 volumio volumio[723]: info: ----- Volumio3 ----
Apr 02 19:08:03 volumio volumio[723]: info: -------------------------------------------
Apr 02 19:08:03 volumio volumio[723]: info: ----- System startup ----
Apr 02 19:08:03 volumio volumio[723]: info: -------------------------------------------
Apr 02 19:08:04 volumio nmbd[623]: [2024/04/02 19:08:04.867527, 0] ../lib/util/become_daemon.c:138(daemon_ready)
Apr 02 19:08:04 volumio nmbd[623]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections
Apr 02 19:08:04 volumio systemd[1]: Started Samba NMB Daemon.
Apr 02 19:08:04 volumio systemd[1]: Starting Samba Winbind Daemon...
Apr 02 19:08:04 volumio ntpd[682]: Listen normally on 3 eth0 192.168.100.4:123
Apr 02 19:08:04 volumio ntpd[682]: new interface(s) found: waking up resolver
Apr 02 19:08:05 volumio volumio[723]: info: MYVOLUMIO Environment detected
Apr 02 19:08:05 volumio volumio[723]: info: Plugin folders cleanup
Apr 02 19:08:05 volumio volumio[723]: info: Scanning into folder /volumio/app/plugins/
Apr 02 19:08:05 volumio volumio[723]: info: Scanning category audio_interface
Apr 02 19:08:05 volumio volumio[723]: info: Scanning category miscellanea
Apr 02 19:08:05 volumio volumio[723]: info: Scanning category music_service
Apr 02 19:08:05 volumio volumio[723]: info: Scanning category plugins.json
Apr 02 19:08:05 volumio volumio[723]: info: Scanning category system_controller
Apr 02 19:08:05 volumio volumio[723]: info: Scanning category user_interface
Apr 02 19:08:05 volumio volumio[723]: info: Scanning into folder /data/plugins/
Apr 02 19:08:05 volumio volumio[723]: info: Scanning category audio_interface
Apr 02 19:08:05 volumio volumio[723]: info: Plugin folders cleanup completed
Apr 02 19:08:05 volumio volumio[723]: info: -------------------------------------------
Apr 02 19:08:05 volumio volumio[723]: info: ----- Core plugins startup ----
Apr 02 19:08:05 volumio volumio[723]: info: -------------------------------------------
Apr 02 19:08:05 volumio volumio[723]: info: Loading plugins from folder /volumio/app/plugins/
Apr 02 19:08:05 volumio volumio[723]: info: Adding plugin upnp to MyMusic Plugins
Apr 02 19:08:05 volumio volumio[723]: info: Adding plugin airplay_emulation to MyMusic Plugins
Apr 02 19:08:05 volumio volumio[723]: info: Adding plugin upnp_browser to MyMusic Plugins
Apr 02 19:08:05 volumio volumio[723]: info: Loading plugins from folder /data/plugins/
Apr 02 19:08:05 volumio volumio[723]: info: Loading plugin "system"...
Apr 02 19:08:05 volumio volumio[723]: info: Loading plugin "appearance"...
Apr 02 19:08:05 volumio winbindd[774]: [2024/04/02 19:08:05.296432, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache)
Apr 02 19:08:05 volumio winbindd[774]: initialize_winbindd_cache: clearing cache and re-creating with version number 2
Apr 02 19:08:05 volumio winbindd[774]: [2024/04/02 19:08:05.333591, 0] ../lib/util/become_daemon.c:138(daemon_ready)
Apr 02 19:08:05 volumio winbindd[774]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections
Apr 02 19:08:05 volumio systemd[1]: Started Samba Winbind Daemon.
Apr 02 19:08:05 volumio systemd[1]: Starting Samba SMB Daemon...
Apr 02 19:08:05 volumio smbd[784]: [2024/04/02 19:08:05.932759, 0] ../lib/util/become_daemon.c:138(daemon_ready)
Apr 02 19:08:05 volumio smbd[784]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections
Apr 02 19:08:05 volumio systemd[1]: Started Samba SMB Daemon.
Apr 02 19:08:05 volumio systemd[1]: Reached target Multi-User System.
Apr 02 19:08:05 volumio systemd[1]: Reached target Graphical Interface.
Apr 02 19:08:06 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes...
Apr 02 19:08:06 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded.
Apr 02 19:08:06 volumio systemd[1]: Started Update UTMP about System Runlevel Changes.
Apr 02 19:08:06 volumio systemd[1]: Startup finished in 10.483s (kernel) + 17.088s (userspace) = 27.571s.
Apr 02 19:08:06 volumio volumio[723]: info: Loading plugin "network"...
Apr 02 19:08:06 volumio volumio[723]: info: Refreshing Cached IP Addresses
Apr 02 19:08:06 volumio volumio[723]: info: Loading plugin "services"...
Apr 02 19:08:06 volumio sudo[794]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Apr 02 19:08:06 volumio sudo[794]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 02 19:08:06 volumio volumio[723]: info: Loading plugin "alsa_controller"...
Apr 02 19:08:06 volumio sudo[796]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Apr 02 19:08:06 volumio sudo[796]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 02 19:08:06 volumio sudo[794]: pam_unix(sudo:session): session closed for user root
Apr 02 19:08:06 volumio sudo[796]: pam_unix(sudo:session): session closed for user root
Apr 02 19:08:07 volumio volumio[723]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Apr 02 19:08:07 volumio volumio[723]: info: Loading plugin "wizard"...
Apr 02 19:08:07 volumio volumio[723]: info: Loading plugin "networkfs"...
Apr 02 19:08:07 volumio volumio[723]: info: Starting Udev Watcher for removable devices
Apr 02 19:08:07 volumio sudo[804]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.100.2/Musica /mnt/NAS/Musica
Apr 02 19:08:07 volumio sudo[804]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 02 19:08:07 volumio volumio[723]: info: Ignoring mount for partition: boot
Apr 02 19:08:07 volumio volumio[723]: info: Ignoring mount for partition: volumio
Apr 02 19:08:07 volumio volumio[723]: info: Ignoring mount for partition: volumio_data
Apr 02 19:08:07 volumio volumio[723]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Apr 02 19:08:07 volumio volumio[723]: info: Loading plugin "volumio_command_line_client"...
Apr 02 19:08:07 volumio volumio[723]: info: Loading plugin "upnp"...
Apr 02 19:08:07 volumio volumio[723]: info: [1712077687271] Starting Upmpd Daemon
Apr 02 19:08:07 volumio volumio[723]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Apr 02 19:08:07 volumio volumio[723]: info: Loading plugin "my_music"...
Apr 02 19:08:07 volumio volumio[723]: info: Loading plugin "mpd"...
Apr 02 19:08:07 volumio kernel: Key type cifs.spnego registered
Apr 02 19:08:07 volumio kernel: Key type cifs.idmap registered
Apr 02 19:08:07 volumio kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount.
Apr 02 19:08:07 volumio kernel: CIFS: Attempting to mount \\192.168.100.2\Musica
Apr 02 19:08:07 volumio sudo[804]: pam_unix(sudo:session): session closed for user root
Apr 02 19:08:07 volumio volumio[723]: info: Loading plugin "upnp_browser"...
Apr 02 19:08:08 volumio volumio[723]: info: Loading plugin "alarm-clock"...
Apr 02 19:08:08 volumio volumio[723]: info: Loading plugin "airplay_emulation"...
Apr 02 19:08:08 volumio volumio[723]: info: Starting Shairport Sync
Apr 02 19:08:08 volumio volumio[723]: info: Loading plugin "last_100"...
Apr 02 19:08:08 volumio volumio[723]: info: Loading plugin "webradio"...
Apr 02 19:08:08 volumio volumio[723]: info: Loading plugin "i2s_dacs"...
Apr 02 19:08:08 volumio volumio[723]: info: I2S DAC not set, start Auto-detection
Apr 02 19:08:08 volumio volumio[723]: info: Loading plugin "volumiodiscovery"...
Apr 02 19:08:08 volumio volumio[723]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Apr 02 19:08:08 volumio volumio[723]: *** WARNING *** Please fix your application to use the native API of Avahi!
Apr 02 19:08:08 volumio volumio[723]: *** WARNING *** For more information see
Apr 02 19:08:08 volumio node[723]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Apr 02 19:08:08 volumio volumio[723]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Apr 02 19:08:08 volumio volumio[723]: *** WARNING *** Please fix your application to use the native API of Avahi!
Apr 02 19:08:08 volumio volumio[723]: *** WARNING *** For more information see
Apr 02 19:08:08 volumio node[723]: *** WARNING *** Please fix your application to use the native API of Avahi!
Apr 02 19:08:08 volumio node[723]: *** WARNING *** For more information see
Apr 02 19:08:08 volumio node[723]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Apr 02 19:08:08 volumio node[723]: *** WARNING *** Please fix your application to use the native API of Avahi!
Apr 02 19:08:08 volumio node[723]: *** WARNING *** For more information see
Apr 02 19:08:08 volumio volumio[723]: info: Applying required configuration parameters for plugin volumiodiscovery
Apr 02 19:08:08 volumio volumio[723]: info: Discovery: Started advertising with name: Volumio
Apr 02 19:08:08 volumio volumio[723]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Apr 02 19:08:08 volumio volumio[723]: info: Loading plugin "outputs"...
Apr 02 19:08:08 volumio volumio[723]: info: Loading plugin "albumart"...
Apr 02 19:08:08 volumio volumio[723]: info: Plugin example_plugin is not enabled
Apr 02 19:08:08 volumio volumio[723]: info: Loading plugin "inputs"...
Apr 02 19:08:08 volumio volumio[723]: info: Loading plugin "updater_comm"...
Apr 02 19:08:09 volumio volumio[723]: info: Plugin mpdemulation is not enabled
Apr 02 19:08:09 volumio volumio[723]: info: Loading plugin "rest_api"...
Apr 02 19:08:09 volumio volumio[723]: info: Loading plugin "websocket"...
Apr 02 19:08:09 volumio volumio[723]: info: Loading plugin "fusiondsp"...
Apr 02 19:08:09 volumio volumio[723]: Forking 3 albumart workers
Apr 02 19:08:09 volumio volumio[723]: info: Applying required configuration parameters for plugin fusiondsp
Apr 02 19:08:09 volumio volumio[723]: info: Loading i18n strings for locale es
Apr 02 19:08:09 volumio volumio[723]: Updating browse sources language
Apr 02 19:08:09 volumio volumio[723]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 02 19:08:10 volumio volumio[723]: Starting albumart workers
Apr 02 19:08:10 volumio volumio[723]: Starting albumart workers
Apr 02 19:08:10 volumio volumio[723]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 02 19:08:10 volumio volumio[723]: info: CoreCommandRouter::initPlayerControls
Apr 02 19:08:10 volumio volumio[723]: Starting albumart workers
Apr 02 19:08:10 volumio volumio[723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 19:08:10 volumio volumio[723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 19:08:10 volumio volumio[723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 19:08:10 volumio volumio[723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 19:08:10 volumio volumio[723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 19:08:10 volumio volumio[723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 19:08:10 volumio volumio[723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 19:08:10 volumio volumio[723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 19:08:10 volumio volumio[723]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 02 19:08:10 volumio volumio[723]: Express server listening on port 3000
Apr 02 19:08:10 volumio volumio[723]: [Metrics] WebUI: 7s 259.14ms
Apr 02 19:08:10 volumio volumio[723]: info: CoreStateMachine::resetVolumioState
Apr 02 19:08:10 volumio volumio[723]: info: CoreStateMachine::getcurrentVolume
Apr 02 19:08:10 volumio volumio[723]: info: CoreCommandRouter::volumioRetrievevolume
Apr 02 19:08:10 volumio volumio[723]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 02 19:08:10 volumio volumio[723]: verbose: New Socket.io Connection to 192.168.100.4 from 192.168.100.231 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:124.0) Gecko/20100101 Firefox/124.0 Total Clients: 1
Apr 02 19:08:10 volumio volumio[723]: verbose: New Socket.io Connection to 192.168.100.4 from 192.168.100.231 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:124.0) Gecko/20100101 Firefox/124.0 Total Clients: 2
Apr 02 19:08:10 volumio volumio[723]: verbose: New Socket.io Connection to 192.168.100.4 from 192.168.100.231 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:124.0) Gecko/20100101 Firefox/124.0 Total Clients: 3
Apr 02 19:08:10 volumio volumio[723]: verbose: New Socket.io Connection to 192.168.100.4 from 192.168.100.231 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:124.0) Gecko/20100101 Firefox/124.0 Total Clients: 4
Apr 02 19:08:10 volumio volumio[723]: verbose: New Socket.io Connection to 192.168.100.4 from 192.168.100.231 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:124.0) Gecko/20100101 Firefox/124.0 Total Clients: 5
Apr 02 19:08:10 volumio volumio[723]: info: Reloading queue from file
Apr 02 19:08:10 volumio volumio[723]: info: CoreStateMachine::setRepeat null single undefined
Apr 02 19:08:10 volumio volumio[723]: info: CoreStateMachine::pushState
Apr 02 19:08:10 volumio volumio[723]: info: CorePlayQueue::getTrack 0
Apr 02 19:08:10 volumio volumio[723]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 02 19:08:10 volumio volumio[723]: info: CoreCommandRouter::volumioPushState
Apr 02 19:08:10 volumio volumio[723]: info: CoreStateMachine::setRandom null
Apr 02 19:08:10 volumio volumio[723]: info: CoreStateMachine::pushState
Apr 02 19:08:10 volumio volumio[723]: info: CorePlayQueue::getTrack 0
Apr 02 19:08:10 volumio volumio[723]: info: CoreCommandRouter::volumioPushState
Apr 02 19:08:10 volumio volumio[723]: info: Setting Device type: Raspberry PI
Apr 02 19:08:10 volumio volumio[723]: info: VolumeController:: Volume=70 Mute =false
Apr 02 19:08:10 volumio volumio[723]: info: CoreStateMachine::pushState
Apr 02 19:08:10 volumio volumio[723]: info: CorePlayQueue::getTrack 0
Apr 02 19:08:10 volumio volumio[723]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 02 19:08:10 volumio volumio[723]: info: CoreCommandRouter::volumioPushState
Apr 02 19:08:10 volumio volumio[723]: info: CoreStateMachine::updateTrackBlock
Apr 02 19:08:10 volumio volumio[723]: info: CorePlayQueue::getTrackBlock
Apr 02 19:08:10 volumio volumio[723]: info: CoreCommandRouter::volumioRetrievevolume
Apr 02 19:08:10 volumio volumio[723]: info: Discovery: adding 0bffcfe1-dd2f-46f6-8ce6-4e407c9004ee
Apr 02 19:08:10 volumio volumio[723]: info: Discovery: Found device Volumio
Apr 02 19:08:10 volumio volumio[723]: info: CoreCommandRouter::volumioGetState
Apr 02 19:08:10 volumio volumio[723]: info: CorePlayQueue::getTrack 0
Apr 02 19:08:10 volumio volumio[723]: info: VolumeController:: Volume=70 Mute =false
Apr 02 19:08:10 volumio volumio[723]: info: CoreStateMachine::pushState
Apr 02 19:08:10 volumio volumio[723]: info: CorePlayQueue::getTrack 0
Apr 02 19:08:10 volumio volumio[723]: info: CoreCommandRouter::volumioPushState
Apr 02 19:08:13 volumio volumio[723]: verbose: New Socket.io Connection to 192.168.100.4 from 192.168.100.231 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:124.0) Gecko/20100101 Firefox/124.0 Total Clients: 6
Apr 02 19:08:13 volumio volumio[723]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
Apr 02 19:08:13 volumio volumio[723]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Apr 02 19:08:13 volumio volumio[723]: info: CoreCommandRouter::volumioGetVisibleSources
Apr 02 19:08:13 volumio volumio[723]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 02 19:08:13 volumio volumio[723]: info: CoreCommandRouter::volumioGetState
Apr 02 19:08:13 volumio volumio[723]: info: CorePlayQueue::getTrack 0
Apr 02 19:08:13 volumio volumio[723]: info: CoreCommandRouter::volumioGetState
Apr 02 19:08:13 volumio volumio[723]: info: CorePlayQueue::getTrack 0
Apr 02 19:08:13 volumio volumio[723]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Apr 02 19:08:13 volumio volumio[723]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Apr 02 19:08:13 volumio volumio[723]: info: Received Get System Info
Apr 02 19:08:13 volumio volumio[723]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Apr 02 19:08:13 volumio volumio[723]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Apr 02 19:08:13 volumio volumio[723]: info: Discovery: Getting this device information
Apr 02 19:08:13 volumio volumio[723]: info: CoreCommandRouter::volumioGetState
Apr 02 19:08:13 volumio volumio[723]: info: CorePlayQueue::getTrack 0
Apr 02 19:08:13 volumio volumio[723]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Apr 02 19:08:13 volumio volumio[723]: info: CoreCommandRouter::volumioGetState
Apr 02 19:08:13 volumio volumio[723]: info: CorePlayQueue::getTrack 0
Apr 02 19:08:13 volumio volumio[723]: info: Listing playlists
Apr 02 19:08:13 volumio volumio[723]: info: CoreCommandRouter::volumioGetQueue
Apr 02 19:08:13 volumio volumio[723]: info: CoreStateMachine::getQueue
Apr 02 19:08:13 volumio volumio[723]: info: CorePlayQueue::getQueue
Apr 02 19:08:14 volumio volumio[723]: info: CoreCommandRouter::getUIConfigOnPlugin
Apr 02 19:08:14 volumio volumio[723]: info: CoreCommandRouter::getUIConfigOnPlugin
Apr 02 19:08:14 volumio volumio[723]: info: CoreCommandRouter::getUIConfigOnPlugin
Apr 02 19:08:15 volumio volumio[723]: info: CoreCommandRouter::getUIConfigOnPlugin
Apr 02 19:08:15 volumio volumio[723]: info: CoreCommandRouter::getUIConfigOnPlugin
Apr 02 19:08:15 volumio volumio[723]: info: CoreCommandRouter::getUIConfigOnPlugin
Apr 02 19:08:15 volumio volumio[723]: info: CoreCommandRouter::getUIConfigOnPlugin
Apr 02 19:08:15 volumio volumio[723]: info: CoreCommandRouter::getUIConfigOnPlugin
Apr 02 19:08:15 volumio volumio[723]: info: CoreCommandRouter::getUIConfigOnPlugin
Apr 02 19:08:15 volumio volumio-remote-updater[506]: [2024-04-02 19:08:15] [connect] Successful connection
Apr 02 19:08:16 volumio volumio[723]: info: CoreCommandRouter::getUIConfigOnPlugin
Apr 02 19:08:16 volumio volumio-remote-updater[506]: [2024-04-02 19:08:16] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1712077695 101
Apr 02 19:08:16 volumio volumio[723]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 7
Apr 02 19:08:16 volumio volumio[723]: info: CoreCommandRouter::getUIConfigOnPlugin
Apr 02 19:08:16 volumio volumio[723]: info: CoreCommandRouter::getUIConfigOnPlugin
Apr 02 19:08:16 volumio volumio[723]: info: CoreCommandRouter::getUIConfigOnPlugin
Apr 02 19:08:16 volumio volumio[723]: info: CoreCommandRouter::getUIConfigOnPlugin
Apr 02 19:08:16 volumio volumio[723]: info: CoreCommandRouter::getUIConfigOnPlugin
Apr 02 19:08:17 volumio sudo[896]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Apr 02 19:08:17 volumio sudo[896]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 02 19:08:17 volumio sudo[896]: pam_unix(sudo:session): session closed for user root
Apr 02 19:08:17 volumio sudo[899]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Apr 02 19:08:17 volumio sudo[899]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 02 19:08:17 volumio sudo[899]: pam_unix(sudo:session): session closed for user root
Apr 02 19:08:17 volumio sudo[902]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service
Apr 02 19:08:17 volumio sudo[902]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 02 19:08:17 volumio systemd[1]: Started UPnP Renderer front-end to MPD.
Apr 02 19:08:17 volumio sudo[902]: pam_unix(sudo:session): session closed for user root
Apr 02 19:08:17 volumio volumio[723]: info: Upmpdcli Daemon Started
Apr 02 19:08:17 volumio volumio[723]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600
Apr 02 19:08:21 volumio volumio[723]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600
Apr 02 19:08:23 volumio systemd[1]: systemd-fsckd.service: Succeeded.
Apr 02 19:08:26 volumio sudo[906]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Apr 02 19:08:26 volumio sudo[906]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 02 19:08:26 volumio sudo[906]: pam_unix(sudo:session): session closed for user root
Apr 02 19:08:26 volumio sudo[908]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Apr 02 19:08:26 volumio sudo[908]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 02 19:08:26 volumio sudo[908]: pam_unix(sudo:session): session closed for user root
Apr 02 19:08:27 volumio volumio[723]: verbose: New Socket.io Connection to 192.168.100.4 from 192.168.100.231 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:124.0) Gecko/20100101 Firefox/124.0 Total Clients: 7
Apr 02 19:08:27 volumio volumio[723]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
Apr 02 19:08:27 volumio volumio[723]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Apr 02 19:08:27 volumio volumio[723]: info: CoreCommandRouter::volumioGetVisibleSources
Apr 02 19:08:27 volumio volumio[723]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 02 19:08:27 volumio volumio[723]: info: CoreCommandRouter::volumioGetState
Apr 02 19:08:27 volumio volumio[723]: info: CorePlayQueue::getTrack 0
Apr 02 19:08:27 volumio volumio[723]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Apr 02 19:08:27 volumio volumio[723]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Apr 02 19:08:27 volumio volumio[723]: info: Received Get System Info
Apr 02 19:08:27 volumio volumio[723]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Apr 02 19:08:27 volumio volumio[723]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Apr 02 19:08:27 volumio volumio[723]: info: Discovery: Getting this device information
Apr 02 19:08:27 volumio volumio[723]: info: CoreCommandRouter::volumioGetState
Apr 02 19:08:27 volumio volumio[723]: info: CorePlayQueue::getTrack 0
Apr 02 19:08:27 volumio volumio[723]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Apr 02 19:08:27 volumio volumio[723]: info: CoreCommandRouter::volumioGetState
Apr 02 19:08:27 volumio volumio[723]: info: CorePlayQueue::getTrack 0
Apr 02 19:08:27 volumio volumio[723]: info: Listing playlists
Apr 02 19:08:27 volumio volumio[723]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
Apr 02 19:08:27 volumio volumio[723]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Apr 02 19:08:27 volumio volumio[723]: info: CoreCommandRouter::getUIConfigOnPlugin
Apr 02 19:08:27 volumio volumio[723]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600
Apr 02 19:08:28 volumio volumio[723]: info: CoreCommandRouter::volumioGetState
Apr 02 19:08:28 volumio volumio[723]: info: CorePlayQueue::getTrack 0
Apr 02 19:08:28 volumio volumio[723]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Apr 02 19:08:28 volumio volumio[723]: info: Received Get System Info
Apr 02 19:08:28 volumio volumio[723]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Apr 02 19:08:28 volumio volumio[723]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Apr 02 19:08:28 volumio volumio[723]: info: Discovery: Getting this device information
Apr 02 19:08:28 volumio volumio[723]: info: CoreCommandRouter::volumioGetState
Apr 02 19:08:28 volumio volumio[723]: info: CorePlayQueue::getTrack 0
Apr 02 19:08:28 volumio volumio[723]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Apr 02 19:08:29 volumio volumio[723]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken
Apr 02 19:08:29 volumio systemd[1]: systemd-hostnamed.service: Succeeded.
Apr 02 19:08:29 volumio volumio[723]: info: CoreCommandRouter::volumioGetQueue
Apr 02 19:08:29 volumio volumio[723]: info: CoreStateMachine::getQueue
Apr 02 19:08:29 volumio volumio[723]: info: CorePlayQueue::getQueue
Apr 02 19:08:29 volumio volumio[723]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Apr 02 19:08:29 volumio volumio[723]: info: Received Get System Info
Apr 02 19:08:29 volumio volumio[723]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Apr 02 19:08:29 volumio volumio[723]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Apr 02 19:08:29 volumio volumio[723]: info: Discovery: Getting this device information
Apr 02 19:08:29 volumio volumio[723]: info: CoreCommandRouter::volumioGetState
Apr 02 19:08:29 volumio volumio[723]: info: CorePlayQueue::getTrack 0
Apr 02 19:08:29 volumio volumio[723]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Apr 02 19:08:30 volumio volumio[723]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken
Apr 02 19:08:30 volumio volumio[723]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Apr 02 19:08:30 volumio volumio[723]: info: CURURI: albums://
Apr 02 19:08:30 volumio volumio[723]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 02 19:08:30 volumio volumio[723]: TypeError: Cannot read property 'sendCommand' of undefined
Apr 02 19:08:30 volumio volumio[723]: at /volumio/app/plugins/music_service/mpd/index.js:2986:22
Apr 02 19:08:30 volumio volumio[723]: at processTicksAndRejections (internal/process/task_queues.js:75:11)
Apr 02 19:08:30 volumio volumio[723]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 02 19:08:31 volumio sudo[922]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-02 19:07
Apr 02 19:08:31 volumio sudo[922]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 02 19:08:31 volumio sudo[922]: pam_unix(sudo:session): session closed for user root
Apr 02 19:08:31 volumio volumio-remote-updater[506]: [2024-04-02 19:08:31] [error] handle_read_frame error: websocketpp.transport:7 (End of File)
Apr 02 19:08:31 volumio volumio-remote-updater[506]: [2024-04-02 19:08:31] [disconnect] Disconnect close local:[1006,End of File] remote:[1006]
Apr 02 19:08:31 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE
Apr 02 19:08:31 volumio systemd[1]: volumio.service: Failed with result 'exit-code'.
Apr 02 19:08:31 volumio systemd[1]: Started dynamicswap service.
Apr 02 19:08:31 volumio systemd[1]: dynamicswap.service: Succeeded.
Apr 02 19:08:32 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart.
Apr 02 19:08:32 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1.
Apr 02 19:08:32 volumio systemd[1]: Started dynamicswap service.
Apr 02 19:08:32 volumio systemd[1]: Stopped Volumio Backend Module.
Apr 02 19:08:32 volumio systemd[1]: Started Volumio Backend Module.
Apr 02 19:08:32 volumio systemd[1]: dynamicswap.service: Succeeded.
Apr 02 19:08:33 volumio volumio[936]: info: -------------------------------------------
Apr 02 19:08:33 volumio volumio[936]: info: ----- Volumio3 ----
Apr 02 19:08:33 volumio volumio[936]: info: -------------------------------------------
Apr 02 19:08:33 volumio volumio[936]: info: ----- System startup ----
Apr 02 19:08:33 volumio volumio[936]: info: -------------------------------------------
Apr 02 19:08:34 volumio volumio[936]: info: MYVOLUMIO Environment detected
Apr 02 19:08:34 volumio volumio[936]: info: Plugin folders cleanup
Apr 02 19:08:34 volumio volumio[936]: info: Scanning into folder /volumio/app/plugins/
Apr 02 19:08:34 volumio volumio[936]: info: Scanning category audio_interface
Apr 02 19:08:34 volumio volumio[936]: info: Scanning category miscellanea
Apr 02 19:08:34 volumio volumio[936]: info: Scanning category music_service
Apr 02 19:08:34 volumio volumio[936]: info: Scanning category plugins.json
Apr 02 19:08:34 volumio volumio[936]: info: Scanning category system_controller
Apr 02 19:08:34 volumio volumio[936]: info: Scanning category user_interface
Apr 02 19:08:34 volumio volumio[936]: info: Scanning into folder /data/plugins/
Apr 02 19:08:34 volumio volumio[936]: info: Scanning category audio_interface
Apr 02 19:08:34 volumio volumio[936]: info: Plugin folders cleanup completed
Apr 02 19:08:34 volumio volumio[936]: info: -------------------------------------------
Apr 02 19:08:34 volumio volumio[936]: info: ----- Core plugins startup ----
Apr 02 19:08:34 volumio volumio[936]: info: -------------------------------------------
Apr 02 19:08:34 volumio volumio[936]: info: Loading plugins from folder /volumio/app/plugins/
Apr 02 19:08:34 volumio volumio[936]: info: Adding plugin upnp to MyMusic Plugins
Apr 02 19:08:34 volumio volumio[936]: info: Adding plugin airplay_emulation to MyMusic Plugins
Apr 02 19:08:34 volumio volumio[936]: info: Adding plugin upnp_browser to MyMusic Plugins
Apr 02 19:08:34 volumio volumio[936]: info: Loading plugins from folder /data/plugins/
Apr 02 19:08:34 volumio volumio[936]: info: Loading plugin "system"...
Apr 02 19:08:34 volumio volumio[936]: info: Loading plugin "appearance"...
Apr 02 19:08:35 volumio volumio[936]: info: Loading plugin "network"...
Apr 02 19:08:35 volumio volumio[936]: info: Refreshing Cached IP Addresses
Apr 02 19:08:35 volumio volumio[936]: info: Loading plugin "services"...
Apr 02 19:08:35 volumio sudo[962]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Apr 02 19:08:35 volumio volumio[936]: info: Loading plugin "alsa_controller"...
Apr 02 19:08:35 volumio sudo[962]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 02 19:08:35 volumio sudo[962]: pam_unix(sudo:session): session closed for user root
Apr 02 19:08:35 volumio sudo[964]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Apr 02 19:08:35 volumio sudo[964]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 02 19:08:35 volumio sudo[964]: pam_unix(sudo:session): session closed for user root
Apr 02 19:08:35 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Apr 02 19:08:35 volumio volumio[936]: info: Loading plugin "wizard"...
Apr 02 19:08:35 volumio volumio[936]: info: Loading plugin "networkfs"...
Apr 02 19:08:35 volumio volumio[936]: info: Cannot mount NAS Musica at system boot, trial number 1 ,retrying in 5 seconds
Apr 02 19:08:35 volumio volumio[936]: info: Starting Udev Watcher for removable devices
Apr 02 19:08:35 volumio volumio[936]: info: Ignoring mount for partition: boot
Apr 02 19:08:35 volumio volumio[936]: info: Ignoring mount for partition: volumio
Apr 02 19:08:35 volumio volumio[936]: info: Ignoring mount for partition: volumio_data
Apr 02 19:08:35 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Apr 02 19:08:35 volumio volumio[936]: info: Loading plugin "volumio_command_line_client"...
Apr 02 19:08:35 volumio volumio[936]: info: Loading plugin "upnp"...
Apr 02 19:08:35 volumio volumio[936]: info: [1712077715775] Starting Upmpd Daemon
Apr 02 19:08:35 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Apr 02 19:08:35 volumio volumio[936]: info: Loading plugin "my_music"...
Apr 02 19:08:35 volumio volumio[936]: info: Loading plugin "mpd"...
Apr 02 19:08:36 volumio volumio[936]: info: Loading plugin "upnp_browser"...
Apr 02 19:08:36 volumio volumio[936]: info: Loading plugin "alarm-clock"...
Apr 02 19:08:36 volumio volumio[936]: info: Loading plugin "airplay_emulation"...
Apr 02 19:08:36 volumio volumio[936]: info: Starting Shairport Sync
Apr 02 19:08:36 volumio volumio[936]: info: Loading plugin "last_100"...
Apr 02 19:08:36 volumio volumio[936]: info: Loading plugin "webradio"...
Apr 02 19:08:36 volumio volumio[936]: info: Loading plugin "i2s_dacs"...
Apr 02 19:08:36 volumio volumio[936]: info: I2S DAC not set, start Auto-detection
Apr 02 19:08:36 volumio volumio[936]: info: Loading plugin "volumiodiscovery"...
Apr 02 19:08:36 volumio volumio[936]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Apr 02 19:08:36 volumio volumio[936]: *** WARNING *** Please fix your application to use the native API of Avahi!
Apr 02 19:08:36 volumio volumio[936]: *** WARNING *** For more information see
Apr 02 19:08:36 volumio node[936]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Apr 02 19:08:36 volumio volumio[936]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Apr 02 19:08:36 volumio volumio[936]: *** WARNING *** Please fix your application to use the native API of Avahi!
Apr 02 19:08:36 volumio volumio[936]: *** WARNING *** For more information see
Apr 02 19:08:36 volumio node[936]: *** WARNING *** Please fix your application to use the native API of Avahi!
Apr 02 19:08:36 volumio node[936]: *** WARNING *** For more information see
Apr 02 19:08:36 volumio node[936]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Apr 02 19:08:36 volumio node[936]: *** WARNING *** Please fix your application to use the native API of Avahi!
Apr 02 19:08:36 volumio node[936]: *** WARNING *** For more information see
Apr 02 19:08:36 volumio volumio[936]: info: Applying required configuration parameters for plugin volumiodiscovery
Apr 02 19:08:36 volumio volumio[936]: info: Discovery: Started advertising with name: Volumio
Apr 02 19:08:36 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Apr 02 19:08:36 volumio volumio[936]: info: Loading plugin "outputs"...
Apr 02 19:08:36 volumio volumio[936]: info: Loading plugin "albumart"...
Apr 02 19:08:36 volumio volumio[936]: info: Plugin example_plugin is not enabled
Apr 02 19:08:36 volumio volumio[936]: info: Loading plugin "inputs"...
Apr 02 19:08:36 volumio volumio[936]: info: Loading plugin "updater_comm"...
Apr 02 19:08:36 volumio volumio-remote-updater[506]: [2024-04-02 19:08:36] [connect] Successful connection
Apr 02 19:08:36 volumio volumio[936]: info: Plugin mpdemulation is not enabled
Apr 02 19:08:36 volumio volumio[936]: info: Loading plugin "rest_api"...
Apr 02 19:08:36 volumio volumio[936]: info: Loading plugin "websocket"...
Apr 02 19:08:36 volumio volumio[936]: info: Loading plugin "fusiondsp"...
Apr 02 19:08:37 volumio volumio[936]: Forking 3 albumart workers
Apr 02 19:08:37 volumio volumio[936]: info: Applying required configuration parameters for plugin fusiondsp
Apr 02 19:08:37 volumio volumio[936]: info: Loading i18n strings for locale es
Apr 02 19:08:37 volumio volumio[936]: Updating browse sources language
Apr 02 19:08:37 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 02 19:08:37 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 02 19:08:37 volumio volumio[936]: info: CoreCommandRouter::initPlayerControls
Apr 02 19:08:37 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 19:08:37 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 19:08:37 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 19:08:37 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 19:08:37 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 19:08:37 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 19:08:37 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 19:08:37 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 02 19:08:37 volumio volumio[936]: Express server listening on port 3000
Apr 02 19:08:37 volumio volumio[936]: [Metrics] WebUI: 4s 336.15ms
Apr 02 19:08:37 volumio volumio[936]: info: CoreStateMachine::resetVolumioState
Apr 02 19:08:37 volumio volumio[936]: info: CoreStateMachine::getcurrentVolume
Apr 02 19:08:37 volumio volumio[936]: info: CoreCommandRouter::volumioRetrievevolume
Apr 02 19:08:37 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 02 19:08:37 volumio volumio[936]: verbose: New Socket.io Connection to 192.168.100.4 from 192.168.100.231 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:124.0) Gecko/20100101 Firefox/124.0 Total Clients: 1
Apr 02 19:08:37 volumio volumio[936]: verbose: New Socket.io Connection to 192.168.100.4 from 192.168.100.231 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:124.0) Gecko/20100101 Firefox/124.0 Total Clients: 2
Apr 02 19:08:38 volumio volumio-remote-updater[506]: [2024-04-02 19:08:38] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1712077716 101
Apr 02 19:08:38 volumio volumio[936]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 3
Apr 02 19:08:38 volumio volumio[936]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600
Apr 02 19:08:38 volumio volumio[936]: Starting albumart workers
Apr 02 19:08:38 volumio volumio[936]: info: Reloading queue from file
Apr 02 19:08:38 volumio volumio[936]: info: CoreStateMachine::setRepeat null single undefined
Apr 02 19:08:38 volumio volumio[936]: info: CoreStateMachine::pushState
Apr 02 19:08:38 volumio volumio[936]: info: CorePlayQueue::getTrack 0
Apr 02 19:08:38 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 02 19:08:38 volumio volumio[936]: info: CoreCommandRouter::volumioPushState
Apr 02 19:08:38 volumio volumio[936]: info: CoreStateMachine::setRandom null
Apr 02 19:08:38 volumio volumio[936]: info: CoreStateMachine::pushState
Apr 02 19:08:38 volumio volumio[936]: info: CorePlayQueue::getTrack 0
Apr 02 19:08:38 volumio volumio[936]: info: CoreCommandRouter::volumioPushState
Apr 02 19:08:38 volumio volumio[936]: Starting albumart workers
Apr 02 19:08:38 volumio volumio[936]: info: VolumeController:: Volume=70 Mute =false
Apr 02 19:08:38 volumio volumio[936]: info: CoreStateMachine::pushState
Apr 02 19:08:38 volumio volumio[936]: info: CorePlayQueue::getTrack 0
Apr 02 19:08:38 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 02 19:08:38 volumio volumio[936]: Starting albumart workers
Apr 02 19:08:38 volumio volumio[936]: info: CoreCommandRouter::volumioPushState
Apr 02 19:08:38 volumio volumio[936]: info: CoreStateMachine::updateTrackBlock
Apr 02 19:08:38 volumio volumio[936]: info: CorePlayQueue::getTrackBlock
Apr 02 19:08:38 volumio volumio[936]: info: CoreCommandRouter::volumioRetrievevolume
Apr 02 19:08:38 volumio volumio[936]: info: Setting Device type: Raspberry PI
Apr 02 19:08:38 volumio volumio[936]: info: Discovery: adding 0bffcfe1-dd2f-46f6-8ce6-4e407c9004ee
Apr 02 19:08:38 volumio volumio[936]: info: Discovery: Found device Volumio
Apr 02 19:08:38 volumio volumio[936]: info: CoreCommandRouter::volumioGetState
Apr 02 19:08:38 volumio volumio[936]: info: CorePlayQueue::getTrack 0
Apr 02 19:08:38 volumio volumio[936]: info: VolumeController:: Volume=70 Mute =false
Apr 02 19:08:38 volumio volumio[936]: info: CoreStateMachine::pushState
Apr 02 19:08:38 volumio volumio[936]: info: CorePlayQueue::getTrack 0
Apr 02 19:08:38 volumio volumio[936]: info: CoreCommandRouter::volumioPushState
Apr 02 19:08:40 volumio volumio[936]: info: Cannot mount NAS Musica at system boot, trial number 2 ,retrying in 5 seconds
Apr 02 19:08:41 volumio volumio[936]: verbose: New Socket.io Connection to 192.168.100.4 from 192.168.100.231 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:124.0) Gecko/20100101 Firefox/124.0 Total Clients: 4
Apr 02 19:08:41 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
Apr 02 19:08:41 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Apr 02 19:08:41 volumio volumio[936]: info: CoreCommandRouter::volumioGetVisibleSources
Apr 02 19:08:41 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 02 19:08:41 volumio volumio[936]: info: CoreCommandRouter::volumioGetState
Apr 02 19:08:41 volumio volumio[936]: info: CorePlayQueue::getTrack 0
Apr 02 19:08:41 volumio volumio[936]: info: CoreCommandRouter::volumioGetState
Apr 02 19:08:41 volumio volumio[936]: info: CorePlayQueue::getTrack 0
Apr 02 19:08:41 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Apr 02 19:08:41 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Apr 02 19:08:41 volumio volumio[936]: info: Received Get System Info
Apr 02 19:08:41 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Apr 02 19:08:41 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Apr 02 19:08:41 volumio volumio[936]: info: Discovery: Getting this device information
Apr 02 19:08:41 volumio volumio[936]: info: CoreCommandRouter::volumioGetState
Apr 02 19:08:41 volumio volumio[936]: info: CorePlayQueue::getTrack 0
Apr 02 19:08:41 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Apr 02 19:08:41 volumio volumio[936]: info: CoreCommandRouter::volumioGetState
Apr 02 19:08:41 volumio volumio[936]: info: CorePlayQueue::getTrack 0
Apr 02 19:08:41 volumio volumio[936]: info: Listing playlists
Apr 02 19:08:41 volumio volumio[936]: info: CoreCommandRouter::volumioGetQueue
Apr 02 19:08:41 volumio volumio[936]: info: CoreStateMachine::getQueue
Apr 02 19:08:41 volumio volumio[936]: info: CorePlayQueue::getQueue
Apr 02 19:08:42 volumio volumio[936]: info: CoreCommandRouter::getUIConfigOnPlugin
Apr 02 19:08:42 volumio volumio[936]: info: CoreCommandRouter::getUIConfigOnPlugin
Apr 02 19:08:45 volumio volumio[936]: info: Cannot mount NAS Musica at system boot, trial number 3 ,retrying in 5 seconds
Apr 02 19:08:45 volumio sudo[1038]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Apr 02 19:08:45 volumio sudo[1038]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 02 19:08:45 volumio sudo[1038]: pam_unix(sudo:session): session closed for user root
Apr 02 19:08:45 volumio sudo[1040]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Apr 02 19:08:45 volumio sudo[1040]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 02 19:08:45 volumio sudo[1040]: pam_unix(sudo:session): session closed for user root
Apr 02 19:08:45 volumio sudo[1044]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service
Apr 02 19:08:45 volumio sudo[1044]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 02 19:08:45 volumio sudo[1044]: pam_unix(sudo:session): session closed for user root
Apr 02 19:08:45 volumio volumio[936]: info: Upmpdcli Daemon Started
Apr 02 19:08:47 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Apr 02 19:08:48 volumio volumio[936]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined
Apr 02 19:08:48 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates
Apr 02 19:08:48 volumio volumio[936]: info: Received Get System Version
Apr 02 19:08:48 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Apr 02 19:08:48 volumio volumio[936]: info: Received Get System Info
Apr 02 19:08:48 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Apr 02 19:08:48 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Apr 02 19:08:48 volumio volumio[936]: info: Discovery: Getting this device information
Apr 02 19:08:48 volumio volumio[936]: info: CoreCommandRouter::volumioGetState
Apr 02 19:08:48 volumio volumio[936]: info: CorePlayQueue::getTrack 0
Apr 02 19:08:48 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Apr 02 19:08:50 volumio volumio[936]: info: Cannot mount NAS Musica at system boot, trial number 4 ,retrying in 5 seconds
Apr 02 19:08:55 volumio volumio[936]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600
Apr 02 19:08:55 volumio volumio[936]: info: Cannot mount NAS at system boot, trial number 4 ,stopping
Apr 02 19:09:05 volumio ntpd[682]: Soliciting pool server 178.215.228.24
Apr 02 19:09:05 volumio ntpd[682]: Soliciting pool server 185.198.109.227
Apr 02 19:09:06 volumio ntpd[682]: Soliciting pool server 185.90.148.209
Apr 02 19:09:06 volumio ntpd[682]: Soliciting pool server 162.159.200.1
Apr 02 19:09:06 volumio ntpd[682]: Soliciting pool server 158.227.98.15
Apr 02 19:09:06 volumio ntpd[682]: Soliciting pool server 82.223.203.159
Apr 02 19:09:06 volumio ntpd[682]: Soliciting pool server 65.20.99.74
Apr 02 19:09:07 volumio volumio[936]: error: Plugin system_controller system failed to complete 'onVolumioStart' in a timely fashion
Apr 02 19:09:07 volumio volumio[936]: info: Preparing to generate the ALSA configuration file
Apr 02 19:09:07 volumio volumio[936]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf
Apr 02 19:09:07 volumio volumio[936]: info: Reading ALSA contributions from plugins.
Apr 02 19:09:07 volumio volumio[936]: info: Asound.conf file unchanged, so no further update is needed
Apr 02 19:09:07 volumio volumio[936]: info: Output device has changed, restarting MPD
Apr 02 19:09:07 volumio volumio[936]: info: Output device has changed, restarting Shairport Sync
Apr 02 19:09:07 volumio sudo[1082]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Apr 02 19:09:07 volumio sudo[1082]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 02 19:09:07 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 19:09:07 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 02 19:09:07 volumio sudo[1082]: pam_unix(sudo:session): session closed for user root
Apr 02 19:09:07 volumio sudo[1084]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Apr 02 19:09:07 volumio sudo[1084]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 02 19:09:07 volumio volumio[936]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Apr 02 19:09:07 volumio volumio[936]: info: ___________ START PLUGINS ___________
Apr 02 19:09:07 volumio volumio[936]: info: ControllerMpd::onStart: Initializing MPD
Apr 02 19:09:07 volumio volumio[936]: info: Creating MPD Configuration file
Apr 02 19:09:07 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 02 19:09:07 volumio sudo[1092]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Apr 02 19:09:07 volumio sudo[1092]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 02 19:09:07 volumio volumio[936]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Apr 02 19:09:07 volumio volumio[936]: info: [1712077747629] CoreMusicLibrary::Adding element Media Servers
Apr 02 19:09:07 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 02 19:09:07 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 19:09:07 volumio sudo[1092]: pam_unix(sudo:session): session closed for user root
Apr 02 19:09:07 volumio sudo[1094]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Apr 02 19:09:07 volumio sudo[1094]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 02 19:09:07 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 02 19:09:07 volumio systemd[1]: Listening on mpd.socket.
Apr 02 19:09:07 volumio systemd[1]: Starting Music Player Daemon...
Apr 02 19:09:07 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM
Apr 02 19:09:07 volumio systemd[1]: mpd.service: Succeeded.
Apr 02 19:09:07 volumio systemd[1]: Stopped Music Player Daemon.
Apr 02 19:09:07 volumio systemd[1]: Starting Music Player Daemon...
Apr 02 19:09:07 volumio volumio[936]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Apr 02 19:09:07 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 19:09:07 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 02 19:09:07 volumio volumio[936]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Apr 02 19:09:07 volumio volumio[936]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Apr 02 19:09:07 volumio volumio[936]: info: [1712077747761] CoreMusicLibrary::Adding element Last_100
Apr 02 19:09:07 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 02 19:09:07 volumio volumio[936]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Apr 02 19:09:07 volumio volumio[936]: info: [1712077747766] CoreMusicLibrary::Adding element Webradio
Apr 02 19:09:07 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 02 19:09:07 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Apr 02 19:09:07 volumio volumio[936]: info: Loading i18n strings for locale es
Apr 02 19:09:07 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile
Apr 02 19:09:07 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 19:09:07 volumio volumio[936]: info: FusionDsp - mixtype--------------------- Hardware
Apr 02 19:09:07 volumio volumio[936]: info: Volumio Calling Home
Apr 02 19:09:07 volumio volumio[936]: info: Preparing to generate the ALSA configuration file
Apr 02 19:09:07 volumio ntpd[682]: Soliciting pool server 89.140.186.3
Apr 02 19:09:07 volumio ntpd[682]: Soliciting pool server 90.68.206.60
Apr 02 19:09:08 volumio volumio[936]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf
Apr 02 19:09:08 volumio volumio[936]: info: Reading ALSA contributions from plugins.
Apr 02 19:09:08 volumio volumio[936]: info: MPD Permissions set
Apr 02 19:09:08 volumio volumio[936]: info: MPD Permissions set
Apr 02 19:09:08 volumio volumio[936]: info: Volumio called home
Apr 02 19:09:08 volumio volumio[936]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 5
Apr 02 19:09:08 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 19:09:08 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 19:09:08 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 19:09:08 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 19:09:08 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 19:09:08 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 19:09:08 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 19:09:08 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 19:09:08 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 02 19:09:08 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 19:09:08 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 19:09:08 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 19:09:08 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 19:09:08 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 19:09:08 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 19:09:08 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 19:09:08 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 19:09:08 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 19:09:08 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 02 19:09:08 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 19:09:08 volumio volumio[936]: info: Starting Shairport Sync
Apr 02 19:09:08 volumio volumio[936]: info: Starting Shairport Sync
Apr 02 19:09:08 volumio volumio[936]: info: Starting Shairport Sync
Apr 02 19:09:08 volumio sudo[1119]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Apr 02 19:09:08 volumio sudo[1119]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 02 19:09:08 volumio sudo[1117]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Apr 02 19:09:08 volumio sudo[1117]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 02 19:09:08 volumio sudo[1121]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Apr 02 19:09:08 volumio sudo[1121]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 02 19:09:08 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Apr 02 19:09:08 volumio systemd[1]: shairport-sync.service: Succeeded.
Apr 02 19:09:08 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Apr 02 19:09:08 volumio volumio[936]: info: Asound.conf file unchanged, so no further update is needed
Apr 02 19:09:08 volumio volumio[936]: info: Output device has changed, restarting MPD
Apr 02 19:09:08 volumio volumio[936]: info: Output device has changed, restarting Shairport Sync
Apr 02 19:09:08 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 19:09:08 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 02 19:09:08 volumio sudo[1127]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Apr 02 19:09:08 volumio sudo[1127]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 02 19:09:08 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Apr 02 19:09:08 volumio sudo[1127]: pam_unix(sudo:session): session closed for user root
Apr 02 19:09:08 volumio sudo[1129]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Apr 02 19:09:08 volumio sudo[1129]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 02 19:09:08 volumio volumio[936]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Apr 02 19:09:08 volumio sudo[1117]: pam_unix(sudo:session): session closed for user root
Apr 02 19:09:08 volumio sudo[1119]: pam_unix(sudo:session): session closed for user root
Apr 02 19:09:08 volumio shairport-sync[1125]: free(): double free detected in tcache 2
Apr 02 19:09:08 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Apr 02 19:09:08 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=6/ABRT
Apr 02 19:09:08 volumio systemd[1]: shairport-sync.service: Failed with result 'signal'.
Apr 02 19:09:08 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Apr 02 19:09:08 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM
Apr 02 19:09:08 volumio systemd[1]: mpd.service: Succeeded.
Apr 02 19:09:08 volumio systemd[1]: Stopped Music Player Daemon.
Apr 02 19:09:08 volumio systemd[1]: Starting Music Player Daemon...
Apr 02 19:09:08 volumio volumio[936]: info: MPD Permissions set
Apr 02 19:09:08 volumio volumio[936]: info: Shairport-Sync Started
Apr 02 19:09:08 volumio volumio[936]: Error adding Membership: Error: addMembership EINVAL
Apr 02 19:09:08 volumio volumio[936]: info: Shairport-Sync Started
Apr 02 19:09:08 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Apr 02 19:09:08 volumio volumio[936]: info: CoreCommandRouter::volumioGetState
Apr 02 19:09:08 volumio volumio[936]: info: CorePlayQueue::getTrack 0
Apr 02 19:09:08 volumio sudo[1121]: pam_unix(sudo:session): session closed for user root
Apr 02 19:09:08 volumio volumio[936]: info: Shairport-Sync Started
Apr 02 19:09:08 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 19:09:08 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 19:09:08 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 19:09:08 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 19:09:08 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 19:09:08 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 19:09:08 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 19:09:08 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 19:09:08 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 02 19:09:08 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 19:09:08 volumio volumio[936]: info: Starting Shairport Sync
Apr 02 19:09:08 volumio sudo[1144]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Apr 02 19:09:08 volumio sudo[1144]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 02 19:09:08 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Apr 02 19:09:08 volumio systemd[1]: shairport-sync.service: Succeeded.
Apr 02 19:09:08 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Apr 02 19:09:08 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Apr 02 19:09:08 volumio sudo[1144]: pam_unix(sudo:session): session closed for user root
Apr 02 19:09:08 volumio volumio[936]: info: Shairport-Sync Started
Apr 02 19:09:09 volumio volumio[936]: info: camilladsp spawned new process with pid undefined, instance 1, run: true
Apr 02 19:09:09 volumio volumio[936]: info: camilladsp service started and running in background, instance 1
Apr 02 19:09:09 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 19:09:09 volumio volumio[936]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found
Apr 02 19:09:09 volumio volumio[936]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json
Apr 02 19:09:09 volumio volumio[936]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found
Apr 02 19:09:09 volumio volumio[936]: info: FusionDsp loaded
Apr 02 19:09:09 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Apr 02 19:09:09 volumio mpd[1135]: Apr 02 19:09 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Apr 02 19:09:09 volumio sudo[1159]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service
Apr 02 19:09:09 volumio sudo[1159]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 02 19:09:09 volumio volumio[936]: info: FusionDsp - Reporting Fusion DSP Enabled
Apr 02 19:09:09 volumio volumio[936]: info: Adding Signal Path Element [object Object]
Apr 02 19:09:09 volumio volumio[936]: info: Adding fusiondspeq DSP Signal Path Element
Apr 02 19:09:09 volumio sudo[1159]: pam_unix(sudo:session): session closed for user root
Apr 02 19:09:09 volumio volumio[936]: info: FusionDsp - ---- installed callbackRead
Apr 02 19:09:09 volumio volumio[936]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 02 19:09:09 volumio volumio[936]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT
Apr 02 19:09:09 volumio volumio[936]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19)
Apr 02 19:09:09 volumio volumio[936]: at onErrorNT (internal/child_process.js:465:16)
Apr 02 19:09:09 volumio volumio[936]: at processTicksAndRejections (internal/process/task_queues.js:80:21) {
Apr 02 19:09:09 volumio volumio[936]: errno: -2,
Apr 02 19:09:09 volumio volumio[936]: code: 'ENOENT',
Apr 02 19:09:09 volumio volumio[936]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp',
Apr 02 19:09:09 volumio volumio[936]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp',
Apr 02 19:09:09 volumio volumio[936]: spawnargs: [
Apr 02 19:09:09 volumio volumio[936]: '-p',
Apr 02 19:09:09 volumio volumio[936]: 9876,
Apr 02 19:09:09 volumio volumio[936]: '-o',
Apr 02 19:09:09 volumio volumio[936]: '/tmp/camilladsp.log',
Apr 02 19:09:09 volumio volumio[936]: '-l',
Apr 02 19:09:09 volumio volumio[936]: 'warn',
Apr 02 19:09:09 volumio volumio[936]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml'
Apr 02 19:09:09 volumio volumio[936]: ]
Apr 02 19:09:09 volumio volumio[936]: }
Apr 02 19:09:09 volumio volumio[936]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 02 19:09:10 volumio systemd[1]: Started Music Player Daemon.
Apr 02 19:09:10 volumio sudo[1129]: pam_unix(sudo:session): session closed for user root
Apr 02 19:09:10 volumio sudo[1094]: pam_unix(sudo:session): session closed for user root
Apr 02 19:09:10 volumio sudo[1084]: pam_unix(sudo:session): session closed for user root
Apr 02 19:09:10 volumio ntpd[682]: Soliciting pool server 217.71.200.210
Apr 02 19:09:10 volumio sudo[1172]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-02 19:08
Apr 02 19:09:10 volumio sudo[1172]: 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="7f9f1bf001add7a8b19a4516e3c74ef598b2730d"
VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71"
VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1"
VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET"
VOLUMIO_VERSION="3.631"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"