-- Logs begin at Thu 2019-02-14 10:12:00 UTC, end at Thu 2024-08-15 16:16:09 UTC. -- Aug 15 16:15:00 volumio ntpd[777]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Aug 15 16:15:00 volumio volumio-remote-updater[651]: [2024-08-15 16:15:00] [info] asio async_connect error: system:111 (Connection refused) Aug 15 16:15:00 volumio volumio-remote-updater[651]: [2024-08-15 16:15:00] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Aug 15 16:15:00 volumio volumio-remote-updater[651]: [2024-08-15 16:15:00] [error] handle_connect error: Underlying Transport Error Aug 15 16:15:01 volumio sh[513]: eth0: leased 192.168.1.19 for 43200 seconds Aug 15 16:15:01 volumio sh[513]: eth0: adding route to 192.168.1.0/24 Aug 15 16:15:01 volumio sh[513]: eth0: adding default route via 192.168.1.254 Aug 15 16:15:01 volumio dhcpcd[555]: eth0: leased 192.168.1.19 for 43200 seconds Aug 15 16:15:01 volumio avahi-daemon[582]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.19. Aug 15 16:15:01 volumio avahi-daemon[582]: New relevant interface eth0.IPv4 for mDNS. Aug 15 16:15:01 volumio dhcpcd[555]: eth0: adding route to 192.168.1.0/24 Aug 15 16:15:01 volumio avahi-daemon[582]: Registering new address record for 192.168.1.19 on eth0.IPv4. Aug 15 16:15:01 volumio dhcpcd[555]: eth0: adding default route via 192.168.1.254 Aug 15 16:15:01 volumio sudo[834]: pam_unix(sudo:session): session closed for user root Aug 15 16:15:01 volumio wireless.js[638]: SETTING APPROPRIATE REG DOMAIN: FR Aug 15 16:15:01 volumio sudo[859]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Aug 15 16:15:01 volumio sudo[859]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 15 16:15:01 volumio sudo[859]: pam_unix(sudo:session): session closed for user root Aug 15 16:15:01 volumio sh[513]: forked to background, child pid 866 Aug 15 16:15:01 volumio dhcpcd[555]: forked to background, child pid 866 Aug 15 16:15:01 volumio sudo[867]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set FR Aug 15 16:15:01 volumio sudo[867]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 15 16:15:01 volumio sudo[867]: pam_unix(sudo:session): session closed for user root Aug 15 16:15:01 volumio wireless.js[638]: SUCCESSFULLY SET NEW REGDOMAIN: FR Aug 15 16:15:01 volumio sudo[892]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Aug 15 16:15:01 volumio sudo[892]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 15 16:15:01 volumio sudo[892]: pam_unix(sudo:session): session closed for user root Aug 15 16:15:01 volumio sudo[897]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Aug 15 16:15:01 volumio sudo[897]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 15 16:15:01 volumio ifplugd(eth0)[776]: client: ifup: interface eth0 already configured Aug 15 16:15:01 volumio sh[513]: eth0=eth0 Aug 15 16:15:01 volumio ifplugd(eth0)[776]: Program executed successfully. Aug 15 16:15:01 volumio ifplugd[725]: Network Interface Plugging Daemon...start eth0...done. Aug 15 16:15:01 volumio systemd[1]: Started LSB: Brings up/down network automatically. Aug 15 16:15:02 volumio sudo[897]: pam_unix(sudo:session): session closed for user root Aug 15 16:15:02 volumio wireless.js[638]: Hotspot is disabled, not starting it Aug 15 16:15:02 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Aug 15 16:15:02 volumio systemd[1]: wireless.service: Succeeded. Aug 15 16:15:02 volumio systemd[1]: Started Wireless Services. Aug 15 16:15:02 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Aug 15 16:15:02 volumio systemd[1]: Started Volumio Backend Module. Aug 15 16:15:02 volumio systemd[1]: Started Volumio Cpu Tweaker. Aug 15 16:15:02 volumio volumio-cpu-tweak[908]: Setting RT Priority for mpd Aug 15 16:15:02 volumio volumio-cpu-tweak[908]: pid 35's current scheduling policy: SCHED_OTHER Aug 15 16:15:02 volumio volumio-cpu-tweak[908]: pid 35's current scheduling priority: 0 Aug 15 16:15:02 volumio volumio-cpu-tweak[908]: Setting MPD Affinity Aug 15 16:15:02 volumio volumio-cpu-tweak[908]: pid 3's current affinity mask: f Aug 15 16:15:02 volumio volumio-cpu-tweak[908]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Aug 15 16:15:02 volumio volumio-cpu-tweak[908]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Aug 15 16:15:02 volumio nmbd[741]: [2024/08/15 16:15:02.373457, 0] ../lib/util/become_daemon.c:138(daemon_ready) Aug 15 16:15:02 volumio systemd[1]: Started Samba NMB Daemon. Aug 15 16:15:02 volumio nmbd[741]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Aug 15 16:15:02 volumio systemd[1]: Starting Samba Winbind Daemon... Aug 15 16:15:02 volumio systemd[1]: volumio_cpu_tweak.service: Succeeded. Aug 15 16:15:02 volumio winbindd[924]: [2024/08/15 16:15:02.799892, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Aug 15 16:15:02 volumio winbindd[924]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Aug 15 16:15:02 volumio systemd[1]: Started Samba Winbind Daemon. Aug 15 16:15:02 volumio winbindd[924]: [2024/08/15 16:15:02.831591, 0] ../lib/util/become_daemon.c:138(daemon_ready) Aug 15 16:15:02 volumio winbindd[924]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Aug 15 16:15:02 volumio systemd[1]: Starting Samba SMB Daemon... Aug 15 16:15:03 volumio ntpd[777]: Listen normally on 3 eth0 192.168.1.19:123 Aug 15 16:15:03 volumio ntpd[777]: new interface(s) found: waking up resolver Aug 15 16:15:03 volumio winbindd[924]: [2024/08/15 16:15:03.403686, 0] ../source3/winbindd/wb_lookupsids.c:659(wb_lookupsids_recv) Aug 15 16:15:03 volumio winbindd[924]: res_names->count = 3, expected 4 Aug 15 16:15:03 volumio systemd[1]: Started Samba SMB Daemon. Aug 15 16:15:03 volumio systemd[1]: Reached target Multi-User System. Aug 15 16:15:03 volumio smbd[929]: [2024/08/15 16:15:03.433614, 0] ../lib/util/become_daemon.c:138(daemon_ready) Aug 15 16:15:03 volumio systemd[1]: Reached target Graphical Interface. Aug 15 16:15:03 volumio smbd[929]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Aug 15 16:15:03 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Aug 15 16:15:03 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Aug 15 16:15:03 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Aug 15 16:15:03 volumio systemd[1]: Startup finished in 17.070s (kernel) + 18.014s (userspace) = 35.084s. Aug 15 16:15:05 volumio volumio[907]: info: ------------------------------------------- Aug 15 16:15:05 volumio volumio[907]: info: ----- Volumio3 ---- Aug 15 16:15:05 volumio volumio[907]: info: ------------------------------------------- Aug 15 16:15:05 volumio volumio[907]: info: ----- System startup ---- Aug 15 16:15:05 volumio volumio[907]: info: ------------------------------------------- Aug 15 16:15:06 volumio volumio[907]: info: MYVOLUMIO Environment detected Aug 15 16:15:06 volumio volumio[907]: info: Plugin folders cleanup Aug 15 16:15:06 volumio volumio[907]: info: Scanning into folder /volumio/app/plugins/ Aug 15 16:15:06 volumio volumio[907]: info: Scanning category audio_interface Aug 15 16:15:06 volumio volumio[907]: info: Scanning category miscellanea Aug 15 16:15:06 volumio volumio[907]: info: Scanning category music_service Aug 15 16:15:06 volumio volumio[907]: info: Scanning category plugins.json Aug 15 16:15:06 volumio volumio[907]: info: Scanning category system_controller Aug 15 16:15:06 volumio volumio[907]: info: Scanning category user_interface Aug 15 16:15:06 volumio volumio[907]: info: Scanning into folder /data/plugins/ Aug 15 16:15:06 volumio volumio[907]: info: Scanning category audio_interface Aug 15 16:15:06 volumio volumio[907]: info: Scanning category music_service Aug 15 16:15:06 volumio volumio[907]: info: Scanning category system_controller Aug 15 16:15:06 volumio volumio[907]: info: Scanning category system_hardware Aug 15 16:15:06 volumio volumio[907]: info: Scanning category user_interface Aug 15 16:15:06 volumio volumio[907]: info: Plugin folders cleanup completed Aug 15 16:15:06 volumio volumio[907]: info: ------------------------------------------- Aug 15 16:15:06 volumio volumio[907]: info: ----- Core plugins startup ---- Aug 15 16:15:06 volumio volumio[907]: info: ------------------------------------------- Aug 15 16:15:06 volumio volumio[907]: info: Loading plugins from folder /volumio/app/plugins/ Aug 15 16:15:06 volumio volumio[907]: info: Adding plugin upnp to MyMusic Plugins Aug 15 16:15:06 volumio volumio[907]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 15 16:15:06 volumio volumio[907]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 15 16:15:06 volumio volumio[907]: info: Loading plugins from folder /data/plugins/ Aug 15 16:15:06 volumio volumio[907]: info: Loading plugin "system"... Aug 15 16:15:06 volumio volumio[907]: info: Loading plugin "appearance"... Aug 15 16:15:07 volumio volumio[907]: info: Loading plugin "network"... Aug 15 16:15:07 volumio volumio[907]: info: Refreshing Cached IP Addresses Aug 15 16:15:07 volumio sudo[952]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 15 16:15:07 volumio sudo[952]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 15 16:15:07 volumio volumio[907]: info: Loading plugin "services"... Aug 15 16:15:07 volumio sudo[954]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 15 16:15:07 volumio sudo[954]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 15 16:15:07 volumio sudo[952]: pam_unix(sudo:session): session closed for user root Aug 15 16:15:07 volumio volumio[907]: info: Loading plugin "alsa_controller"... Aug 15 16:15:07 volumio sudo[954]: pam_unix(sudo:session): session closed for user root Aug 15 16:15:07 volumio sudo[956]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 15 16:15:07 volumio sudo[956]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 15 16:15:08 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 15 16:15:08 volumio volumio[907]: info: Loading plugin "wizard"... Aug 15 16:15:08 volumio volumio[907]: info: Loading plugin "networkfs"... Aug 15 16:15:08 volumio volumio[907]: info: Starting Udev Watcher for removable devices Aug 15 16:15:08 volumio sudo[965]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto 192.168.1.3:/volume1/music /mnt/NAS/music Aug 15 16:15:08 volumio sudo[965]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 15 16:15:08 volumio sudo[967]: 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.1.3/Downloads Torrent /mnt/NAS/Downloads_Torrent Aug 15 16:15:08 volumio sudo[967]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 15 16:15:08 volumio volumio[907]: info: Ignoring mount for partition: boot Aug 15 16:15:08 volumio volumio[907]: info: Ignoring mount for partition: volumio Aug 15 16:15:08 volumio volumio[907]: info: Ignoring mount for partition: volumio_data Aug 15 16:15:08 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 15 16:15:08 volumio volumio[907]: info: Loading plugin "volumio_command_line_client"... Aug 15 16:15:08 volumio volumio[907]: info: Loading plugin "upnp"... Aug 15 16:15:08 volumio volumio[907]: info: [1723738508242] Starting Upmpd Daemon Aug 15 16:15:08 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 15 16:15:08 volumio volumio[907]: info: Loading plugin "my_music"... Aug 15 16:15:08 volumio volumio[907]: info: Loading plugin "mpd"... Aug 15 16:15:08 volumio volumio-remote-updater[651]: [2024-08-15 16:15:08] [connect] Successful connection Aug 15 16:15:08 volumio systemd[1]: /lib/systemd/system/rpc-statd.service:13: PIDFile= references path below legacy directory /var/run/, updating /var/run/rpc.statd.pid → /run/rpc.statd.pid; please update the unit file accordingly. Aug 15 16:15:08 volumio kernel: Key type cifs.spnego registered Aug 15 16:15:08 volumio kernel: Key type cifs.idmap registered Aug 15 16:15:08 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. Aug 15 16:15:08 volumio kernel: CIFS: Attempting to mount \\192.168.1.3\Downloads Torrent Aug 15 16:15:08 volumio systemd[1]: Starting Preprocess NFS configuration... Aug 15 16:15:08 volumio systemd[1]: Reached target Host and Network Name Lookups. Aug 15 16:15:08 volumio systemd[1]: nfs-config.service: Succeeded. Aug 15 16:15:08 volumio systemd[1]: Started Preprocess NFS configuration. Aug 15 16:15:08 volumio systemd[1]: Starting Notify NFS peers of a restart... Aug 15 16:15:08 volumio systemd[1]: Starting NFS status monitor for NFSv2/3 locking.... Aug 15 16:15:08 volumio sm-notify[1005]: Version 1.3.3 starting Aug 15 16:15:08 volumio systemd[1]: rpc-statd-notify.service: Succeeded. Aug 15 16:15:08 volumio systemd[1]: Started Notify NFS peers of a restart. Aug 15 16:15:08 volumio rpc.statd[1007]: Version 1.3.3 starting Aug 15 16:15:08 volumio rpc.statd[1007]: Flags: TI-RPC Aug 15 16:15:08 volumio systemd[1]: Started NFS status monitor for NFSv2/3 locking.. Aug 15 16:15:08 volumio sudo[967]: pam_unix(sudo:session): session closed for user root Aug 15 16:15:08 volumio sudo[965]: pam_unix(sudo:session): session closed for user root Aug 15 16:15:08 volumio volumio[907]: info: Loading plugin "upnp_browser"... Aug 15 16:15:09 volumio volumio[907]: info: Loading plugin "alarm-clock"... Aug 15 16:15:09 volumio volumio[907]: info: Loading plugin "airplay_emulation"... Aug 15 16:15:09 volumio volumio[907]: info: Starting Shairport Sync Aug 15 16:15:09 volumio volumio[907]: info: Loading plugin "last_100"... Aug 15 16:15:09 volumio volumio[907]: info: Loading plugin "webradio"... Aug 15 16:15:09 volumio volumio[907]: info: Loading plugin "i2s_dacs"... Aug 15 16:15:09 volumio volumio[907]: info: Loading plugin "volumiodiscovery"... Aug 15 16:15:09 volumio volumio[907]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 15 16:15:09 volumio volumio[907]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 15 16:15:09 volumio volumio[907]: *** WARNING *** For more information see Aug 15 16:15:09 volumio volumio[907]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 15 16:15:09 volumio node[907]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 15 16:15:09 volumio volumio[907]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 15 16:15:09 volumio volumio[907]: *** WARNING *** For more information see Aug 15 16:15:09 volumio node[907]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 15 16:15:09 volumio node[907]: *** WARNING *** For more information see Aug 15 16:15:09 volumio node[907]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 15 16:15:09 volumio node[907]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 15 16:15:09 volumio node[907]: *** WARNING *** For more information see Aug 15 16:15:09 volumio volumio[907]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 15 16:15:09 volumio volumio[907]: info: Discovery: Started advertising with name: Volumio Aug 15 16:15:09 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 15 16:15:09 volumio volumio[907]: info: Loading plugin "mixcloud"... Aug 15 16:15:10 volumio volumio[907]: info: Loading plugin "spop"... Aug 15 16:15:10 volumio sudo[956]: pam_unix(sudo:session): session closed for user root Aug 15 16:15:11 volumio volumio[907]: info: Loading plugin "now_playing"... Aug 15 16:15:13 volumio volumio[907]: info: Loading plugin "outputs"... Aug 15 16:15:13 volumio volumio[907]: info: Loading plugin "albumart"... Aug 15 16:15:13 volumio volumio[907]: info: Plugin example_plugin is not enabled Aug 15 16:15:13 volumio volumio[907]: info: Loading plugin "inputs"... Aug 15 16:15:13 volumio volumio[907]: info: Loading plugin "updater_comm"... Aug 15 16:15:13 volumio volumio[907]: info: Plugin mpdemulation is not enabled Aug 15 16:15:13 volumio volumio[907]: info: Loading plugin "rest_api"... Aug 15 16:15:13 volumio volumio[907]: info: Loading plugin "websocket"... Aug 15 16:15:13 volumio volumio[907]: info: Starting Socket.io Server version 2.3.0 Aug 15 16:15:13 volumio volumio[907]: info: Loading plugin "fusiondsp"... Aug 15 16:15:13 volumio volumio[907]: Forking 3 albumart workers Aug 15 16:15:14 volumio volumio[907]: info: Applying required configuration parameters for plugin fusiondsp Aug 15 16:15:14 volumio volumio[907]: info: Loading plugin "motherearthradio"... Aug 15 16:15:14 volumio volumio[907]: Starting albumart workers Aug 15 16:15:14 volumio volumio[907]: Starting albumart workers Aug 15 16:15:14 volumio volumio[907]: Starting albumart workers Aug 15 16:15:14 volumio volumio[907]: info: Applying required configuration parameters for plugin motherearthradio Aug 15 16:15:14 volumio volumio[907]: info: [1723738514938] [MotherEarth] API delay: 5 Aug 15 16:15:14 volumio volumio[907]: info: Loading plugin "podcast"... Aug 15 16:15:15 volumio volumio[907]: info: ControllerPodcast::constructor Aug 15 16:15:15 volumio volumio[907]: info: Loading plugin "audiophonicsonoff"... Aug 15 16:15:15 volumio volumio[907]: info: Applying required configuration parameters for plugin audiophonicsonoff Aug 15 16:15:15 volumio volumio[907]: info: Audiophonics on/off initiated Aug 15 16:15:15 volumio volumio[907]: info: Loading plugin "audiophonics_evo_sabre"... Aug 15 16:15:15 volumio volumio[907]: info: Applying required configuration parameters for plugin audiophonics_evo_sabre Aug 15 16:15:16 volumio volumio[907]: info: Loading plugin "rotaryencoder2"... Aug 15 16:15:16 volumio volumio[907]: info: Loading plugin "Systeminfo"... Aug 15 16:15:17 volumio volumio[907]: info: Loading plugin "lastfm"... Aug 15 16:15:17 volumio volumio[907]: info: Loading plugin "touch_display"... Aug 15 16:15:18 volumio volumio[907]: info: Applying required configuration parameters for plugin touch_display Aug 15 16:15:18 volumio volumio[907]: info: Loading i18n strings for locale fr Aug 15 16:15:18 volumio volumio[907]: info: Plugin audiophonics_evo_sabre has duplicated i18n key SAVE. It is ignored. Aug 15 16:15:18 volumio volumio[907]: Updating browse sources language Aug 15 16:15:18 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 15 16:15:18 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 15 16:15:18 volumio volumio[907]: info: CoreCommandRouter::initPlayerControls Aug 15 16:15:18 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 15 16:15:18 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 15 16:15:18 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 15 16:15:18 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 15 16:15:18 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 15 16:15:18 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 15 16:15:18 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 15 16:15:18 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 15 16:15:18 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 15 16:15:18 volumio volumio[907]: Express server listening on port 3000 Aug 15 16:15:18 volumio volumio[907]: [Metrics] WebUI: 14s 462.61ms Aug 15 16:15:18 volumio volumio[907]: info: CoreStateMachine::resetVolumioState Aug 15 16:15:18 volumio volumio[907]: info: CoreStateMachine::getcurrentVolume Aug 15 16:15:18 volumio volumio[907]: info: CoreCommandRouter::volumioRetrievevolume Aug 15 16:15:18 volumio sudo[1070]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 15 16:15:18 volumio sudo[1070]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 15 16:15:18 volumio sudo[1070]: pam_unix(sudo:session): session closed for user root Aug 15 16:15:18 volumio sudo[1072]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 15 16:15:18 volumio sudo[1072]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 15 16:15:18 volumio sudo[1072]: pam_unix(sudo:session): session closed for user root Aug 15 16:15:18 volumio volumio[907]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Aug 15 16:15:18 volumio volumio[907]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Aug 15 16:15:18 volumio volumio[907]: info: VolumeController:: Volume=22 Mute =true Aug 15 16:15:18 volumio volumio[907]: info: CoreStateMachine::pushState Aug 15 16:15:18 volumio volumio[907]: info: CorePlayQueue::getTrack 0 Aug 15 16:15:18 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 15 16:15:18 volumio volumio[907]: info: CoreCommandRouter::volumioPushState Aug 15 16:15:18 volumio volumio[907]: info: CoreStateMachine::updateTrackBlock Aug 15 16:15:18 volumio volumio[907]: info: CorePlayQueue::getTrackBlock Aug 15 16:15:18 volumio volumio[907]: info: CoreCommandRouter::volumioRetrievevolume Aug 15 16:15:19 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 15 16:15:19 volumio volumio[907]: info: Reloading queue from file Aug 15 16:15:19 volumio volumio[907]: info: Setting Device type: Raspberry PI Aug 15 16:15:19 volumio volumio[907]: info: CoreStateMachine::setRepeat false single undefined Aug 15 16:15:19 volumio volumio[907]: info: CoreStateMachine::pushState Aug 15 16:15:19 volumio volumio[907]: info: CorePlayQueue::getTrack 0 Aug 15 16:15:19 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 15 16:15:19 volumio volumio[907]: info: CoreCommandRouter::volumioPushState Aug 15 16:15:19 volumio volumio[907]: info: CoreStateMachine::setRandom false Aug 15 16:15:19 volumio volumio[907]: info: CoreStateMachine::pushState Aug 15 16:15:19 volumio volumio[907]: info: CorePlayQueue::getTrack 0 Aug 15 16:15:19 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 15 16:15:19 volumio volumio[907]: info: CoreCommandRouter::volumioPushState Aug 15 16:15:19 volumio volumio[907]: info: Completed loading Core Plugins Aug 15 16:15:19 volumio volumio[907]: info: Preparing to generate the ALSA configuration file Aug 15 16:15:19 volumio volumio[907]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Aug 15 16:15:19 volumio volumio[907]: info: Reading ALSA contributions from plugins. Aug 15 16:15:19 volumio sudo[1084]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Aug 15 16:15:19 volumio sudo[1084]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 15 16:15:19 volumio volumio[907]: info: VolumeController:: Volume=22 Mute =true Aug 15 16:15:19 volumio volumio[907]: info: CoreStateMachine::pushState Aug 15 16:15:19 volumio volumio[907]: info: CorePlayQueue::getTrack 0 Aug 15 16:15:19 volumio volumio[907]: info: CoreCommandRouter::volumioPushState Aug 15 16:15:19 volumio volumio[907]: info: Discovery: adding cda790a8-bc80-4e5b-be05-718532fb8e37 Aug 15 16:15:19 volumio volumio[907]: info: Discovery: Found device Volumio Aug 15 16:15:19 volumio volumio[907]: info: CoreCommandRouter::volumioGetState Aug 15 16:15:19 volumio volumio[907]: info: CorePlayQueue::getTrack 0 Aug 15 16:15:19 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Aug 15 16:15:19 volumio sudo[1084]: pam_unix(sudo:session): session closed for user root Aug 15 16:15:19 volumio volumio[907]: info: Upmpdcli Daemon Started Aug 15 16:15:19 volumio volumio[907]: info: Asound.conf file unchanged, so no further update is needed Aug 15 16:15:19 volumio volumio[907]: info: Output device has changed, restarting MPD Aug 15 16:15:19 volumio volumio[907]: info: Output device has changed, restarting Shairport Sync Aug 15 16:15:19 volumio sudo[1088]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 15 16:15:19 volumio sudo[1088]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 15 16:15:19 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 15 16:15:19 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 15 16:15:19 volumio sudo[1088]: pam_unix(sudo:session): session closed for user root Aug 15 16:15:19 volumio sudo[1090]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 15 16:15:19 volumio sudo[1090]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 15 16:15:19 volumio volumio[907]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 15 16:15:19 volumio volumio[907]: info: ___________ START PLUGINS ___________ Aug 15 16:15:19 volumio volumio[907]: info: ControllerMpd::onStart: Initializing MPD Aug 15 16:15:19 volumio volumio[907]: info: Creating MPD Configuration file Aug 15 16:15:19 volumio systemd[1]: Listening on mpd.socket. Aug 15 16:15:19 volumio sudo[1098]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 15 16:15:19 volumio systemd[1]: Starting Music Player Daemon... Aug 15 16:15:19 volumio sudo[1098]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 15 16:15:19 volumio sudo[1098]: pam_unix(sudo:session): session closed for user root Aug 15 16:15:19 volumio sudo[1101]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 15 16:15:19 volumio sudo[1101]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 15 16:15:19 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 15 16:15:19 volumio volumio[907]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 15 16:15:19 volumio volumio[907]: info: [1723738519508] CoreMusicLibrary::Adding element Serveurs Média Aug 15 16:15:19 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 15 16:15:19 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Aug 15 16:15:19 volumio systemd[1]: mpd.service: Succeeded. Aug 15 16:15:19 volumio systemd[1]: Stopped Music Player Daemon. Aug 15 16:15:19 volumio systemd[1]: Starting Music Player Daemon... Aug 15 16:15:19 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 15 16:15:19 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 15 16:15:19 volumio volumio[907]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 15 16:15:19 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 15 16:15:19 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 15 16:15:19 volumio volumio[907]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 15 16:15:19 volumio volumio[907]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 15 16:15:19 volumio volumio[907]: info: [1723738519637] CoreMusicLibrary::Adding element Last_100 Aug 15 16:15:19 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 15 16:15:19 volumio volumio[907]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 15 16:15:19 volumio volumio[907]: info: [1723738519641] CoreMusicLibrary::Adding element Webradio Aug 15 16:15:19 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 15 16:15:19 volumio sudo[1104]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 15 16:15:19 volumio sudo[1104]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 15 16:15:19 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 15 16:15:19 volumio volumio[907]: info: Initializing BBC Radios Aug 15 16:15:19 volumio sudo[1104]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Aug 15 16:15:19 volumio sudo[1104]: pam_unix(sudo:session): session closed for user root Aug 15 16:15:19 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 15 16:15:19 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 15 16:15:19 volumio volumio[907]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 15 16:15:19 volumio volumio[907]: info: [1723738519743] CoreMusicLibrary::Adding element Mixcloud Aug 15 16:15:19 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 15 16:15:19 volumio volumio[907]: Cannot find translation for source Mixcloud Aug 15 16:15:19 volumio volumio[907]: info: Creating Spotify config file Aug 15 16:15:19 volumio volumio[907]: info: [now-playing-config] Config is up to date. Aug 15 16:15:19 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 15 16:15:19 volumio volumio[907]: info: Discovery: Getting this device information Aug 15 16:15:19 volumio volumio[907]: info: CoreCommandRouter::volumioGetState Aug 15 16:15:19 volumio volumio[907]: info: CorePlayQueue::getTrack 0 Aug 15 16:15:19 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 15 16:15:19 volumio volumio[907]: info: Loading i18n strings for locale fr Aug 15 16:15:19 volumio volumio[907]: info: Plugin audiophonics_evo_sabre has duplicated i18n key SAVE. It is ignored. Aug 15 16:15:19 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Aug 15 16:15:19 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 15 16:15:19 volumio volumio[907]: info: mixtype--------------------- Hardware Aug 15 16:15:19 volumio volumio[907]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 15 16:15:19 volumio volumio[907]: info: [1723738519850] CoreMusicLibrary::Adding element Mother Earth Radio Aug 15 16:15:19 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 15 16:15:19 volumio volumio[907]: Cannot find translation for source Mixcloud Aug 15 16:15:19 volumio volumio[907]: Cannot find translation for source Mother Earth Radio Aug 15 16:15:19 volumio volumio[907]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 15 16:15:19 volumio volumio[907]: info: [1723738519867] CoreMusicLibrary::Adding element Podcast Aug 15 16:15:19 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 15 16:15:19 volumio volumio[907]: Cannot find translation for source Mixcloud Aug 15 16:15:19 volumio volumio[907]: Cannot find translation for source Mother Earth Radio Aug 15 16:15:19 volumio volumio[907]: Cannot find translation for source Podcast Aug 15 16:15:19 volumio volumio[907]: info: Configuring GPIO pins Aug 15 16:15:19 volumio volumio[907]: error: Plugin audiophonicsonoff failed to start! Error: EBUSY: resource busy or locked, write Aug 15 16:15:19 volumio volumio[907]: info: EVO SABRE : Starting Plugin Aug 15 16:15:19 volumio volumio[907]: info: Loading i18n strings for locale fr Aug 15 16:15:19 volumio volumio[907]: info: Plugin audiophonics_evo_sabre has duplicated i18n key SAVE. It is ignored. Aug 15 16:15:19 volumio volumio[907]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 15 16:15:19 volumio volumio[907]: info: [1723738519946] CoreMusicLibrary::Adding element LastFM Aug 15 16:15:19 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 15 16:15:19 volumio volumio[907]: Cannot find translation for source Mixcloud Aug 15 16:15:19 volumio volumio[907]: Cannot find translation for source Mother Earth Radio Aug 15 16:15:19 volumio volumio[907]: Cannot find translation for source Podcast Aug 15 16:15:19 volumio volumio[907]: Cannot find translation for source LastFM Aug 15 16:15:19 volumio volumio[907]: info: [LastFM] scrobbler initiated! Aug 15 16:15:19 volumio volumio[907]: info: [LastFM] extended logging: false Aug 15 16:15:19 volumio volumio[907]: info: [LastFM] try scrobble stream/radio plays: true Aug 15 16:15:19 volumio volumio[907]: info: [LastFM] Configuration parameters missing: "API_KEY" "API_SECRET" "username" "authToken" Aug 15 16:15:19 volumio volumio[907]: info: [LastFM] Left init routine Aug 15 16:15:19 volumio volumio[907]: info: [LastFM] Socket already connected: true Aug 15 16:15:19 volumio volumio[907]: info: Loading i18n strings for locale fr Aug 15 16:15:19 volumio volumio[907]: info: Plugin audiophonics_evo_sabre has duplicated i18n key SAVE. It is ignored. Aug 15 16:15:19 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 15 16:15:19 volumio volumio[907]: info: Volumio Calling Home Aug 15 16:15:20 volumio volumio[907]: info: [now-playing-app] App is listening on port 4004. Aug 15 16:15:20 volumio volumio[907]: info: Preparing to generate the ALSA configuration file Aug 15 16:15:20 volumio volumio[907]: info: [LastFM] finished init with error: Configuration parameters missing: "API_KEY" "API_SECRET" "username" "authToken" Aug 15 16:15:20 volumio sudo[1129]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Aug 15 16:15:20 volumio sudo[1129]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 15 16:15:20 volumio systemd[1]: Reloading. Aug 15 16:15:20 volumio volumio[907]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Aug 15 16:15:20 volumio volumio[907]: info: Reading ALSA contributions from plugins. Aug 15 16:15:20 volumio volumio[907]: info: MPD Permissions set Aug 15 16:15:20 volumio volumio[907]: info: MPD Permissions set Aug 15 16:15:20 volumio volumio[907]: info: Volumio called home Aug 15 16:15:20 volumio volumio[907]: info: Spotify config file written Aug 15 16:15:20 volumio sudo[1145]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Aug 15 16:15:20 volumio sudo[1145]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 15 16:15:20 volumio volumio[907]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Aug 15 16:15:20 volumio volumio[907]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Aug 15 16:15:20 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 15 16:15:20 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 15 16:15:20 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 15 16:15:20 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 15 16:15:20 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 15 16:15:20 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 15 16:15:20 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 15 16:15:20 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 15 16:15:20 volumio sudo[1148]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Aug 15 16:15:20 volumio sudo[1148]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 15 16:15:20 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 15 16:15:20 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 15 16:15:20 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 15 16:15:20 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 15 16:15:20 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 15 16:15:20 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 15 16:15:20 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 15 16:15:20 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 15 16:15:20 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 15 16:15:20 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 15 16:15:20 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 15 16:15:20 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 15 16:15:20 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Aug 15 16:15:20 volumio volumio[907]: info: touch_display: Raspberry Pi Foundation touch screen detected. Aug 15 16:15:20 volumio volumio[907]: info: touch_display: Backlight module of a Raspberry Pi Foundation touch screen detected. Aug 15 16:15:20 volumio volumio[907]: info: Starting Shairport Sync Aug 15 16:15:20 volumio volumio[907]: info: Starting Shairport Sync Aug 15 16:15:20 volumio sudo[1159]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 15 16:15:20 volumio sudo[1159]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 15 16:15:20 volumio volumio[907]: info: Starting Shairport Sync Aug 15 16:15:20 volumio sudo[1162]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 15 16:15:20 volumio sudo[1162]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 15 16:15:20 volumio volumio[907]: info: CoreCommandRouter::volumioGetState Aug 15 16:15:20 volumio volumio[907]: info: CorePlayQueue::getTrack 0 Aug 15 16:15:20 volumio volumio[907]: info: CoreCommandRouter::volumioGetState Aug 15 16:15:20 volumio volumio[907]: info: CorePlayQueue::getTrack 0 Aug 15 16:15:20 volumio sudo[1165]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 15 16:15:20 volumio sudo[1165]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 15 16:15:20 volumio systemd[1]: getty@tty1.service: Current command vanished from the unit file, execution of the command list won't be resumed. Aug 15 16:15:20 volumio volumio[907]: info: Asound.conf file unchanged, so no further update is needed Aug 15 16:15:20 volumio volumio[907]: info: Output device has changed, restarting MPD Aug 15 16:15:20 volumio systemd[1]: /lib/systemd/system/rpc-statd.service:13: PIDFile= references path below legacy directory /var/run/, updating /var/run/rpc.statd.pid → /run/rpc.statd.pid; please update the unit file accordingly. Aug 15 16:15:20 volumio volumio[907]: info: Output device has changed, restarting Shairport Sync Aug 15 16:15:20 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 15 16:15:20 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 15 16:15:20 volumio sudo[1168]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 15 16:15:20 volumio sudo[1168]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 15 16:15:21 volumio sudo[1171]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 15 16:15:21 volumio volumio[907]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 15 16:15:21 volumio volumio[907]: info: MPD Permissions set Aug 15 16:15:21 volumio sudo[1171]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 15 16:15:21 volumio sudo[1168]: pam_unix(sudo:session): session closed for user root Aug 15 16:15:21 volumio sudo[1177]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+w /sys/devices/platform/rpi_backlight/backlight/rpi_backlight/brightness Aug 15 16:15:21 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 15 16:15:21 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 15 16:15:21 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 15 16:15:21 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 15 16:15:21 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 15 16:15:21 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 15 16:15:21 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 15 16:15:21 volumio sudo[1177]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 15 16:15:21 volumio sudo[1177]: pam_unix(sudo:session): session closed for user root Aug 15 16:15:21 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 15 16:15:21 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 15 16:15:21 volumio systemd[1]: systemd-fsckd.service: Succeeded. Aug 15 16:15:21 volumio sudo[1129]: pam_unix(sudo:session): session closed for user root Aug 15 16:15:21 volumio volumio[907]: info: touch_display: File permissions for backlight brightness control set. Aug 15 16:15:21 volumio systemd[1]: Reloading. Aug 15 16:15:21 volumio volumio[907]: info: touch_display: systemctl daemon-reload succeeded. Aug 15 16:15:21 volumio volumio[907]: info: Starting Shairport Sync Aug 15 16:15:21 volumio sudo[1187]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Aug 15 16:15:21 volumio sudo[1187]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 15 16:15:21 volumio sudo[1188]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 15 16:15:21 volumio sudo[1188]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 15 16:15:21 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Aug 15 16:15:21 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 15 16:15:21 volumio volumio[907]: info: AAAAAAAAAAAAAA-> 44100 48000 88200 96000 176400 192000 384000 <-AAAAAAAAAAAAA Aug 15 16:15:21 volumio volumio[907]: info: FusionDsp loaded Aug 15 16:15:21 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 15 16:15:21 volumio sudo[1209]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Aug 15 16:15:21 volumio sudo[1209]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 15 16:15:21 volumio volumio[907]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Aug 15 16:15:22 volumio systemd[1]: /lib/systemd/system/rpc-statd.service:13: PIDFile= references path below legacy directory /var/run/, updating /var/run/rpc.statd.pid → /run/rpc.statd.pid; please update the unit file accordingly. Aug 15 16:15:22 volumio volumio[907]: error: File size not found in array! Aug 15 16:15:22 volumio volumio[907]: info: eq1: Aug 15 16:15:22 volumio volumio[907]: type: Biquad Aug 15 16:15:22 volumio volumio[907]: parameters: Aug 15 16:15:22 volumio volumio[907]: type: Peaking Aug 15 16:15:22 volumio volumio[907]: freq: 25 Aug 15 16:15:22 volumio volumio[907]: q: 1.85 Aug 15 16:15:22 volumio volumio[907]: gain: 0 Aug 15 16:15:22 volumio volumio[907]: eq2: Aug 15 16:15:22 volumio volumio[907]: type: Biquad Aug 15 16:15:22 volumio volumio[907]: parameters: Aug 15 16:15:22 volumio volumio[907]: type: Peaking Aug 15 16:15:22 volumio volumio[907]: freq: 40 Aug 15 16:15:22 volumio volumio[907]: q: 1.85 Aug 15 16:15:22 volumio volumio[907]: gain: 0 Aug 15 16:15:22 volumio volumio[907]: eq3: Aug 15 16:15:22 volumio volumio[907]: type: Biquad Aug 15 16:15:22 volumio volumio[907]: parameters: Aug 15 16:15:22 volumio volumio[907]: type: Peaking Aug 15 16:15:22 volumio volumio[907]: freq: 63 Aug 15 16:15:22 volumio volumio[907]: q: 1.85 Aug 15 16:15:22 volumio volumio[907]: gain: 0 Aug 15 16:15:22 volumio volumio[907]: eq4: Aug 15 16:15:22 volumio volumio[907]: type: Biquad Aug 15 16:15:22 volumio volumio[907]: parameters: Aug 15 16:15:22 volumio volumio[907]: type: Peaking Aug 15 16:15:22 volumio volumio[907]: freq: 100 Aug 15 16:15:22 volumio volumio[907]: q: 1.85 Aug 15 16:15:22 volumio volumio[907]: gain: 0 Aug 15 16:15:22 volumio volumio[907]: eq5: Aug 15 16:15:22 volumio volumio[907]: type: Biquad Aug 15 16:15:22 volumio volumio[907]: parameters: Aug 15 16:15:22 volumio volumio[907]: type: Peaking Aug 15 16:15:22 volumio volumio[907]: freq: 160 Aug 15 16:15:22 volumio volumio[907]: q: 1.85 Aug 15 16:15:22 volumio volumio[907]: gain: 0 Aug 15 16:15:22 volumio volumio[907]: eq6: Aug 15 16:15:22 volumio volumio[907]: type: Biquad Aug 15 16:15:22 volumio volumio[907]: parameters: Aug 15 16:15:22 volumio volumio[907]: type: Peaking Aug 15 16:15:22 volumio volumio[907]: freq: 250 Aug 15 16:15:22 volumio volumio[907]: q: 1.85 Aug 15 16:15:22 volumio volumio[907]: gain: 0 Aug 15 16:15:22 volumio volumio[907]: eq7: Aug 15 16:15:22 volumio volumio[907]: type: Biquad Aug 15 16:15:22 volumio volumio[907]: parameters: Aug 15 16:15:22 volumio volumio[907]: type: Peaking Aug 15 16:15:22 volumio volumio[907]: freq: 400 Aug 15 16:15:22 volumio volumio[907]: q: 1.85 Aug 15 16:15:22 volumio volumio[907]: gain: 0 Aug 15 16:15:22 volumio volumio[907]: eq8: Aug 15 16:15:22 volumio volumio[907]: type: Biquad Aug 15 16:15:22 volumio volumio[907]: parameters: Aug 15 16:15:22 volumio volumio[907]: type: Peaking Aug 15 16:15:22 volumio volumio[907]: freq: 630 Aug 15 16:15:22 volumio volumio[907]: q: 1.85 Aug 15 16:15:22 volumio volumio[907]: gain: 0 Aug 15 16:15:22 volumio volumio[907]: eq9: Aug 15 16:15:22 volumio volumio[907]: type: Biquad Aug 15 16:15:22 volumio volumio[907]: parameters: Aug 15 16:15:22 volumio volumio[907]: type: Peaking Aug 15 16:15:22 volumio volumio[907]: freq: 1000 Aug 15 16:15:22 volumio volumio[907]: q: 1.85 Aug 15 16:15:22 volumio volumio[907]: gain: 0 Aug 15 16:15:22 volumio volumio[907]: eq10: Aug 15 16:15:22 volumio volumio[907]: type: Biquad Aug 15 16:15:22 volumio volumio[907]: parameters: Aug 15 16:15:22 volumio volumio[907]: type: Peaking Aug 15 16:15:22 volumio volumio[907]: freq: 1600 Aug 15 16:15:22 volumio volumio[907]: q: 1.85 Aug 15 16:15:22 volumio volumio[907]: gain: 0 Aug 15 16:15:22 volumio volumio[907]: eq11: Aug 15 16:15:22 volumio volumio[907]: type: Biquad Aug 15 16:15:22 volumio volumio[907]: parameters: Aug 15 16:15:22 volumio volumio[907]: type: Peaking Aug 15 16:15:22 volumio volumio[907]: freq: 2500 Aug 15 16:15:22 volumio volumio[907]: q: 1.85 Aug 15 16:15:22 volumio volumio[907]: gain: 0 Aug 15 16:15:22 volumio volumio[907]: eq12: Aug 15 16:15:22 volumio volumio[907]: type: Biquad Aug 15 16:15:22 volumio volumio[907]: parameters: Aug 15 16:15:22 volumio volumio[907]: type: Peaking Aug 15 16:15:22 volumio volumio[907]: freq: 4000 Aug 15 16:15:22 volumio volumio[907]: q: 1.85 Aug 15 16:15:22 volumio volumio[907]: gain: 0 Aug 15 16:15:22 volumio volumio[907]: eq13: Aug 15 16:15:22 volumio volumio[907]: type: Biquad Aug 15 16:15:22 volumio volumio[907]: parameters: Aug 15 16:15:22 volumio volumio[907]: type: Peaking Aug 15 16:15:22 volumio volumio[907]: freq: 6300 Aug 15 16:15:22 volumio volumio[907]: q: 1.85 Aug 15 16:15:22 volumio volumio[907]: gain: 0 Aug 15 16:15:22 volumio volumio[907]: eq14: Aug 15 16:15:22 volumio volumio[907]: type: Biquad Aug 15 16:15:22 volumio volumio[907]: parameters: Aug 15 16:15:22 volumio volumio[907]: type: Peaking Aug 15 16:15:22 volumio volumio[907]: freq: 10000 Aug 15 16:15:22 volumio volumio[907]: q: 1.85 Aug 15 16:15:22 volumio volumio[907]: gain: 0 Aug 15 16:15:22 volumio volumio[907]: eq15: Aug 15 16:15:22 volumio volumio[907]: type: Biquad Aug 15 16:15:22 volumio volumio[907]: parameters: Aug 15 16:15:22 volumio volumio[907]: type: Peaking Aug 15 16:15:22 volumio volumio[907]: freq: 16000 Aug 15 16:15:22 volumio volumio[907]: q: 1.85 Aug 15 16:15:22 volumio volumio[907]: gain: 0 Aug 15 16:15:22 volumio volumio[907]: error: WebSocket error: [object Object] Aug 15 16:15:22 volumio sudo[1145]: pam_unix(sudo:session): session closed for user root Aug 15 16:15:22 volumio volumio[907]: info: EVO SABRE : systemd cmd daemon-reload : success Aug 15 16:15:22 volumio sudo[1212]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart evo_oled2.service Aug 15 16:15:22 volumio sudo[1212]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 15 16:15:22 volumio systemd[1]: Started OLED Display Service for EVO SABRE. Aug 15 16:15:22 volumio systemd[1]: Started FusionDsp Daemon. Aug 15 16:15:22 volumio sudo[1214]: root : TTY=unknown ; PWD=/data/plugins/system_hardware/audiophonics_evo_sabre/apps/evo_oled ; USER=root ; COMMAND=/usr/bin/node /data/plugins/system_hardware/audiophonics_evo_sabre/apps/evo_oled/index.js volumio Aug 15 16:15:22 volumio sudo[1214]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 15 16:15:22 volumio systemd[1]: Started Volumio Kiosk. Aug 15 16:15:22 volumio sudo[1187]: pam_unix(sudo:session): session closed for user root Aug 15 16:15:22 volumio volumio[907]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Aug 15 16:15:22 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 15 16:15:22 volumio volumio[907]: info: touch_display: Volumio Kiosk started Aug 15 16:15:22 volumio volumio[907]: info: CoreCommandRouter::volumioGetState Aug 15 16:15:22 volumio volumio[907]: info: CorePlayQueue::getTrack 0 Aug 15 16:15:22 volumio systemd[1]: Started go-librespot Daemon. Aug 15 16:15:22 volumio go-librespot[1223]: Librespot-go daemon starting... Aug 15 16:15:22 volumio sudo[1148]: pam_unix(sudo:session): session closed for user root Aug 15 16:15:22 volumio volumio[907]: info: CoreCommandRouter::volumioGetState Aug 15 16:15:22 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 15 16:15:22 volumio volumio[907]: info: CorePlayQueue::getTrack 0 Aug 15 16:15:22 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 15 16:15:22 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Aug 15 16:15:22 volumio systemd[1]: mpd.service: Succeeded. Aug 15 16:15:22 volumio systemd[1]: Stopped Music Player Daemon. Aug 15 16:15:22 volumio sudo[1212]: pam_unix(sudo:session): session closed for user root Aug 15 16:15:22 volumio sudo[1209]: pam_unix(sudo:session): session closed for user root Aug 15 16:15:22 volumio volumio[907]: info: EVO SABRE : systemd cmd restart evo_oled2.service : success Aug 15 16:15:22 volumio volumio[907]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Aug 15 16:15:22 volumio sudo[1249]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart evo_remote.service Aug 15 16:15:22 volumio systemd[1]: Starting Music Player Daemon... Aug 15 16:15:22 volumio sudo[1249]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 15 16:15:22 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 15 16:15:22 volumio sudo[1188]: pam_unix(sudo:session): session closed for user root Aug 15 16:15:22 volumio sudo[1162]: pam_unix(sudo:session): session closed for user root Aug 15 16:15:22 volumio sudo[1159]: pam_unix(sudo:session): session closed for user root Aug 15 16:15:22 volumio sudo[1165]: pam_unix(sudo:session): session closed for user root Aug 15 16:15:22 volumio volumio[907]: info: Shairport-Sync Started Aug 15 16:15:22 volumio volumio[907]: Error adding Membership: Error: addMembership EINVAL Aug 15 16:15:22 volumio volumio[907]: info: Shairport-Sync Started Aug 15 16:15:22 volumio volumio[907]: info: Shairport-Sync Started Aug 15 16:15:22 volumio volumio[907]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Aug 15 16:15:22 volumio sudo[1252]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 15 16:15:22 volumio sudo[1252]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 15 16:15:22 volumio systemd[1]: Starting lircd(8) initialization helper tool... Aug 15 16:15:22 volumio sudo[1252]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Aug 15 16:15:22 volumio sudo[1252]: pam_unix(sudo:session): session closed for user root Aug 15 16:15:22 volumio startx[1217]: X.Org X Server 1.20.4 Aug 15 16:15:22 volumio startx[1217]: X Protocol Version 11, Revision 0 Aug 15 16:15:22 volumio startx[1217]: Build Operating System: Linux 5.4.0-109-generic armv8l Raspbian Aug 15 16:15:22 volumio startx[1217]: Current Operating System: Linux volumio 6.1.69-v7+ #1710 SMP Thu Dec 21 13:14:13 GMT 2023 armv7l Aug 15 16:15:22 volumio startx[1217]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 snd_bcm2835.enable_headphones=1 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=800 bcm2708_fb.fbheight=480 bcm2708_fb.fbswap=1 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyS0,115200 console=tty1 imgpart=UUID=65f9b753-7ee7-4306-bce8-cc2a0ef04a3c imgfile=/volumio_current.sqsh bootpart=UUID=C494-84EB datapart=UUID=be44d21a-06bd-421a-b406-4f1062ab648a uuidconfig=cmdline.txt pcie_aspm=off pci=pcie_bus_safe rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd-bcm2835.enable_compat_alsa= snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 nodebug use_kmsg=no Aug 15 16:15:22 volumio startx[1217]: Build Date: 09 November 2022 03:13:10PM Aug 15 16:15:22 volumio startx[1217]: xorg-server 2:1.20.4-1+rpt4+deb10u6 (https://www.debian.org/support) Aug 15 16:15:22 volumio startx[1217]: Current version of pixman: 0.36.0 Aug 15 16:15:22 volumio startx[1217]: Before reporting problems, check http://wiki.x.org Aug 15 16:15:22 volumio startx[1217]: to make sure that you have the latest version. Aug 15 16:15:22 volumio startx[1217]: Markers: (--) probed, (**) from config file, (==) default setting, Aug 15 16:15:22 volumio startx[1217]: (++) from command line, (!!) notice, (II) informational, Aug 15 16:15:22 volumio startx[1217]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Aug 15 16:15:22 volumio startx[1217]: (==) Log file: "/var/log/Xorg.0.log", Time: Thu Aug 15 16:15:22 2024 Aug 15 16:15:22 volumio volumio[907]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Aug 15 16:15:23 volumio startx[1217]: (==) Using config directory: "/etc/X11/xorg.conf.d" Aug 15 16:15:23 volumio startx[1217]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Aug 15 16:15:23 volumio volumio[907]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Aug 15 16:15:23 volumio volumio-remote-updater[651]: [2024-08-15 16:15:23] [connect] Successful connection Aug 15 16:15:23 volumio volumio-remote-updater[651]: [2024-08-15 16:15:23] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1723738523 101 Aug 15 16:15:23 volumio volumio[907]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 6 Aug 15 16:15:23 volumio go-librespot[1223]: time="2024-08-15T16:15:23Z" level=info msg="generated new device id: f1f1a54e2adb20f6b07c157c2dadf227052d20a1" Aug 15 16:15:23 volumio lircd-setup[1264]: /usr/sbin/lircd-setup:37: DeprecationWarning: The SafeConfigParser class has been renamed to ConfigParser in Python 3.2. This alias will be removed in future versions. Use ConfigParser directly instead. Aug 15 16:15:23 volumio lircd-setup[1264]: parser = configparser.SafeConfigParser() Aug 15 16:15:23 volumio systemd[1]: lircd-setup.service: Succeeded. Aug 15 16:15:23 volumio systemd[1]: Started lircd(8) initialization helper tool. Aug 15 16:15:23 volumio systemd[1]: Started evo_remote.service. Aug 15 16:15:23 volumio sudo[1249]: pam_unix(sudo:session): session closed for user root Aug 15 16:15:23 volumio volumio[907]: info: EVO SABRE : systemd cmd restart evo_remote.service : success Aug 15 16:15:23 volumio lircd-0.10.1[1273]: Info: lircd: Opening log, level: Info Aug 15 16:15:23 volumio lircd-0.10.1[1273]: Notice: Version: lircd 0.10.1 Aug 15 16:15:23 volumio lircd-0.10.1[1273]: Notice: System info: Linux volumio 6.1.69-v7+ #1710 SMP Thu Dec 21 13:14:13 GMT 2023 armv7l GNU/Linux Aug 15 16:15:23 volumio sudo[1277]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart evo_irexec.service Aug 15 16:15:23 volumio sudo[1277]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 15 16:15:24 volumio lircd-0.10.1[1273]: Info: Initial device: /dev/lirc0 Aug 15 16:15:24 volumio lircd-0.10.1[1273]: Notice: 'lirc' written to protocols file /sys/class/rc/rc0/protocols Aug 15 16:15:24 volumio lircd-0.10.1[1273]: Info: Initial device: /dev/lirc0 Aug 15 16:15:24 volumio lircd-0.10.1[1273]: Info: [lirc] protocol is enabled Aug 15 16:15:24 volumio lircd-0.10.1[1273]: Notice: Options: driver: default Aug 15 16:15:24 volumio lircd-0.10.1[1273]: Notice: Options: output: /var/run/lirc/lircd Aug 15 16:15:24 volumio lircd[1273]: lircd-0.10.1[1273]: Info: lircd: Opening log, level: Info Aug 15 16:15:24 volumio lircd-0.10.1[1273]: Notice: Options: nodaemon: 1 Aug 15 16:15:24 volumio lircd[1273]: lircd-0.10.1[1273]: Warning: Running as root Aug 15 16:15:24 volumio lircd-0.10.1[1273]: Notice: Options: plugindir: /usr/lib/arm-linux-gnueabihf/lirc/plugins Aug 15 16:15:24 volumio lircd-0.10.1[1273]: Notice: Options: logfile: syslog Aug 15 16:15:24 volumio lircd-0.10.1[1273]: Notice: Options: immediate-init: 0 Aug 15 16:15:24 volumio lircd-0.10.1[1273]: Notice: Options: permission: 666 Aug 15 16:15:24 volumio lircd-0.10.1[1273]: Notice: Options: driver-options: Aug 15 16:15:24 volumio lircd-0.10.1[1273]: Notice: Options: pidfile: /var/run/lirc/lircd.pid Aug 15 16:15:24 volumio lircd-0.10.1[1273]: Notice: Options: listen: 0 Aug 15 16:15:24 volumio lircd-0.10.1[1273]: Notice: Options: connect: (null) Aug 15 16:15:24 volumio lircd-0.10.1[1273]: Notice: Options: userelease: 0 Aug 15 16:15:24 volumio lircd-0.10.1[1273]: Notice: Options: effective_user: (null) Aug 15 16:15:24 volumio lircd-0.10.1[1273]: Notice: Options: release_suffix: _EVUP Aug 15 16:15:24 volumio lircd-0.10.1[1273]: Notice: Options: allow_simulate: 0 Aug 15 16:15:24 volumio lircd-0.10.1[1273]: Notice: Options: repeat_max: 600 Aug 15 16:15:24 volumio lircd-0.10.1[1273]: Notice: Options: configfile: /data/plugins/system_hardware/audiophonics_evo_sabre/apps/lirc/lircd.conf Aug 15 16:15:24 volumio lircd-0.10.1[1273]: Notice: Options: dynamic_codes: (null) Aug 15 16:15:24 volumio lircd-0.10.1[1273]: Notice: Current driver: default Aug 15 16:15:24 volumio lircd-0.10.1[1273]: Notice: Driver API version: 3 Aug 15 16:15:24 volumio lircd[1273]: lircd-0.10.1[1273]: Info: Using remote: ApEvo. Aug 15 16:15:24 volumio lircd-0.10.1[1273]: Notice: Driver version: 0.10.0 Aug 15 16:15:24 volumio lircd-0.10.1[1273]: Notice: Driver info: See file:///usr/share/doc/lirc/plugindocs/default.html Aug 15 16:15:24 volumio lircd[1273]: lircd-0.10.1[1273]: Notice: lircd(default) ready, using /var/run/lirc/lircd Aug 15 16:15:24 volumio lircd-0.10.1[1273]: Info: lircd: Opening log, level: Info Aug 15 16:15:24 volumio lircd-0.10.1[1273]: Warning: Running as root Aug 15 16:15:24 volumio lircd-0.10.1[1273]: Info: Using remote: ApEvo. Aug 15 16:15:24 volumio lircd-0.10.1[1273]: Notice: lircd(default) ready, using /var/run/lirc/lircd Aug 15 16:15:24 volumio systemd[1]: Starting lircd(8) initialization helper tool... Aug 15 16:15:24 volumio go-librespot[1223]: time="2024-08-15T16:15:24Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Aug 15 16:15:24 volumio go-librespot[1223]: time="2024-08-15T16:15:24Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Aug 15 16:15:24 volumio go-librespot[1223]: time="2024-08-15T16:15:24Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Aug 15 16:15:24 volumio go-librespot[1223]: time="2024-08-15T16:15:24Z" level=debug msg="zeroconf server listening on port 33291" Aug 15 16:15:24 volumio lircd-setup[1280]: /usr/sbin/lircd-setup:37: DeprecationWarning: The SafeConfigParser class has been renamed to ConfigParser in Python 3.2. This alias will be removed in future versions. Use ConfigParser directly instead. Aug 15 16:15:24 volumio lircd-setup[1280]: parser = configparser.SafeConfigParser() Aug 15 16:15:24 volumio systemd[1]: lircd-setup.service: Succeeded. Aug 15 16:15:24 volumio systemd[1]: Started lircd(8) initialization helper tool. Aug 15 16:15:24 volumio systemd[1]: Started evo_irexec.service. Aug 15 16:15:24 volumio sudo[1277]: pam_unix(sudo:session): session closed for user root Aug 15 16:15:24 volumio volumio[907]: info: EVO SABRE : systemd cmd restart evo_irexec.service : success Aug 15 16:15:24 volumio lircd[1273]: lircd-0.10.1[1273]: Notice: accepted new client on /var/run/lirc/lircd Aug 15 16:15:24 volumio lircd[1273]: lircd-0.10.1[1273]: Info: [lirc] protocol is enabled Aug 15 16:15:24 volumio lircd-0.10.1[1273]: Notice: accepted new client on /var/run/lirc/lircd Aug 15 16:15:24 volumio lircd-0.10.1[1273]: Info: [lirc] protocol is enabled Aug 15 16:15:25 volumio mpd[1268]: Aug 15 16:15 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 15 16:15:25 volumio volumio[907]: info: go-librespot daemon successfully initialized Aug 15 16:15:25 volumio systemd[1]: Started Music Player Daemon. Aug 15 16:15:25 volumio sudo[1171]: pam_unix(sudo:session): session closed for user root Aug 15 16:15:25 volumio sudo[1090]: pam_unix(sudo:session): session closed for user root Aug 15 16:15:25 volumio sudo[1101]: pam_unix(sudo:session): session closed for user root Aug 15 16:15:25 volumio volumio[907]: info: Completed starting Core Plugins Aug 15 16:15:25 volumio volumio[907]: info: ------------------------------------------- Aug 15 16:15:25 volumio volumio[907]: info: ----- MyVolumio plugins startup ---- Aug 15 16:15:25 volumio volumio[907]: info: ------------------------------------------- Aug 15 16:15:25 volumio volumio[907]: info: [MyVolumio PluginManager] Fetching plans data.... Aug 15 16:15:25 volumio volumio[907]: error: MPD error: The expression evaluated to a falsy value: Aug 15 16:15:25 volumio volumio[907]: assert.ok(self.idling) Aug 15 16:15:25 volumio volumio[907]: error: The expression evaluated to a falsy value: Aug 15 16:15:25 volumio volumio[907]: assert.ok(self.idling) Aug 15 16:15:25 volumio volumio[907]: Upnp client error: Error: This socket has been ended by the other party Aug 15 16:15:25 volumio volumio[907]: Upnp client error: Error: This socket has been ended by the other party Aug 15 16:15:25 volumio volumio[907]: info: MPD running with PID1268 Aug 15 16:15:25 volumio volumio[907]: ,establishing connection Aug 15 16:15:26 volumio volumio[907]: error: MPD error: The expression evaluated to a falsy value: Aug 15 16:15:26 volumio volumio[907]: assert.ok(self.idling) Aug 15 16:15:26 volumio volumio[907]: error: The expression evaluated to a falsy value: Aug 15 16:15:26 volumio volumio[907]: assert.ok(self.idling) Aug 15 16:15:26 volumio volumio[907]: error: MPD error: The expression evaluated to a falsy value: Aug 15 16:15:26 volumio volumio[907]: assert.ok(self.idling) Aug 15 16:15:26 volumio volumio[907]: error: The expression evaluated to a falsy value: Aug 15 16:15:26 volumio volumio[907]: assert.ok(self.idling) Aug 15 16:15:26 volumio volumio[907]: error: updateQueue error: null Aug 15 16:15:26 volumio volumio[907]: info: touch_display: Setting screensaver timeout to 120 seconds. Aug 15 16:15:26 volumio volumio[907]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Aug 15 16:15:26 volumio volumio[907]: info: CoreCommandRouter::volumioGetState Aug 15 16:15:26 volumio volumio[907]: info: CorePlayQueue::getTrack 0 Aug 15 16:15:26 volumio volumio[907]: info: CoreCommandRouter::volumioGetQueue Aug 15 16:15:26 volumio volumio[907]: info: CoreStateMachine::getQueue Aug 15 16:15:26 volumio volumio[907]: info: CorePlayQueue::getQueue Aug 15 16:15:26 volumio volumio[907]: info: CoreCommandRouter::volumioGetState Aug 15 16:15:26 volumio volumio[907]: info: CorePlayQueue::getTrack 0 Aug 15 16:15:27 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Aug 15 16:15:28 volumio volumio[907]: info: Initializing connection to go-librespot Websocket Aug 15 16:15:28 volumio go-librespot[1223]: time="2024-08-15T16:15:28Z" level=debug msg="new websocket client" Aug 15 16:15:28 volumio volumio[907]: info: Connection to go-librespot Websocket established Aug 15 16:15:29 volumio volumio[1086]: Generating RSA private key, 4096 bit long modulus (2 primes) Aug 15 16:15:31 volumio volumio[907]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Aug 15 16:15:31 volumio volumio[907]: info: Getting Spotify volume Aug 15 16:15:32 volumio volumio[907]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Aug 15 16:15:32 volumio volumio[907]: info: CoreCommandRouter::volumioGetState Aug 15 16:15:32 volumio volumio[907]: info: CorePlayQueue::getTrack 0 Aug 15 16:15:33 volumio volumio[907]: SPOTIFY: RECEIVED VOLUMIO VOLUME 0 Aug 15 16:15:33 volumio volumio[907]: SPOTIFY: SPOTIFY VOLUME undefined Aug 15 16:15:33 volumio volumio[907]: SPOTIFY: VOLUMIO VOLUME 0 Aug 15 16:15:33 volumio volumio[907]: info: Aligning Spotify Volume to Volumio Volume Aug 15 16:15:33 volumio volumio[907]: info: CoreCommandRouter::volumioGetState Aug 15 16:15:33 volumio volumio[907]: info: CorePlayQueue::getTrack 0 Aug 15 16:15:33 volumio volumio[907]: info: Setting Spotify Volume from Volumio: 0 Aug 15 16:15:35 volumio volumio[907]: SPOTIFY: SETTING SPOTIFY VOLUME 0 Aug 15 16:15:35 volumio volumio[907]: info: Sending Spotify command with payload to local API: /player/volume Aug 15 16:15:40 volumio volumio[907]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Aug 15 16:15:41 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 15 16:15:42 volumio volumio[907]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Aug 15 16:15:44 volumio volumio[907]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Aug 15 16:15:45 volumio volumio[907]: error: MyVolumio Plugin failed to start in a timely fashion Aug 15 16:15:45 volumio volumio[907]: info: BOOT COMPLETED Aug 15 16:15:45 volumio volumio[907]: [Metrics] CommandRouter: 40s 802.89ms Aug 15 16:15:45 volumio volumio[907]: info: CoreCommandRouter::volumiosetStartupVolume Aug 15 16:15:45 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 15 16:15:45 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 15 16:15:45 volumio volumio[907]: info: CoreCommandRouter::Close All Modals sent Aug 15 16:15:45 volumio volumio[907]: info: CoreCommandRouter::Close All Modals sent Aug 15 16:15:46 volumio volumio[907]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Aug 15 16:15:46 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 15 16:15:46 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Aug 15 16:15:46 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 15 16:15:46 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Aug 15 16:15:46 volumio volumio[907]: info: Version has changed, forcing UI Reload Aug 15 16:15:46 volumio volumio[907]: info: CoreCommandRouter::Reload Ui Aug 15 16:15:47 volumio volumio[907]: info: CoreCommandRouter::volumioGetVisibleSources Aug 15 16:15:47 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 15 16:15:47 volumio volumio[907]: info: CoreCommandRouter::volumioGetState Aug 15 16:15:47 volumio volumio[907]: info: CorePlayQueue::getTrack 0 Aug 15 16:15:47 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 15 16:15:47 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 15 16:15:47 volumio volumio[907]: info: Received Get System Info Aug 15 16:15:47 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 15 16:15:47 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 15 16:15:47 volumio volumio[907]: info: Discovery: Getting this device information Aug 15 16:15:47 volumio volumio[907]: info: CoreCommandRouter::volumioGetState Aug 15 16:15:47 volumio volumio[907]: info: CorePlayQueue::getTrack 0 Aug 15 16:15:47 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 15 16:15:47 volumio volumio[907]: info: CoreCommandRouter::volumioGetState Aug 15 16:15:47 volumio volumio[907]: info: CorePlayQueue::getTrack 0 Aug 15 16:15:47 volumio volumio[907]: info: Listing playlists Aug 15 16:15:47 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 15 16:15:47 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 15 16:15:47 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 15 16:15:47 volumio volumio[907]: info: Received Get System Info Aug 15 16:15:47 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 15 16:15:47 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 15 16:15:47 volumio volumio[907]: info: Discovery: Getting this device information Aug 15 16:15:47 volumio volumio[907]: info: CoreCommandRouter::volumioGetState Aug 15 16:15:47 volumio volumio[907]: info: CorePlayQueue::getTrack 0 Aug 15 16:15:47 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 15 16:15:47 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 15 16:15:47 volumio volumio[907]: info: CoreCommandRouter::volumioGetVisibleSources Aug 15 16:15:47 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 15 16:15:47 volumio volumio[907]: info: CoreCommandRouter::volumioGetState Aug 15 16:15:47 volumio volumio[907]: info: CorePlayQueue::getTrack 0 Aug 15 16:15:47 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 15 16:15:47 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 15 16:15:47 volumio volumio[907]: info: Received Get System Info Aug 15 16:15:47 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 15 16:15:47 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 15 16:15:47 volumio volumio[907]: info: Discovery: Getting this device information Aug 15 16:15:47 volumio volumio[907]: info: CoreCommandRouter::volumioGetState Aug 15 16:15:47 volumio volumio[907]: info: CorePlayQueue::getTrack 0 Aug 15 16:15:47 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 15 16:15:47 volumio volumio[907]: info: CoreCommandRouter::volumioGetState Aug 15 16:15:47 volumio volumio[907]: info: CorePlayQueue::getTrack 0 Aug 15 16:15:47 volumio volumio[907]: info: Listing playlists Aug 15 16:15:48 volumio volumio[907]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Aug 15 16:15:49 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 15 16:15:49 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 15 16:15:49 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 15 16:15:50 volumio volumio[907]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 13 Aug 15 16:15:50 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 15 16:15:50 volumio volumio[907]: info: CoreCommandRouter::volumioGetVisibleSources Aug 15 16:15:50 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 15 16:15:50 volumio volumio[907]: info: CoreCommandRouter::volumioGetState Aug 15 16:15:50 volumio volumio[907]: info: CorePlayQueue::getTrack 0 Aug 15 16:15:50 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 15 16:15:50 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 15 16:15:50 volumio volumio[907]: info: Received Get System Info Aug 15 16:15:50 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 15 16:15:50 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 15 16:15:50 volumio volumio[907]: info: Discovery: Getting this device information Aug 15 16:15:50 volumio volumio[907]: info: CoreCommandRouter::volumioGetState Aug 15 16:15:50 volumio volumio[907]: info: CorePlayQueue::getTrack 0 Aug 15 16:15:50 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 15 16:15:50 volumio volumio[907]: info: CoreCommandRouter::volumioGetState Aug 15 16:15:50 volumio volumio[907]: info: CorePlayQueue::getTrack 0 Aug 15 16:15:50 volumio volumio[907]: info: Listing playlists Aug 15 16:15:50 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 15 16:15:50 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 15 16:15:50 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 15 16:15:50 volumio volumio[907]: info: Received Get System Info Aug 15 16:15:50 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 15 16:15:50 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 15 16:15:50 volumio volumio[907]: info: Discovery: Getting this device information Aug 15 16:15:50 volumio volumio[907]: info: CoreCommandRouter::volumioGetState Aug 15 16:15:50 volumio volumio[907]: info: CorePlayQueue::getTrack 0 Aug 15 16:15:50 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 15 16:15:50 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 15 16:15:50 volumio volumio[907]: info: CoreCommandRouter::volumioGetVisibleSources Aug 15 16:15:50 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 15 16:15:50 volumio volumio[907]: info: CoreCommandRouter::volumioGetState Aug 15 16:15:50 volumio volumio[907]: info: CorePlayQueue::getTrack 0 Aug 15 16:15:50 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 15 16:15:50 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 15 16:15:50 volumio volumio[907]: info: Received Get System Info Aug 15 16:15:50 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 15 16:15:50 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 15 16:15:50 volumio volumio[907]: info: Discovery: Getting this device information Aug 15 16:15:50 volumio volumio[907]: info: CoreCommandRouter::volumioGetState Aug 15 16:15:50 volumio volumio[907]: info: CorePlayQueue::getTrack 0 Aug 15 16:15:50 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 15 16:15:50 volumio volumio[907]: info: CoreCommandRouter::volumioGetState Aug 15 16:15:50 volumio volumio[907]: info: CorePlayQueue::getTrack 0 Aug 15 16:15:50 volumio volumio[907]: info: Listing playlists Aug 15 16:15:50 volumio volumio[907]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 15 16:15:50 volumio volumio[907]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 15 16:15:50 volumio volumio[907]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 15 16:15:50 volumio volumio[907]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 15 16:15:50 volumio volumio[907]: info: effect true Aug 15 16:15:50 volumio volumio[907]: info: Dsp mode set is EQ15 Aug 15 16:15:51 volumio volumio[907]: info: effect true Aug 15 16:15:51 volumio volumio[907]: info: Dsp mode set is EQ15 Aug 15 16:15:51 volumio volumio[907]: info: effect true Aug 15 16:15:51 volumio volumio[907]: info: Dsp mode set is EQ15 Aug 15 16:15:51 volumio volumio[907]: info: effect true Aug 15 16:15:51 volumio volumio[907]: info: Dsp mode set is EQ15 Aug 15 16:15:52 volumio volumio[907]: info: << convolution filters enabled Aug 15 16:15:52 volumio volumio[907]: info: tools file to play :512kMeasSweep_30_to_20000_44k_PCM16_LR_refL.wav Aug 15 16:15:52 volumio volumio[907]: info: tools file to play :512kMeasSweep_30_to_20000_44k_PCM16_L_refL.wav Aug 15 16:15:52 volumio volumio[907]: info: tools file to play :512kMeasSweep_30_to_20000_44k_PCM16_R_refL.wav Aug 15 16:15:52 volumio volumio[907]: info: tools file to play :HouseCurveTestSignal.wav Aug 15 16:15:52 volumio volumio[907]: info: tools file to play :PinkNoise_44k_BOTH.wav Aug 15 16:15:52 volumio volumio[907]: info: tools file to play :PinkNoise_44k_L.wav Aug 15 16:15:52 volumio volumio[907]: info: tools file to play :PinkNoise_44k_R.wav Aug 15 16:15:52 volumio volumio[907]: info: tools file to play :folder.png Aug 15 16:15:52 volumio volumio[907]: info: << convolution filters enabled Aug 15 16:15:52 volumio volumio[907]: info: tools file to play :512kMeasSweep_30_to_20000_44k_PCM16_LR_refL.wav Aug 15 16:15:52 volumio volumio[907]: info: tools file to play :512kMeasSweep_30_to_20000_44k_PCM16_L_refL.wav Aug 15 16:15:52 volumio volumio[907]: info: tools file to play :512kMeasSweep_30_to_20000_44k_PCM16_R_refL.wav Aug 15 16:15:52 volumio volumio[907]: info: tools file to play :HouseCurveTestSignal.wav Aug 15 16:15:52 volumio volumio[907]: info: tools file to play :PinkNoise_44k_BOTH.wav Aug 15 16:15:52 volumio volumio[907]: info: tools file to play :PinkNoise_44k_L.wav Aug 15 16:15:52 volumio volumio[907]: info: tools file to play :PinkNoise_44k_R.wav Aug 15 16:15:52 volumio volumio[907]: info: tools file to play :folder.png Aug 15 16:15:52 volumio volumio[907]: info: << convolution filters enabled Aug 15 16:15:52 volumio volumio[907]: info: tools file to play :512kMeasSweep_30_to_20000_44k_PCM16_LR_refL.wav Aug 15 16:15:52 volumio volumio[907]: info: tools file to play :512kMeasSweep_30_to_20000_44k_PCM16_L_refL.wav Aug 15 16:15:52 volumio volumio[907]: info: tools file to play :512kMeasSweep_30_to_20000_44k_PCM16_R_refL.wav Aug 15 16:15:52 volumio volumio[907]: info: tools file to play :HouseCurveTestSignal.wav Aug 15 16:15:52 volumio volumio[907]: info: tools file to play :PinkNoise_44k_BOTH.wav Aug 15 16:15:52 volumio volumio[907]: info: tools file to play :PinkNoise_44k_L.wav Aug 15 16:15:52 volumio volumio[907]: info: tools file to play :PinkNoise_44k_R.wav Aug 15 16:15:52 volumio volumio[907]: info: tools file to play :folder.png Aug 15 16:15:52 volumio volumio[907]: info: << convolution filters enabled Aug 15 16:15:52 volumio volumio[907]: info: tools file to play :512kMeasSweep_30_to_20000_44k_PCM16_LR_refL.wav Aug 15 16:15:52 volumio volumio[907]: info: tools file to play :512kMeasSweep_30_to_20000_44k_PCM16_L_refL.wav Aug 15 16:15:52 volumio volumio[907]: info: tools file to play :512kMeasSweep_30_to_20000_44k_PCM16_R_refL.wav Aug 15 16:15:52 volumio volumio[907]: info: tools file to play :HouseCurveTestSignal.wav Aug 15 16:15:52 volumio volumio[907]: info: tools file to play :PinkNoise_44k_BOTH.wav Aug 15 16:15:52 volumio volumio[907]: info: tools file to play :PinkNoise_44k_L.wav Aug 15 16:15:52 volumio volumio[907]: info: tools file to play :PinkNoise_44k_R.wav Aug 15 16:15:52 volumio volumio[907]: info: tools file to play :folder.png Aug 15 16:15:52 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Aug 15 16:15:52 volumio volumio[1086]: .........................................................................................................................................................................................................++++ Aug 15 16:15:54 volumio volumio[1086]: ..........++++ Aug 15 16:15:54 volumio volumio[1086]: e is 65537 (0x010001) Aug 15 16:15:54 volumio volumio[1086]: writing RSA key Aug 15 16:15:58 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 15 16:15:59 volumio volumio[907]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 15 16:15:59 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Aug 15 16:15:59 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Aug 15 16:15:59 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Aug 15 16:15:59 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Aug 15 16:16:00 volumio volumio[907]: info: Received Get System Version Aug 15 16:16:00 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 15 16:16:02 volumio ntpd[777]: Soliciting pool server 212.85.158.10 Aug 15 16:16:03 volumio ntpd[777]: Soliciting pool server 162.159.200.123 Aug 15 16:16:03 volumio ntpd[777]: Soliciting pool server 129.151.225.244 Aug 15 16:16:03 volumio ntpd[777]: Soliciting pool server 129.250.35.250 Aug 15 16:16:04 volumio ntpd[777]: Soliciting pool server 146.59.88.209 Aug 15 16:16:04 volumio ntpd[777]: Soliciting pool server 185.254.101.25 Aug 15 16:16:04 volumio ntpd[777]: Soliciting pool server 37.59.63.125 Aug 15 16:16:05 volumio ntpd[777]: Soliciting pool server 82.67.41.119 Aug 15 16:16:05 volumio ntpd[777]: Soliciting pool server 95.81.173.74 Aug 15 16:16:05 volumio ntpd[777]: Soliciting pool server 51.195.104.188 Aug 15 16:16:06 volumio ntpd[777]: Soliciting pool server 92.222.227.225 Aug 15 16:16:06 volumio ntpd[777]: Soliciting pool server 5.39.80.51 Aug 15 16:16:06 volumio ntpd[777]: Soliciting pool server 212.227.232.161 Aug 15 16:16:06 volumio ntpd[777]: Soliciting pool server 162.159.200.1 Aug 15 16:16:06 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 15 16:16:06 volumio volumio[907]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Aug 15 16:16:06 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Aug 15 16:16:06 volumio volumio[907]: info: Received Get System Version Aug 15 16:16:06 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 15 16:16:06 volumio volumio[907]: info: Received Get System Info Aug 15 16:16:06 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 15 16:16:06 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 15 16:16:06 volumio volumio[907]: info: Discovery: Getting this device information Aug 15 16:16:06 volumio volumio[907]: info: CoreCommandRouter::volumioGetState Aug 15 16:16:06 volumio volumio[907]: info: CorePlayQueue::getTrack 0 Aug 15 16:16:06 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 15 16:16:07 volumio ntpd[777]: Soliciting pool server 176.137.36.37 Aug 15 16:16:07 volumio ntpd[777]: Soliciting pool server 2001:41d0:304:200::6803 Aug 15 16:16:07 volumio ntpd[777]: Soliciting pool server 129.250.35.251 Aug 15 16:16:07 volumio volumio[907]: info: ___________ PLUGINS: Run Shutdown Tasks ___________ Aug 15 16:16:07 volumio volumio[907]: info: PLUGIN onShutdown : networkfs Aug 15 16:16:08 volumio sudo[1767]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount -f /mnt/NAS/music Aug 15 16:16:08 volumio sudo[1767]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 15 16:16:08 volumio systemd[1]: mnt-NAS-music.mount: Succeeded. Aug 15 16:16:08 volumio ntpd[777]: Soliciting pool server 82.65.248.56 Aug 15 16:16:08 volumio ntpd[777]: Soliciting pool server 54.36.61.42 Aug 15 16:16:08 volumio sudo[1767]: pam_unix(sudo:session): session closed for user root Aug 15 16:16:08 volumio sudo[1775]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount -f /mnt/NAS/Downloads_Torrent Aug 15 16:16:08 volumio sudo[1775]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 15 16:16:08 volumio systemd[1]: mnt-NAS-Downloads_Torrent.mount: Succeeded. Aug 15 16:16:08 volumio sudo[1775]: pam_unix(sudo:session): session closed for user root Aug 15 16:16:08 volumio volumio[907]: info: PLUGIN onShutdown : audiophonicsonoff Aug 15 16:16:08 volumio volumio[907]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 15 16:16:08 volumio volumio[907]: TypeError: Cannot read property 'writeSync' of undefined Aug 15 16:16:08 volumio volumio[907]: at ControllerAudiophonicsOnOff.onVolumioShutdown (/data/plugins/system_controller/audiophonicsonoff/index.js:43:25) Aug 15 16:16:08 volumio volumio[907]: at PluginManager.onVolumioShutdownPlugin (/volumio/app/pluginmanager.js:638:30) Aug 15 16:16:08 volumio volumio[907]: at HashMap. (/volumio/app/pluginmanager.js:621:31) Aug 15 16:16:08 volumio volumio[907]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Aug 15 16:16:08 volumio volumio[907]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Aug 15 16:16:08 volumio volumio[907]: at PluginManager.onVolumioShutdown (/volumio/app/pluginmanager.js:619:20) Aug 15 16:16:08 volumio volumio[907]: at CoreCommandRouter.shutdown (/volumio/app/index.js:1319:24) Aug 15 16:16:08 volumio volumio[907]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:859:33) Aug 15 16:16:08 volumio volumio[907]: at Socket.emit (events.js:315:20) Aug 15 16:16:08 volumio volumio[907]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Aug 15 16:16:08 volumio volumio[907]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Aug 15 16:16:08 volumio volumio[907]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 15 16:16:09 volumio sudo[1786]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-15 16:15 Aug 15 16:16:09 volumio sudo[1786]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"