-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Wed 2024-05-01 17:19:11 CEST. --
May 01 17:18:21 volumio-mf ntpd[747]: receive: Unexpected origin timestamp 0xe9bd3145.b992888c does not match aorg 0000000000.00000000 from server@162.159.200.1 xmt 0xe9dcdbbd.dbac73de
May 01 17:18:21 volumio-mf ntpd[747]: receive: Unexpected origin timestamp 0xe9bd3145.b98f93a7 does not match aorg 0000000000.00000000 from server@212.6.50.243 xmt 0xe9dcdbbd.dda8779b
May 01 17:18:21 volumio-mf ntpd[747]: receive: Unexpected origin timestamp 0xe9bd3145.b98061a4 does not match aorg 0000000000.00000000 from server@93.94.88.50 xmt 0xe9dcdbbd.def2f4d3
May 01 17:18:21 volumio-mf dhcpcd[869]: wlan0: carrier acquired
May 01 17:18:21 volumio-mf systemd[1]: Starting Daily apt download activities...
May 01 17:18:21 volumio-mf dhcpcd[869]: wlan0: IAID eb:9c:0b:62
May 01 17:18:21 volumio-mf dhcpcd[869]: wlan0: probing address 192.168.178.33/24
May 01 17:18:22 volumio-mf wireless.js[528]: trying...
May 01 17:18:22 volumio-mf sudo[942]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
May 01 17:18:22 volumio-mf sudo[942]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 01 17:18:22 volumio-mf sudo[942]: pam_unix(sudo:session): session closed for user root
May 01 17:18:22 volumio-mf dhcpcd[869]: wlan0: soliciting an IPv6 router
May 01 17:18:23 volumio-mf systemd[1]: apt-daily.service: Succeeded.
May 01 17:18:23 volumio-mf systemd[1]: Started Daily apt download activities.
May 01 17:18:23 volumio-mf wireless.js[528]: trying...
May 01 17:18:23 volumio-mf sudo[990]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
May 01 17:18:23 volumio-mf sudo[990]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 01 17:18:23 volumio-mf systemd[1]: Starting Daily apt upgrade and clean activities...
May 01 17:18:23 volumio-mf sudo[990]: pam_unix(sudo:session): session closed for user root
May 01 17:18:24 volumio-mf wireless.js[528]: trying...
May 01 17:18:24 volumio-mf sudo[1036]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
May 01 17:18:24 volumio-mf sudo[1036]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 01 17:18:24 volumio-mf sudo[1036]: pam_unix(sudo:session): session closed for user root
May 01 17:18:24 volumio-mf systemd[1]: apt-daily-upgrade.service: Succeeded.
May 01 17:18:24 volumio-mf systemd[1]: Started Daily apt upgrade and clean activities.
May 01 17:18:25 volumio-mf wireless.js[528]: trying...
May 01 17:18:25 volumio-mf sudo[1045]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
May 01 17:18:25 volumio-mf sudo[1045]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 01 17:18:25 volumio-mf sudo[1045]: pam_unix(sudo:session): session closed for user root
May 01 17:18:26 volumio-mf wireless.js[528]: trying...
May 01 17:18:26 volumio-mf sudo[1048]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
May 01 17:18:26 volumio-mf sudo[1048]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 01 17:18:26 volumio-mf sudo[1048]: pam_unix(sudo:session): session closed for user root
May 01 17:18:26 volumio-mf dhcpcd[869]: wlan0: using static address 192.168.178.33/24
May 01 17:18:26 volumio-mf avahi-daemon[529]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.178.33.
May 01 17:18:26 volumio-mf kernel: hwmon hwmon1: Voltage normalised
May 01 17:18:26 volumio-mf avahi-daemon[529]: New relevant interface wlan0.IPv4 for mDNS.
May 01 17:18:26 volumio-mf avahi-daemon[529]: Registering new address record for 192.168.178.33 on wlan0.IPv4.
May 01 17:18:26 volumio-mf dhcpcd[869]: wlan0: adding route to 192.168.178.0/24
May 01 17:18:26 volumio-mf dhcpcd[869]: wlan0: adding default route via 192.168.1.1
May 01 17:18:26 volumio-mf dhcpcd[869]: if_route (ADD): Network is unreachable
May 01 17:18:27 volumio-mf wireless.js[528]: trying...
May 01 17:18:27 volumio-mf sudo[1078]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
May 01 17:18:27 volumio-mf sudo[1078]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 01 17:18:27 volumio-mf sudo[1078]: pam_unix(sudo:session): session closed for user root
May 01 17:18:27 volumio-mf wireless.js[528]: Connected to: ----FRITZ!Box 4060 KA
May 01 17:18:27 volumio-mf wireless.js[528]: ----
May 01 17:18:27 volumio-mf sudo[1081]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
May 01 17:18:27 volumio-mf sudo[1081]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 01 17:18:27 volumio-mf sudo[1081]: pam_unix(sudo:session): session closed for user root
May 01 17:18:27 volumio-mf wireless.js[528]: ... joined AP, wlan0 IPv4 is 192.168.178.33, ipV6 is undefined
May 01 17:18:27 volumio-mf wireless.js[528]: It's done! AP
May 01 17:18:27 volumio-mf systemd[1]: Started Wireless Services.
May 01 17:18:27 volumio-mf kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled
May 01 17:18:27 volumio-mf systemd[1]: Started Volumio Backend Module.
May 01 17:18:27 volumio-mf systemd[1]: Started Volumio Cpu Tweaker.
May 01 17:18:27 volumio-mf systemd[1]: Reached target Multi-User System.
May 01 17:18:27 volumio-mf systemd[1]: Reached target Graphical Interface.
May 01 17:18:27 volumio-mf systemd[1]: Starting Update UTMP about System Runlevel Changes...
May 01 17:18:27 volumio-mf volumio-cpu-tweak[1087]: Setting RT Priority for mpd
May 01 17:18:27 volumio-mf systemd[1]: systemd-update-utmp-runlevel.service: Succeeded.
May 01 17:18:27 volumio-mf systemd[1]: Started Update UTMP about System Runlevel Changes.
May 01 17:18:27 volumio-mf systemd[1]: Startup finished in 10.193s (kernel) + 27.460s (userspace) = 37.654s.
May 01 17:18:27 volumio-mf volumio-cpu-tweak[1087]: pid 35's current scheduling policy: SCHED_OTHER
May 01 17:18:27 volumio-mf volumio-cpu-tweak[1087]: pid 35's current scheduling priority: 0
May 01 17:18:27 volumio-mf volumio-cpu-tweak[1087]: Setting MPD Affinity
May 01 17:18:27 volumio-mf volumio-cpu-tweak[1087]: pid 3's current affinity mask: f
May 01 17:18:27 volumio-mf volumio-cpu-tweak[1087]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input
May 01 17:18:27 volumio-mf volumio-cpu-tweak[1087]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance
May 01 17:18:27 volumio-mf systemd[1]: volumio_cpu_tweak.service: Succeeded.
May 01 17:18:27 volumio-mf ntpd[747]: Listen normally on 4 wlan0 192.168.178.33:123
May 01 17:18:27 volumio-mf ntpd[747]: new interface(s) found: waking up resolver
May 01 17:18:28 volumio-mf kernel: hwmon hwmon1: Undervoltage detected!
May 01 17:18:31 volumio-mf volumio-remote-updater[523]: [2024-05-01 17:18:31] [connect] Successful connection
May 01 17:18:32 volumio-mf volumio[1086]: info: -------------------------------------------
May 01 17:18:32 volumio-mf volumio[1086]: info: ----- Volumio3 ----
May 01 17:18:32 volumio-mf volumio[1086]: info: -------------------------------------------
May 01 17:18:32 volumio-mf volumio[1086]: info: ----- System startup ----
May 01 17:18:32 volumio-mf volumio[1086]: info: -------------------------------------------
May 01 17:18:34 volumio-mf volumio[1086]: info: MYVOLUMIO Environment detected
May 01 17:18:34 volumio-mf volumio[1086]: info: Plugin folders cleanup
May 01 17:18:34 volumio-mf volumio[1086]: info: Scanning into folder /volumio/app/plugins/
May 01 17:18:34 volumio-mf volumio[1086]: info: Scanning category audio_interface
May 01 17:18:34 volumio-mf volumio[1086]: info: Scanning category miscellanea
May 01 17:18:34 volumio-mf volumio[1086]: info: Scanning category music_service
May 01 17:18:34 volumio-mf volumio[1086]: info: Scanning category plugins.json
May 01 17:18:34 volumio-mf volumio[1086]: info: Scanning category system_controller
May 01 17:18:34 volumio-mf volumio[1086]: info: Scanning category user_interface
May 01 17:18:34 volumio-mf volumio[1086]: info: Scanning into folder /data/plugins/
May 01 17:18:34 volumio-mf volumio[1086]: info: Plugin folders cleanup completed
May 01 17:18:34 volumio-mf volumio[1086]: info: -------------------------------------------
May 01 17:18:34 volumio-mf volumio[1086]: info: ----- Core plugins startup ----
May 01 17:18:34 volumio-mf volumio[1086]: info: -------------------------------------------
May 01 17:18:34 volumio-mf volumio[1086]: info: Loading plugins from folder /volumio/app/plugins/
May 01 17:18:34 volumio-mf volumio[1086]: info: Adding plugin upnp to MyMusic Plugins
May 01 17:18:34 volumio-mf volumio[1086]: info: Adding plugin airplay_emulation to MyMusic Plugins
May 01 17:18:34 volumio-mf volumio[1086]: info: Adding plugin upnp_browser to MyMusic Plugins
May 01 17:18:34 volumio-mf volumio[1086]: info: Loading plugins from folder /data/plugins/
May 01 17:18:34 volumio-mf volumio[1086]: info: Loading plugin "system"...
May 01 17:18:34 volumio-mf volumio[1086]: info: Loading plugin "appearance"...
May 01 17:18:36 volumio-mf systemd[1]: systemd-fsckd.service: Succeeded.
May 01 17:18:37 volumio-mf volumio[1086]: info: Loading plugin "network"...
May 01 17:18:37 volumio-mf volumio[1086]: info: Refreshing Cached IP Addresses
May 01 17:18:37 volumio-mf sudo[1118]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
May 01 17:18:37 volumio-mf sudo[1118]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 01 17:18:37 volumio-mf volumio[1086]: info: Loading plugin "services"...
May 01 17:18:37 volumio-mf sudo[1118]: pam_unix(sudo:session): session closed for user root
May 01 17:18:37 volumio-mf sudo[1120]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
May 01 17:18:37 volumio-mf sudo[1120]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 01 17:18:37 volumio-mf volumio[1086]: info: Loading plugin "alsa_controller"...
May 01 17:18:37 volumio-mf sudo[1120]: pam_unix(sudo:session): session closed for user root
May 01 17:18:37 volumio-mf sudo[1123]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
May 01 17:18:37 volumio-mf sudo[1123]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 01 17:18:37 volumio-mf volumio[1086]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
May 01 17:18:37 volumio-mf volumio[1086]: info: Loading plugin "wizard"...
May 01 17:18:37 volumio-mf volumio[1086]: info: Loading plugin "networkfs"...
May 01 17:18:37 volumio-mf volumio[1086]: info: Starting Udev Watcher for removable devices
May 01 17:18:37 volumio-mf sudo[1131]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Admin,password=FLCmrc14,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.178.30/Musica /mnt/NAS/NAS_35-1C-70
May 01 17:18:37 volumio-mf sudo[1131]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 01 17:18:37 volumio-mf volumio[1086]: info: Ignoring mount for partition: boot
May 01 17:18:37 volumio-mf volumio[1086]: info: Ignoring mount for partition: volumio
May 01 17:18:37 volumio-mf volumio[1086]: info: Ignoring mount for partition: volumio_data
May 01 17:18:37 volumio-mf volumio[1086]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
May 01 17:18:37 volumio-mf volumio[1086]: info: Loading plugin "volumio_command_line_client"...
May 01 17:18:37 volumio-mf volumio[1086]: info: Loading plugin "upnp"...
May 01 17:18:37 volumio-mf volumio[1086]: info: [1714576717755] Starting Upmpd Daemon
May 01 17:18:37 volumio-mf volumio[1086]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
May 01 17:18:37 volumio-mf volumio[1086]: info: Loading plugin "my_music"...
May 01 17:18:37 volumio-mf volumio[1086]: info: Loading plugin "mpd"...
May 01 17:18:38 volumio-mf kernel: Key type cifs.spnego registered
May 01 17:18:38 volumio-mf kernel: Key type cifs.idmap registered
May 01 17:18:38 volumio-mf 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.
May 01 17:18:38 volumio-mf kernel: CIFS: Attempting to mount \\192.168.178.30\Musica
May 01 17:18:38 volumio-mf volumio[1086]: info: Loading plugin "upnp_browser"...
May 01 17:18:38 volumio-mf sudo[1131]: pam_unix(sudo:session): session closed for user root
May 01 17:18:39 volumio-mf volumio[1086]: info: Loading plugin "alarm-clock"...
May 01 17:18:39 volumio-mf volumio[1086]: info: Loading plugin "airplay_emulation"...
May 01 17:18:40 volumio-mf volumio[1086]: info: Starting Shairport Sync
May 01 17:18:40 volumio-mf volumio[1086]: info: Loading plugin "last_100"...
May 01 17:18:40 volumio-mf volumio[1086]: info: Loading plugin "webradio"...
May 01 17:18:40 volumio-mf volumio[1086]: info: Loading plugin "i2s_dacs"...
May 01 17:18:40 volumio-mf volumio[1086]: info: Loading plugin "volumiodiscovery"...
May 01 17:18:40 volumio-mf sudo[1123]: pam_unix(sudo:session): session closed for user root
May 01 17:18:40 volumio-mf volumio[1086]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
May 01 17:18:40 volumio-mf volumio[1086]: *** WARNING *** Please fix your application to use the native API of Avahi!
May 01 17:18:40 volumio-mf volumio[1086]: *** WARNING *** For more information see
May 01 17:18:40 volumio-mf node[1086]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
May 01 17:18:40 volumio-mf volumio[1086]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
May 01 17:18:40 volumio-mf volumio[1086]: *** WARNING *** Please fix your application to use the native API of Avahi!
May 01 17:18:40 volumio-mf volumio[1086]: *** WARNING *** For more information see
May 01 17:18:40 volumio-mf node[1086]: *** WARNING *** Please fix your application to use the native API of Avahi!
May 01 17:18:40 volumio-mf node[1086]: *** WARNING *** For more information see
May 01 17:18:40 volumio-mf node[1086]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
May 01 17:18:40 volumio-mf node[1086]: *** WARNING *** Please fix your application to use the native API of Avahi!
May 01 17:18:40 volumio-mf node[1086]: *** WARNING *** For more information see
May 01 17:18:40 volumio-mf volumio[1086]: info: Applying required configuration parameters for plugin volumiodiscovery
May 01 17:18:40 volumio-mf volumio[1086]: info: Discovery: Started advertising with name: Volumio mf
May 01 17:18:40 volumio-mf volumio[1086]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
May 01 17:18:40 volumio-mf volumio[1086]: info: Loading plugin "outputs"...
May 01 17:18:40 volumio-mf volumio[1086]: info: Loading plugin "albumart"...
May 01 17:18:40 volumio-mf volumio[1086]: info: Plugin example_plugin is not enabled
May 01 17:18:40 volumio-mf volumio[1086]: info: Loading plugin "inputs"...
May 01 17:18:40 volumio-mf volumio[1086]: info: Loading plugin "updater_comm"...
May 01 17:18:40 volumio-mf systemd[1]: systemd-hostnamed.service: Succeeded.
May 01 17:18:41 volumio-mf volumio[1086]: info: Plugin mpdemulation is not enabled
May 01 17:18:41 volumio-mf volumio[1086]: info: Loading plugin "rest_api"...
May 01 17:18:41 volumio-mf volumio[1086]: info: Loading plugin "websocket"...
May 01 17:18:41 volumio-mf volumio[1086]: info: Loading i18n strings for locale it
May 01 17:18:41 volumio-mf volumio[1086]: Updating browse sources language
May 01 17:18:41 volumio-mf volumio[1086]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
May 01 17:18:41 volumio-mf volumio[1086]: Forking 3 albumart workers
May 01 17:18:41 volumio-mf volumio[1086]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
May 01 17:18:41 volumio-mf volumio[1086]: info: CoreCommandRouter::initPlayerControls
May 01 17:18:41 volumio-mf volumio[1086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 01 17:18:41 volumio-mf volumio[1086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 01 17:18:41 volumio-mf volumio[1086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 01 17:18:41 volumio-mf volumio[1086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 01 17:18:41 volumio-mf volumio[1086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 01 17:18:41 volumio-mf volumio[1086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 01 17:18:41 volumio-mf volumio[1086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 01 17:18:41 volumio-mf volumio[1086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 01 17:18:41 volumio-mf volumio[1086]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
May 01 17:18:41 volumio-mf volumio[1086]: Express server listening on port 3000
May 01 17:18:41 volumio-mf volumio[1086]: [Metrics] WebUI: 11s 192.44ms
May 01 17:18:41 volumio-mf volumio[1086]: info: CoreStateMachine::resetVolumioState
May 01 17:18:41 volumio-mf volumio[1086]: info: CoreStateMachine::getcurrentVolume
May 01 17:18:41 volumio-mf volumio[1086]: info: CoreCommandRouter::volumioRetrievevolume
May 01 17:18:42 volumio-mf volumio[1086]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
May 01 17:18:42 volumio-mf volumio[1086]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
May 01 17:18:42 volumio-mf volumio[1086]: info: Discovery: Getting this device information
May 01 17:18:42 volumio-mf volumio[1086]: info: CoreCommandRouter::volumioGetState
May 01 17:18:42 volumio-mf volumio[1086]: info: CorePlayQueue::getTrack 0
May 01 17:18:42 volumio-mf volumio[1086]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
May 01 17:18:42 volumio-mf volumio[1086]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
May 01 17:18:42 volumio-mf volumio[1086]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
May 01 17:18:42 volumio-mf volumio[1086]: info: Discovery: Getting this device information
May 01 17:18:42 volumio-mf volumio[1086]: info: CoreCommandRouter::volumioGetState
May 01 17:18:42 volumio-mf volumio[1086]: info: CorePlayQueue::getTrack 0
May 01 17:18:42 volumio-mf volumio[1086]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
May 01 17:18:42 volumio-mf volumio[1086]: info: Reloading queue from file
May 01 17:18:42 volumio-mf volumio[1086]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 01 17:18:42 volumio-mf volumio[1086]: info: Setting Device type: Raspberry PI
May 01 17:18:42 volumio-mf volumio[1086]: info: CoreStateMachine::setRepeat true single undefined
May 01 17:18:42 volumio-mf volumio[1086]: info: CoreStateMachine::pushState
May 01 17:18:42 volumio-mf volumio[1086]: info: CorePlayQueue::getTrack 0
May 01 17:18:42 volumio-mf volumio[1086]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 01 17:18:42 volumio-mf volumio[1086]: info: CoreCommandRouter::volumioPushState
May 01 17:18:42 volumio-mf volumio[1086]: info: CoreStateMachine::setRandom null
May 01 17:18:42 volumio-mf volumio[1086]: info: CoreStateMachine::pushState
May 01 17:18:42 volumio-mf volumio[1086]: info: CorePlayQueue::getTrack 0
May 01 17:18:42 volumio-mf volumio[1086]: info: CoreCommandRouter::volumioPushState
May 01 17:18:42 volumio-mf volumio[1086]: verbose: New Socket.io Connection to 192.168.178.33 from 192.168.178.20 UA: okhttp/4.9.2 Total Clients: 1
May 01 17:18:42 volumio-mf volumio[1086]: verbose: New Socket.io Connection to 192.168.178.35 from 192.168.178.20 UA: okhttp/4.9.2 Total Clients: 2
May 01 17:18:42 volumio-mf volumio[1086]: info: VolumeController:: Volume=61 Mute =false
May 01 17:18:42 volumio-mf volumio[1086]: info: CoreStateMachine::pushState
May 01 17:18:42 volumio-mf volumio[1086]: info: CorePlayQueue::getTrack 0
May 01 17:18:42 volumio-mf volumio[1086]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 01 17:18:42 volumio-mf volumio[1086]: info: CoreCommandRouter::volumioPushState
May 01 17:18:42 volumio-mf volumio[1086]: info: CoreStateMachine::updateTrackBlock
May 01 17:18:42 volumio-mf volumio[1086]: info: CorePlayQueue::getTrackBlock
May 01 17:18:42 volumio-mf volumio[1086]: info: CoreCommandRouter::volumioRetrievevolume
May 01 17:18:42 volumio-mf volumio[1086]: info: CoreCommandRouter::volumioGetState
May 01 17:18:42 volumio-mf volumio[1086]: info: CorePlayQueue::getTrack 0
May 01 17:18:42 volumio-mf volumio[1086]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard
May 01 17:18:42 volumio-mf volumio[1086]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard
May 01 17:18:42 volumio-mf volumio[1086]: info: CoreCommandRouter::volumioGetState
May 01 17:18:42 volumio-mf volumio[1086]: info: CorePlayQueue::getTrack 0
May 01 17:18:42 volumio-mf volumio[1086]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard
May 01 17:18:42 volumio-mf volumio[1086]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard
May 01 17:18:43 volumio-mf volumio[1086]: info: Discovery: adding 9e34190b-73ed-4e73-9d8d-245a166cc365
May 01 17:18:43 volumio-mf volumio[1086]: info: Discovery: Found device Volumio mf
May 01 17:18:43 volumio-mf volumio[1086]: info: CoreCommandRouter::volumioGetState
May 01 17:18:43 volumio-mf volumio[1086]: info: CorePlayQueue::getTrack 0
May 01 17:18:43 volumio-mf volumio[1086]: info: Discovery: this is already registered, 9e34190b-73ed-4e73-9d8d-245a166cc365
May 01 17:18:43 volumio-mf volumio[1086]: info: Discovery: Found device Volumio mf
May 01 17:18:43 volumio-mf volumio[1086]: info: CoreCommandRouter::volumioGetState
May 01 17:18:43 volumio-mf volumio[1086]: info: CorePlayQueue::getTrack 0
May 01 17:18:43 volumio-mf volumio[1086]: info: VolumeController:: Volume=61 Mute =false
May 01 17:18:43 volumio-mf volumio[1086]: info: CoreStateMachine::pushState
May 01 17:18:43 volumio-mf volumio[1086]: info: CorePlayQueue::getTrack 0
May 01 17:18:43 volumio-mf volumio[1086]: info: CoreCommandRouter::volumioPushState
May 01 17:18:43 volumio-mf volumio[1086]: Starting albumart workers
May 01 17:18:43 volumio-mf volumio[1086]: Starting albumart workers
May 01 17:18:43 volumio-mf volumio[1086]: Starting albumart workers
May 01 17:18:46 volumio-mf volumio-remote-updater[523]: [2024-05-01 17:18:46] [connect] Successful connection
May 01 17:18:46 volumio-mf volumio-remote-updater[523]: [2024-05-01 17:18:46] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1714576726 101
May 01 17:18:46 volumio-mf volumio[1086]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 3
May 01 17:18:47 volumio-mf sudo[1227]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
May 01 17:18:47 volumio-mf sudo[1227]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 01 17:18:47 volumio-mf sudo[1227]: pam_unix(sudo:session): session closed for user root
May 01 17:18:47 volumio-mf sudo[1229]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
May 01 17:18:47 volumio-mf sudo[1229]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 01 17:18:47 volumio-mf sudo[1229]: pam_unix(sudo:session): session closed for user root
May 01 17:18:47 volumio-mf volumio[1086]: verbose: New Socket.io Connection to 192.168.178.35 from 192.168.178.20 UA: okhttp/4.9.2 Total Clients: 4
May 01 17:18:47 volumio-mf volumio[1086]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
May 01 17:18:47 volumio-mf volumio[1086]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
May 01 17:18:47 volumio-mf volumio[1086]: info: CoreCommandRouter::volumioGetVisibleSources
May 01 17:18:47 volumio-mf volumio[1086]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
May 01 17:18:47 volumio-mf volumio[1086]: info: CoreCommandRouter::volumioGetState
May 01 17:18:47 volumio-mf volumio[1086]: info: CorePlayQueue::getTrack 0
May 01 17:18:47 volumio-mf volumio[1086]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
May 01 17:18:47 volumio-mf sudo[1233]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
May 01 17:18:47 volumio-mf sudo[1233]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 01 17:18:47 volumio-mf sudo[1233]: pam_unix(sudo:session): session closed for user root
May 01 17:18:47 volumio-mf sudo[1235]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
May 01 17:18:47 volumio-mf sudo[1235]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 01 17:18:47 volumio-mf sudo[1235]: pam_unix(sudo:session): session closed for user root
May 01 17:18:47 volumio-mf volumio[1086]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
May 01 17:18:47 volumio-mf volumio[1086]: info: Received Get System Info
May 01 17:18:47 volumio-mf volumio[1086]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
May 01 17:18:47 volumio-mf volumio[1086]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
May 01 17:18:47 volumio-mf volumio[1086]: info: Discovery: Getting this device information
May 01 17:18:47 volumio-mf volumio[1086]: info: CoreCommandRouter::volumioGetState
May 01 17:18:47 volumio-mf volumio[1086]: info: CorePlayQueue::getTrack 0
May 01 17:18:47 volumio-mf volumio[1086]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
May 01 17:18:47 volumio-mf volumio[1086]: info: CoreCommandRouter::volumioGetState
May 01 17:18:47 volumio-mf volumio[1086]: info: CorePlayQueue::getTrack 0
May 01 17:18:47 volumio-mf volumio[1086]: info: Listing playlists
May 01 17:18:47 volumio-mf volumio[1086]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
May 01 17:18:47 volumio-mf volumio[1086]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
May 01 17:18:48 volumio-mf sudo[1239]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service
May 01 17:18:48 volumio-mf sudo[1239]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 01 17:18:48 volumio-mf systemd[1]: Started UPnP Renderer front-end to MPD.
May 01 17:18:48 volumio-mf sudo[1239]: pam_unix(sudo:session): session closed for user root
May 01 17:18:48 volumio-mf volumio[1086]: info: Upmpdcli Daemon Started
May 01 17:18:48 volumio-mf volumio[1086]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600
May 01 17:18:49 volumio-mf volumio[1086]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
May 01 17:18:49 volumio-mf volumio[1086]: info: Received Get System Info
May 01 17:18:49 volumio-mf volumio[1086]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
May 01 17:18:49 volumio-mf volumio[1086]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
May 01 17:18:49 volumio-mf volumio[1086]: info: Discovery: Getting this device information
May 01 17:18:49 volumio-mf volumio[1086]: info: CoreCommandRouter::volumioGetState
May 01 17:18:49 volumio-mf volumio[1086]: info: CorePlayQueue::getTrack 0
May 01 17:18:49 volumio-mf volumio[1086]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
May 01 17:18:50 volumio-mf volumio[1086]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken
May 01 17:18:50 volumio-mf volumio[1086]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
May 01 17:18:50 volumio-mf volumio[1086]: info: Received Get System Info
May 01 17:18:50 volumio-mf volumio[1086]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
May 01 17:18:50 volumio-mf volumio[1086]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
May 01 17:18:50 volumio-mf volumio[1086]: info: Discovery: Getting this device information
May 01 17:18:50 volumio-mf volumio[1086]: info: CoreCommandRouter::volumioGetState
May 01 17:18:50 volumio-mf volumio[1086]: info: CorePlayQueue::getTrack 0
May 01 17:18:50 volumio-mf volumio[1086]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
May 01 17:18:51 volumio-mf volumio[1086]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken
May 01 17:18:52 volumio-mf volumio[1086]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600
May 01 17:18:53 volumio-mf volumio[1086]: info: CoreCommandRouter::volumioPlay
May 01 17:18:53 volumio-mf volumio[1086]: info: CoreStateMachine::play index undefined
May 01 17:18:53 volumio-mf volumio[1086]: info: CoreStateMachine::setConsumeUpdateService undefined
May 01 17:18:53 volumio-mf volumio[1086]: info: CorePlayQueue::getTrack 0
May 01 17:18:53 volumio-mf volumio[1086]: info: CoreStateMachine::startPlaybackTimer
May 01 17:18:53 volumio-mf volumio[1086]: info: CorePlayQueue::getTrack 0
May 01 17:18:53 volumio-mf volumio[1086]: info: [1714576733228] ControllerWebradio::clearAddPlayTrack
May 01 17:18:53 volumio-mf kernel: hwmon hwmon1: Voltage normalised
May 01 17:18:57 volumio-mf volumio[1086]: info: CoreCommandRouter::volumioPlay
May 01 17:18:57 volumio-mf volumio[1086]: info: CoreStateMachine::play index undefined
May 01 17:18:57 volumio-mf volumio[1086]: info: CoreStateMachine::setConsumeUpdateService undefined
May 01 17:18:57 volumio-mf volumio[1086]: info: CorePlayQueue::getTrack 0
May 01 17:18:57 volumio-mf volumio[1086]: info: CoreStateMachine::startPlaybackTimer
May 01 17:18:57 volumio-mf volumio[1086]: info: CorePlayQueue::getTrack 0
May 01 17:18:57 volumio-mf volumio[1086]: info: [1714576737500] ControllerWebradio::clearAddPlayTrack
May 01 17:18:58 volumio-mf volumio[1086]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600
May 01 17:19:00 volumio-mf kernel: hwmon hwmon1: Undervoltage detected!
May 01 17:19:04 volumio-mf volumio[1086]: info: CoreCommandRouter::volumioGetQueue
May 01 17:19:04 volumio-mf volumio[1086]: info: CoreStateMachine::getQueue
May 01 17:19:04 volumio-mf volumio[1086]: info: CorePlayQueue::getQueue
May 01 17:19:05 volumio-mf volumio[1086]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
May 01 17:19:05 volumio-mf volumio[1086]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined
May 01 17:19:05 volumio-mf volumio[1086]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates
May 01 17:19:05 volumio-mf volumio[1086]: info: Received Get System Version
May 01 17:19:05 volumio-mf volumio[1086]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
May 01 17:19:05 volumio-mf volumio[1086]: info: Received Get System Info
May 01 17:19:05 volumio-mf volumio[1086]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
May 01 17:19:05 volumio-mf volumio[1086]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
May 01 17:19:05 volumio-mf volumio[1086]: info: Discovery: Getting this device information
May 01 17:19:05 volumio-mf volumio[1086]: info: CoreCommandRouter::volumioGetState
May 01 17:19:05 volumio-mf volumio[1086]: info: CorePlayQueue::getTrack 0
May 01 17:19:05 volumio-mf volumio[1086]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
May 01 17:19:08 volumio-mf volumiologrotate[512]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory
May 01 17:19:08 volumio-mf volumiologrotate[512]: ls: cannot access 'MF': No such file or directory
May 01 17:19:08 volumio-mf volumio[1086]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600
May 01 17:19:08 volumio-mf kernel: hwmon hwmon1: Voltage normalised
May 01 17:19:09 volumio-mf volumio[1086]: info: Preload queue cleared
May 01 17:19:09 volumio-mf volumio[1086]: info: CoreCommandRouter::volumioReplaceandPlayItems
May 01 17:19:09 volumio-mf volumio[1086]: info: CoreStateMachine::ClearQueue
May 01 17:19:09 volumio-mf volumio[1086]: info: CoreStateMachine::stop
May 01 17:19:09 volumio-mf volumio[1086]: info: CoreStateMachine::setConsumeUpdateService undefined
May 01 17:19:09 volumio-mf volumio[1086]: info: CorePlayQueue::clearPlayQueue
May 01 17:19:09 volumio-mf volumio[1086]: info: CorePlayQueue::saveQueue
May 01 17:19:09 volumio-mf volumio[1086]: info: CoreCommandRouter::volumioPushQueue
May 01 17:19:09 volumio-mf volumio[1086]: info: CoreStateMachine::addQueueItems
May 01 17:19:09 volumio-mf volumio[1086]: info: CorePlayQueue::addQueueItems
May 01 17:19:09 volumio-mf volumio[1086]: info: Preload queue cleared
May 01 17:19:09 volumio-mf volumio[1086]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s304644
May 01 17:19:09 volumio-mf volumio[1086]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri
May 01 17:19:09 volumio-mf volumio[1086]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
May 01 17:19:09 volumio-mf volumio[1086]: TypeError: Cannot read property 'tune_radio' of undefined
May 01 17:19:09 volumio-mf volumio[1086]: at ControllerWebradio.explodeUri (/volumio/app/plugins/music_service/webradio/index.js:539:33)
May 01 17:19:09 volumio-mf volumio[1086]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1077:32)
May 01 17:19:09 volumio-mf volumio[1086]: at CorePlayQueue.explodeUri (/volumio/app/playqueue.js:179:33)
May 01 17:19:09 volumio-mf volumio[1086]: at CorePlayQueue.addQueueItems (/volumio/app/playqueue.js:223:30)
May 01 17:19:09 volumio-mf volumio[1086]: at CoreStateMachine.addQueueItems (/volumio/app/statemachine.js:247:25)
May 01 17:19:09 volumio-mf volumio[1086]: at CoreCommandRouter.replaceAndPlay (/volumio/app/index.js:507:23)
May 01 17:19:09 volumio-mf volumio[1086]: at CoreCommandRouter.playItemsList (/volumio/app/index.js:477:17)
May 01 17:19:09 volumio-mf volumio[1086]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:118:33)
May 01 17:19:09 volumio-mf volumio[1086]: at Socket.emit (events.js:315:20)
May 01 17:19:09 volumio-mf volumio[1086]: at /volumio/node_modules/socket.io/lib/socket.js:503:12
May 01 17:19:09 volumio-mf volumio[1086]: at processTicksAndRejections (internal/process/task_queues.js:75:11)
May 01 17:19:09 volumio-mf volumio[1086]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
May 01 17:19:10 volumio-mf kernel: hwmon hwmon1: Undervoltage detected!
May 01 17:19:11 volumio-mf sudo[1292]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-01 17:18
May 01 17:19:11 volumio-mf sudo[1292]: 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"