-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Wed 2026-08-26 07:49:45 CEST. -- Aug 26 07:49:06 volumio systemd-timedated[1014]: Changed local time to Wed Aug 26 07:49:06 2026 Aug 26 07:49:06 volumio sudo[1012]: pam_unix(sudo:session): session closed for user root Aug 26 07:49:06 volumio volumio-time-update[643]: volumio-time-update-util: System time updated successfully. Aug 26 07:49:06 volumio systemd[1]: Started Volumio Time Update Utility. Aug 26 07:49:06 volumio ntpd[946]: Soliciting pool server 178.18.90.82 Aug 26 07:49:06 volumio ntpd[946]: Soliciting pool server 94.198.159.15 Aug 26 07:49:06 volumio ntpd[946]: Soliciting pool server 192.42.116.10 Aug 26 07:49:06 volumio wireless.js[586]: trying... Aug 26 07:49:06 volumio sudo[1019]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 26 07:49:06 volumio sudo[1019]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 07:49:06 volumio sudo[1019]: pam_unix(sudo:session): session closed for user root Aug 26 07:49:07 volumio nmbd[723]: [2026/08/26 07:49:07.221846, 0] ../lib/util/become_daemon.c:138(daemon_ready) Aug 26 07:49:07 volumio systemd[1]: Started Samba NMB Daemon. Aug 26 07:49:07 volumio nmbd[723]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Aug 26 07:49:07 volumio ntpd[946]: Soliciting pool server 195.72.61.39 Aug 26 07:49:07 volumio ntpd[946]: Soliciting pool server 46.34.3.162 Aug 26 07:49:07 volumio ntpd[946]: Soliciting pool server 87.251.32.228 Aug 26 07:49:07 volumio systemd[1]: Starting Samba Winbind Daemon... Aug 26 07:49:07 volumio ntpd[946]: Soliciting pool server 185.51.192.61 Aug 26 07:49:07 volumio winbindd[1021]: [2026/08/26 07:49:07.458326, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Aug 26 07:49:07 volumio winbindd[1021]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Aug 26 07:49:07 volumio winbindd[1021]: [2026/08/26 07:49:07.466773, 0] ../lib/util/become_daemon.c:138(daemon_ready) Aug 26 07:49:07 volumio systemd[1]: Started Samba Winbind Daemon. Aug 26 07:49:07 volumio winbindd[1021]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Aug 26 07:49:07 volumio systemd[1]: Starting Samba SMB Daemon... Aug 26 07:49:07 volumio smbd[1026]: [2026/08/26 07:49:07.777417, 0] ../lib/util/become_daemon.c:138(daemon_ready) Aug 26 07:49:07 volumio systemd[1]: Started Samba SMB Daemon. Aug 26 07:49:07 volumio smbd[1026]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Aug 26 07:49:07 volumio wireless.js[586]: trying... Aug 26 07:49:07 volumio sudo[1035]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 26 07:49:07 volumio sudo[1035]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 07:49:07 volumio sudo[1035]: pam_unix(sudo:session): session closed for user root Aug 26 07:49:08 volumio ntpd[946]: Soliciting pool server 162.159.200.123 Aug 26 07:49:08 volumio ntpd[946]: Soliciting pool server 194.104.0.153 Aug 26 07:49:08 volumio wireless.js[586]: trying... Aug 26 07:49:08 volumio sudo[1038]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 26 07:49:08 volumio sudo[1038]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 07:49:08 volumio sudo[1038]: pam_unix(sudo:session): session closed for user root Aug 26 07:49:09 volumio ntpd[946]: Soliciting pool server 45.139.163.87 Aug 26 07:49:09 volumio ntpd[946]: Soliciting pool server 103.149.168.181 Aug 26 07:49:09 volumio wireless.js[586]: trying... Aug 26 07:49:09 volumio sudo[1041]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 26 07:49:09 volumio sudo[1041]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 07:49:09 volumio sudo[1041]: pam_unix(sudo:session): session closed for user root Aug 26 07:49:10 volumio ntpd[946]: Soliciting pool server 84.27.222.8 Aug 26 07:49:10 volumio ntpd[946]: Soliciting pool server 2a03:38a0:60:123::1 Aug 26 07:49:10 volumio wireless.js[586]: trying... Aug 26 07:49:10 volumio sudo[1044]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 26 07:49:10 volumio sudo[1044]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 07:49:10 volumio sudo[1044]: pam_unix(sudo:session): session closed for user root Aug 26 07:49:11 volumio wireless.js[586]: trying... Aug 26 07:49:11 volumio sudo[1047]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 26 07:49:11 volumio sudo[1047]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 07:49:11 volumio sudo[1047]: pam_unix(sudo:session): session closed for user root Aug 26 07:49:13 volumio ntpd[946]: receive: Unexpected origin timestamp 0xee38fcd8.4ddbac79 does not match aorg 0000000000.00000000 from server@162.159.200.123 xmt 0xee38fcd9.274fb2ac Aug 26 07:49:13 volumio ntpd[946]: receive: Unexpected origin timestamp 0xee38fcd8.4dddb23e does not match aorg 0000000000.00000000 from server@185.51.192.61 xmt 0xee38fcd9.27435792 Aug 26 07:49:13 volumio ntpd[946]: receive: Unexpected origin timestamp 0xee38fcd8.4dd97499 does not match aorg 0000000000.00000000 from server@45.139.163.87 xmt 0xee38fcd9.26e75d24 Aug 26 07:49:13 volumio ntpd[946]: receive: Unexpected origin timestamp 0xee38fcd8.4dd646fc does not match aorg 0000000000.00000000 from server@103.149.168.181 xmt 0xee38fcd9.27543840 Aug 26 07:49:13 volumio ntpd[946]: receive: Unexpected origin timestamp 0xee38fcd8.4de07700 does not match aorg 0000000000.00000000 from server@94.198.159.11 xmt 0xee38fcd9.27c43d12 Aug 26 07:49:13 volumio ntpd[946]: receive: Unexpected origin timestamp 0xee38fcd8.4ddcbf89 does not match aorg 0000000000.00000000 from server@195.72.61.39 xmt 0xee38fcd9.293be238 Aug 26 07:49:13 volumio volumio-remote-updater[599]: [2026-08-26 07:49:13] [info] asio async_connect error: system:111 (Connection refused) Aug 26 07:49:13 volumio volumio-remote-updater[599]: [2026-08-26 07:49:13] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Aug 26 07:49:13 volumio volumio-remote-updater[599]: [2026-08-26 07:49:13] [error] handle_connect error: Underlying Transport Error Aug 26 07:49:13 volumio wireless.js[586]: trying... Aug 26 07:49:13 volumio sudo[1050]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 26 07:49:13 volumio sudo[1050]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 07:49:13 volumio sudo[1050]: pam_unix(sudo:session): session closed for user root Aug 26 07:49:14 volumio wireless.js[586]: trying... Aug 26 07:49:14 volumio sudo[1053]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 26 07:49:14 volumio sudo[1053]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 07:49:14 volumio sudo[1053]: pam_unix(sudo:session): session closed for user root Aug 26 07:49:15 volumio wireless.js[586]: trying... Aug 26 07:49:15 volumio sudo[1059]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 26 07:49:15 volumio sudo[1059]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 07:49:15 volumio sudo[1059]: pam_unix(sudo:session): session closed for user root Aug 26 07:49:16 volumio wireless.js[586]: trying... Aug 26 07:49:16 volumio sudo[1062]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 26 07:49:16 volumio sudo[1062]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 07:49:16 volumio sudo[1062]: pam_unix(sudo:session): session closed for user root Aug 26 07:49:17 volumio wireless.js[586]: trying... Aug 26 07:49:17 volumio sudo[1065]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 26 07:49:17 volumio sudo[1065]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 07:49:17 volumio sudo[1065]: pam_unix(sudo:session): session closed for user root Aug 26 07:49:18 volumio wireless.js[586]: Overtime, starting plan B Aug 26 07:49:18 volumio wireless.js[586]: STARTING HOTSPOT Aug 26 07:49:18 volumio wpa_supplicant[874]: nl80211: Failed to open /proc/sys/net/ipv4/conf/p2p-dev-wlan0/drop_unicast_in_l2_multicast: No such file or directory Aug 26 07:49:18 volumio wpa_supplicant[874]: nl80211: Failed to set IPv4 unicast in multicast filter Aug 26 07:49:18 volumio wpa_supplicant[874]: nl80211: Failed to open /proc/sys/net/ipv4/conf/p2p-dev-wlan0/drop_unicast_in_l2_multicast: No such file or directory Aug 26 07:49:18 volumio wpa_supplicant[874]: nl80211: Failed to set IPv4 unicast in multicast filter Aug 26 07:49:18 volumio wpa_supplicant[874]: nl80211: deinit ifname=p2p-dev-wlan0 disabled_11b_rates=0 Aug 26 07:49:18 volumio wpa_supplicant[874]: p2p-dev-wlan0: CTRL-EVENT-TERMINATING Aug 26 07:49:19 volumio wpa_supplicant[874]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Aug 26 07:49:19 volumio systemd[1]: systemd-fsckd.service: Succeeded. Aug 26 07:49:19 volumio wpa_supplicant[874]: wlan0: CTRL-EVENT-TERMINATING Aug 26 07:49:21 volumio sudo[1074]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Aug 26 07:49:21 volumio sudo[1074]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 07:49:21 volumio sudo[1074]: pam_unix(sudo:session): session closed for user root Aug 26 07:49:21 volumio sudo[1076]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Aug 26 07:49:21 volumio sudo[1076]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 07:49:21 volumio sudo[1076]: pam_unix(sudo:session): session closed for user root Aug 26 07:49:21 volumio wireless.js[586]: Hotspot is disabled, not starting it Aug 26 07:49:21 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Aug 26 07:49:22 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Aug 26 07:49:22 volumio systemd[1]: Started Wireless Services. Aug 26 07:49:22 volumio systemd[1]: Started Volumio Backend Module. Aug 26 07:49:22 volumio systemd[1]: Started Volumio Cpu Tweaker. Aug 26 07:49:22 volumio systemd[1]: Reached target Multi-User System. Aug 26 07:49:22 volumio systemd[1]: Reached target Graphical Interface. Aug 26 07:49:22 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Aug 26 07:49:22 volumio volumio-cpu-tweak[1083]: Setting RT Priority for mpd Aug 26 07:49:22 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Aug 26 07:49:22 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Aug 26 07:49:22 volumio systemd[1]: Startup finished in 12.197s (kernel) + 37.392s (userspace) = 49.590s. Aug 26 07:49:22 volumio volumio-cpu-tweak[1083]: Setting MPD Affinity Aug 26 07:49:22 volumio volumio-cpu-tweak[1083]: pid 783's current affinity mask: f Aug 26 07:49:22 volumio volumio-cpu-tweak[1083]: pid 783's new affinity mask: 3 Aug 26 07:49:22 volumio volumio-cpu-tweak[1083]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Aug 26 07:49:22 volumio volumio-cpu-tweak[1083]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Aug 26 07:49:22 volumio systemd[1]: volumio_cpu_tweak.service: Succeeded. Aug 26 07:49:22 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Aug 26 07:49:23 volumio volumio[1082]: info: ------------------------------------------- Aug 26 07:49:23 volumio volumio[1082]: info: ----- Volumio3 ---- Aug 26 07:49:23 volumio volumio[1082]: info: ------------------------------------------- Aug 26 07:49:23 volumio volumio[1082]: info: ----- System startup ---- Aug 26 07:49:23 volumio volumio[1082]: info: ------------------------------------------- Aug 26 07:49:23 volumio volumio-remote-updater[599]: [2026-08-26 07:49:23] [connect] Successful connection Aug 26 07:49:24 volumio volumio[1082]: info: MYVOLUMIO Environment detected Aug 26 07:49:24 volumio volumio[1082]: info: Plugin folders cleanup Aug 26 07:49:24 volumio volumio[1082]: info: Scanning into folder /volumio/app/plugins/ Aug 26 07:49:24 volumio volumio[1082]: info: Scanning category audio_interface Aug 26 07:49:24 volumio volumio[1082]: info: Scanning category miscellanea Aug 26 07:49:24 volumio volumio[1082]: info: Scanning category music_service Aug 26 07:49:24 volumio volumio[1082]: info: Scanning category plugins.json Aug 26 07:49:24 volumio volumio[1082]: info: Scanning category system_controller Aug 26 07:49:24 volumio volumio[1082]: info: Scanning category user_interface Aug 26 07:49:24 volumio volumio[1082]: info: Scanning into folder /data/plugins/ Aug 26 07:49:24 volumio volumio[1082]: info: Scanning category audio_interface Aug 26 07:49:24 volumio volumio[1082]: info: Scanning category music_service Aug 26 07:49:24 volumio volumio[1082]: info: Scanning category system_controller Aug 26 07:49:24 volumio volumio[1082]: info: Scanning category system_hardware Aug 26 07:49:24 volumio volumio[1082]: info: Scanning category user_interface Aug 26 07:49:24 volumio volumio[1082]: info: Plugin folders cleanup completed Aug 26 07:49:24 volumio volumio[1082]: info: ------------------------------------------- Aug 26 07:49:24 volumio volumio[1082]: info: ----- Core plugins startup ---- Aug 26 07:49:24 volumio volumio[1082]: info: ------------------------------------------- Aug 26 07:49:24 volumio volumio[1082]: info: Loading plugins from folder /volumio/app/plugins/ Aug 26 07:49:24 volumio volumio[1082]: info: Adding plugin upnp to MyMusic Plugins Aug 26 07:49:24 volumio volumio[1082]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 26 07:49:24 volumio volumio[1082]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 26 07:49:24 volumio volumio[1082]: info: Loading plugins from folder /data/plugins/ Aug 26 07:49:24 volumio volumio[1082]: info: Loading plugin "system"... Aug 26 07:49:24 volumio volumio[1082]: info: Loading plugin "ampswitch"... Aug 26 07:49:24 volumio volumio[1082]: info: Applying required configuration parameters for plugin ampswitch Aug 26 07:49:24 volumio volumio[1082]: info: Loading plugin "appearance"... Aug 26 07:49:25 volumio volumio[1082]: info: Loading plugin "network"... Aug 26 07:49:25 volumio volumio[1082]: info: Refreshing Cached IP Addresses Aug 26 07:49:25 volumio sudo[1119]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 26 07:49:25 volumio sudo[1119]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 07:49:25 volumio sudo[1119]: pam_unix(sudo:session): session closed for user root Aug 26 07:49:25 volumio sudo[1121]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 26 07:49:25 volumio sudo[1121]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 07:49:25 volumio volumio[1082]: info: Loading plugin "services"... Aug 26 07:49:25 volumio sudo[1121]: pam_unix(sudo:session): session closed for user root Aug 26 07:49:25 volumio volumio[1082]: info: Loading plugin "alsa_controller"... Aug 26 07:49:25 volumio sudo[1130]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 26 07:49:25 volumio sudo[1130]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 07:49:25 volumio volumio[1082]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 26 07:49:25 volumio volumio[1082]: info: Loading plugin "wizard"... Aug 26 07:49:25 volumio volumio[1082]: info: Loading plugin "networkfs"... Aug 26 07:49:25 volumio volumio[1082]: info: Starting Udev Watcher for removable devices Aug 26 07:49:25 volumio sudo[1147]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=muz,password=192.168.73.15,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.73.15/music /mnt/NAS/Muziek Aug 26 07:49:25 volumio sudo[1147]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 07:49:25 volumio volumio[1082]: info: Ignoring mount for partition: boot Aug 26 07:49:25 volumio volumio[1082]: info: Ignoring mount for partition: volumio Aug 26 07:49:25 volumio volumio[1082]: info: Ignoring mount for partition: volumio_data Aug 26 07:49:25 volumio volumio[1082]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 26 07:49:25 volumio volumio[1082]: info: Loading plugin "volumio_command_line_client"... Aug 26 07:49:25 volumio volumio[1082]: info: Loading plugin "upnp"... Aug 26 07:49:25 volumio volumio[1082]: info: [1787723365653] Starting Upmpd Daemon Aug 26 07:49:25 volumio volumio[1082]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 26 07:49:25 volumio volumio[1082]: info: Loading plugin "my_music"... Aug 26 07:49:25 volumio volumio[1082]: info: Loading plugin "mpd"... Aug 26 07:49:25 volumio kernel: Key type cifs.spnego registered Aug 26 07:49:25 volumio kernel: Key type cifs.idmap registered Aug 26 07:49:25 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 26 07:49:25 volumio kernel: CIFS: Attempting to mount //192.168.73.15/music Aug 26 07:49:25 volumio kernel: cryptd: max_cpu_qlen set to 1000 Aug 26 07:49:26 volumio sudo[1147]: pam_unix(sudo:session): session closed for user root Aug 26 07:49:26 volumio kernel: CIFS: Status code returned 0xc000006d STATUS_LOGON_FAILURE Aug 26 07:49:26 volumio kernel: CIFS: VFS: \\192.168.73.15 Send error in SessSetup = -13 Aug 26 07:49:26 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -13 Aug 26 07:49:26 volumio volumio[1082]: info: Loading plugin "upnp_browser"... Aug 26 07:49:26 volumio volumio[1082]: info: Loading plugin "alarm-clock"... Aug 26 07:49:27 volumio volumio[1082]: info: Loading plugin "airplay_emulation"... Aug 26 07:49:27 volumio volumio[1082]: info: Starting Shairport Sync Aug 26 07:49:27 volumio kernel: hwmon hwmon1: Undervoltage detected! Aug 26 07:49:27 volumio volumio[1082]: info: Loading plugin "last_100"... Aug 26 07:49:27 volumio volumio[1082]: info: Loading plugin "webradio"... Aug 26 07:49:27 volumio volumio[1082]: info: Loading plugin "i2s_dacs"... Aug 26 07:49:27 volumio volumio[1082]: info: Loading plugin "volumiodiscovery"... Aug 26 07:49:27 volumio volumio[1082]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 26 07:49:27 volumio volumio[1082]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 26 07:49:27 volumio volumio[1082]: *** WARNING *** For more information see Aug 26 07:49:27 volumio node[1082]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 26 07:49:27 volumio volumio[1082]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 26 07:49:27 volumio volumio[1082]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 26 07:49:27 volumio volumio[1082]: *** WARNING *** For more information see Aug 26 07:49:27 volumio node[1082]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 26 07:49:27 volumio node[1082]: *** WARNING *** For more information see Aug 26 07:49:27 volumio node[1082]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 26 07:49:27 volumio node[1082]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 26 07:49:27 volumio node[1082]: *** WARNING *** For more information see Aug 26 07:49:27 volumio volumio[1082]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 26 07:49:27 volumio volumio[1082]: info: Discovery: Started advertising with name: Volumio Aug 26 07:49:27 volumio volumio[1082]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 26 07:49:27 volumio volumio[1082]: info: Loading plugin "spop"... Aug 26 07:49:28 volumio sudo[1130]: pam_unix(sudo:session): session closed for user root Aug 26 07:49:29 volumio volumio[1082]: info: Loading plugin "outputs"... Aug 26 07:49:29 volumio volumio[1082]: info: Loading plugin "albumart"... Aug 26 07:49:29 volumio volumio[1082]: info: Plugin example_plugin is not enabled Aug 26 07:49:29 volumio volumio[1082]: info: Loading plugin "inputs"... Aug 26 07:49:29 volumio volumio[1082]: info: Loading plugin "updater_comm"... Aug 26 07:49:29 volumio volumio[1082]: info: Plugin mpdemulation is not enabled Aug 26 07:49:29 volumio volumio[1082]: info: Loading plugin "rest_api"... Aug 26 07:49:29 volumio volumio[1082]: info: Loading plugin "websocket"... Aug 26 07:49:29 volumio volumio[1082]: info: Starting Socket.io Server version 2.3.0 Aug 26 07:49:29 volumio volumio[1082]: info: Loading plugin "fusiondsp"... Aug 26 07:49:29 volumio volumio[1082]: Forking 3 albumart workers Aug 26 07:49:29 volumio volumio[1082]: info: Applying required configuration parameters for plugin fusiondsp Aug 26 07:49:29 volumio volumio[1082]: info: Loading plugin "mpdoutput"... Aug 26 07:49:29 volumio volumio[1082]: info: Loading plugin "podcast"... Aug 26 07:49:29 volumio volumio[1082]: Starting albumart workers Aug 26 07:49:29 volumio volumio[1082]: Starting albumart workers Aug 26 07:49:30 volumio volumio[1082]: Starting albumart workers Aug 26 07:49:30 volumio volumio[1082]: info: ControllerPodcast::constructor Aug 26 07:49:30 volumio volumio[1082]: info: Loading plugin "rotaryencoder2"... Aug 26 07:49:30 volumio volumio[1082]: info: Loading plugin "Systeminfo"... Aug 26 07:49:30 volumio volumio[1082]: info: Loading plugin "ferrum_streaming_control_technology"... Aug 26 07:49:31 volumio volumio[1082]: info: Applying required configuration parameters for plugin ferrum_streaming_control_technology Aug 26 07:49:31 volumio volumio[1082]: info: Loading plugin "peppymeterbasic"... Aug 26 07:49:31 volumio volumio[1082]: info: Loading i18n strings for locale nl Aug 26 07:49:31 volumio volumio[1082]: info: Plugin peppymeterbasic has duplicated i18n key SAVE. It is ignored. Aug 26 07:49:31 volumio volumio[1082]: info: Plugin peppymeterbasic has duplicated i18n key CLICK_TO_UPDATE_LIST. It is ignored. Aug 26 07:49:31 volumio volumio[1082]: info: Plugin peppymeterbasic has duplicated i18n key LIST_SUCCESS_UPDATED. It is ignored. Aug 26 07:49:31 volumio volumio[1082]: Updating browse sources language Aug 26 07:49:31 volumio volumio[1082]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 26 07:49:31 volumio kernel: hwmon hwmon1: Voltage normalised Aug 26 07:49:31 volumio volumio[1082]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 07:49:31 volumio volumio[1082]: info: CoreCommandRouter::initPlayerControls Aug 26 07:49:31 volumio volumio[1082]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 07:49:31 volumio volumio[1082]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 07:49:31 volumio volumio[1082]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 07:49:31 volumio volumio[1082]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 07:49:31 volumio volumio[1082]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 07:49:31 volumio volumio[1082]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 07:49:31 volumio volumio[1082]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 07:49:31 volumio volumio[1082]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 07:49:31 volumio volumio[1082]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 07:49:31 volumio volumio[1082]: Express server listening on port 3000 Aug 26 07:49:31 volumio volumio[1082]: [Metrics] WebUI: 8s 513.60ms Aug 26 07:49:31 volumio volumio[1082]: info: CoreStateMachine::resetVolumioState Aug 26 07:49:31 volumio volumio[1082]: info: CoreStateMachine::getcurrentVolume Aug 26 07:49:31 volumio volumio[1082]: info: CoreCommandRouter::volumioRetrievevolume Aug 26 07:49:31 volumio volumio[1082]: info: Volumio Network Manager: Network status updated: 1 Aug 26 07:49:31 volumio volumio[1082]: info: Cannot mount NAS Muziek at system boot, trial number 1 ,retrying in 5 seconds Aug 26 07:49:31 volumio volumio[1082]: info: CoreCommandRouter::volumioGetState Aug 26 07:49:31 volumio volumio[1082]: info: CorePlayQueue::getTrack 0 Aug 26 07:49:31 volumio volumio[1082]: 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 26 07:49:31 volumio volumio[1082]: info: Reloading queue from file Aug 26 07:49:31 volumio volumio[1082]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 07:49:31 volumio volumio[1082]: info: CoreStateMachine::setRepeat null single undefined Aug 26 07:49:31 volumio volumio[1082]: info: CoreStateMachine::pushState Aug 26 07:49:31 volumio volumio[1082]: info: CorePlayQueue::getTrack 0 Aug 26 07:49:31 volumio volumio[1082]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 07:49:31 volumio volumio[1082]: info: CoreCommandRouter::volumioPushState Aug 26 07:49:31 volumio volumio[1082]: info: CorePlayQueue::getTrack 0 Aug 26 07:49:31 volumio volumio[1082]: info: Setting Device type: Raspberry PI Aug 26 07:49:31 volumio volumio[1082]: info: Listing playlists Aug 26 07:49:31 volumio volumio[1082]: info: Listing playlists Aug 26 07:49:31 volumio volumio[1082]: info: Completed loading Core Plugins Aug 26 07:49:31 volumio volumio[1082]: info: Preparing to generate the ALSA configuration file Aug 26 07:49:31 volumio volumio[1082]: info: VolumeController:: Volume=40 Mute =false Aug 26 07:49:31 volumio volumio[1082]: info: CoreStateMachine::pushState Aug 26 07:49:31 volumio volumio[1082]: info: CorePlayQueue::getTrack 0 Aug 26 07:49:31 volumio volumio[1082]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 07:49:31 volumio volumio[1082]: info: CoreCommandRouter::volumioPushState Aug 26 07:49:31 volumio volumio[1082]: info: CoreStateMachine::updateTrackBlock Aug 26 07:49:31 volumio volumio[1082]: info: CorePlayQueue::getTrackBlock Aug 26 07:49:31 volumio volumio[1082]: info: CoreCommandRouter::volumioRetrievevolume Aug 26 07:49:31 volumio volumio[1082]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Aug 26 07:49:31 volumio volumio[1082]: info: USB Boot Capable - System SBC Revision found in cpuinfo: a03111 Aug 26 07:49:31 volumio volumio[1082]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Aug 26 07:49:31 volumio volumio[1082]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Aug 26 07:49:31 volumio volumio[1082]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Aug 26 07:49:31 volumio volumio[1082]: info: Reading ALSA contributions from plugins. Aug 26 07:49:31 volumio volumio[1082]: info: Discovery: adding edc67390-17ef-48bf-8447-49516c8af0f5 Aug 26 07:49:31 volumio volumio[1082]: info: Discovery: Found device Volumio Aug 26 07:49:31 volumio volumio[1082]: info: CoreCommandRouter::volumioGetState Aug 26 07:49:31 volumio volumio[1082]: info: CorePlayQueue::getTrack 0 Aug 26 07:49:31 volumio volumio[1082]: info: VolumeController:: Volume=40 Mute =false Aug 26 07:49:31 volumio volumio[1082]: info: CoreStateMachine::pushState Aug 26 07:49:31 volumio volumio[1082]: info: CorePlayQueue::getTrack 0 Aug 26 07:49:31 volumio volumio[1082]: info: CoreCommandRouter::volumioPushState Aug 26 07:49:31 volumio volumio[1082]: info: Asound.conf file unchanged, so no further update is needed Aug 26 07:49:31 volumio volumio[1082]: info: Output device has changed, restarting MPD Aug 26 07:49:31 volumio volumio[1082]: info: Output device has changed, restarting Shairport Sync Aug 26 07:49:31 volumio sudo[1256]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 26 07:49:31 volumio volumio[1082]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 07:49:31 volumio sudo[1256]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 07:49:31 volumio volumio[1082]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 07:49:31 volumio sudo[1256]: pam_unix(sudo:session): session closed for user root Aug 26 07:49:31 volumio sudo[1258]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 26 07:49:31 volumio sudo[1258]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 07:49:31 volumio systemd[1]: Stopping Music Player Daemon... Aug 26 07:49:31 volumio volumio[1082]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 26 07:49:31 volumio volumio[1082]: info: ___________ START PLUGINS ___________ Aug 26 07:49:31 volumio volumio[1082]: info: ControllerMpd::onStart: Initializing MPD Aug 26 07:49:31 volumio volumio[1082]: info: Creating MPD Configuration file Aug 26 07:49:31 volumio volumio[1082]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 07:49:31 volumio sudo[1276]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 26 07:49:31 volumio sudo[1276]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 07:49:31 volumio volumio[1082]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 26 07:49:31 volumio volumio[1082]: info: [1787723371954] CoreMusicLibrary::Adding element Media Servers Aug 26 07:49:31 volumio volumio[1082]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 26 07:49:31 volumio sudo[1276]: pam_unix(sudo:session): session closed for user root Aug 26 07:49:31 volumio systemd[1]: mpd.service: Succeeded. Aug 26 07:49:31 volumio systemd[1]: Stopped Music Player Daemon. Aug 26 07:49:31 volumio sudo[1278]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 26 07:49:31 volumio sudo[1278]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 07:49:32 volumio volumio[1082]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 07:49:32 volumio volumio[1082]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 07:49:32 volumio volumio[1082]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 26 07:49:32 volumio volumio[1082]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 07:49:32 volumio volumio[1082]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 07:49:32 volumio systemd[1]: Starting Music Player Daemon... Aug 26 07:49:32 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Aug 26 07:49:32 volumio systemd[1]: mpd.service: Succeeded. Aug 26 07:49:32 volumio systemd[1]: Stopped Music Player Daemon. Aug 26 07:49:32 volumio systemd[1]: Starting Music Player Daemon... Aug 26 07:49:32 volumio volumio[1082]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 26 07:49:32 volumio volumio[1082]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 26 07:49:32 volumio volumio[1082]: info: [1787723372034] CoreMusicLibrary::Adding element Last_100 Aug 26 07:49:32 volumio volumio[1082]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 26 07:49:32 volumio volumio[1082]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 26 07:49:32 volumio volumio[1082]: info: [1787723372036] CoreMusicLibrary::Adding element Webradio Aug 26 07:49:32 volumio volumio[1082]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 26 07:49:32 volumio volumio[1082]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 26 07:49:32 volumio volumio[1082]: info: Initializing BBC Radios Aug 26 07:49:32 volumio sudo[1288]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 26 07:49:32 volumio volumio[1082]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 26 07:49:32 volumio sudo[1288]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 07:49:32 volumio volumio[1082]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 07:49:32 volumio sudo[1288]: pam_unix(sudo:session): session closed for user root Aug 26 07:49:32 volumio volumio[1082]: info: Creating Spotify config file Aug 26 07:49:32 volumio volumio[1082]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 07:49:32 volumio volumio[1082]: info: Loading i18n strings for locale nl Aug 26 07:49:32 volumio volumio[1082]: info: Plugin peppymeterbasic has duplicated i18n key SAVE. It is ignored. Aug 26 07:49:32 volumio volumio[1082]: info: Plugin peppymeterbasic has duplicated i18n key CLICK_TO_UPDATE_LIST. It is ignored. Aug 26 07:49:32 volumio volumio[1082]: info: Plugin peppymeterbasic has duplicated i18n key LIST_SUCCESS_UPDATED. It is ignored. Aug 26 07:49:32 volumio volumio[1082]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Aug 26 07:49:32 volumio volumio[1082]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 07:49:32 volumio volumio[1082]: info: FusionDsp - mixtype--------------------- Hardware Aug 26 07:49:32 volumio volumio[1082]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 26 07:49:32 volumio volumio[1082]: info: [1787723372163] CoreMusicLibrary::Adding element Podcast Aug 26 07:49:32 volumio volumio[1082]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 26 07:49:32 volumio volumio[1082]: Cannot find translation for source Podcast Aug 26 07:49:32 volumio fsct-plugin[1082]: Failed to initialize device 04e8:4001: IO error -> Permission denied (os error 13) Aug 26 07:49:32 volumio fsct-plugin[1082]: Failed to initialize device 1d6b:0002: BOS descriptor not available, unsupported usb version 2.00 Aug 26 07:49:32 volumio fsct-plugin[1082]: Failed to initialize device 2109:3431: IO error -> Permission denied (os error 13) Aug 26 07:49:32 volumio fsct-plugin[1082]: Failed to initialize device 1d6b:0003: IO error -> Permission denied (os error 13) Aug 26 07:49:32 volumio sudo[1302]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Aug 26 07:49:32 volumio sudo[1302]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 07:49:32 volumio volumio[1082]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Aug 26 07:49:32 volumio volumio[1082]: info: Volumio Calling Home Aug 26 07:49:32 volumio sudo[1302]: pam_unix(sudo:session): session closed for user root Aug 26 07:49:32 volumio volumio[1082]: info: Preparing to generate the ALSA configuration file Aug 26 07:49:32 volumio volumio[1082]: info: [ASDebug] CurState: stop PrevState: na Aug 26 07:49:32 volumio volumio[1082]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Aug 26 07:49:32 volumio volumio[1082]: info: [ASDebug] CurState: stop PrevState: na Aug 26 07:49:32 volumio volumio[1082]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Aug 26 07:49:32 volumio volumio[1082]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Aug 26 07:49:32 volumio volumio[1082]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Aug 26 07:49:32 volumio volumio[1082]: info: Reading ALSA contributions from plugins. Aug 26 07:49:32 volumio volumio[1082]: info: CoreCommandRouter::volumioGetState Aug 26 07:49:32 volumio volumio[1082]: info: CorePlayQueue::getTrack 0 Aug 26 07:49:32 volumio volumio[1082]: info: MPD Permissions set Aug 26 07:49:32 volumio volumio[1082]: info: CoreCommandRouter::volumioGetState Aug 26 07:49:32 volumio volumio[1082]: info: CorePlayQueue::getTrack 0 Aug 26 07:49:32 volumio volumio[1082]: info: FSCT Started Aug 26 07:49:32 volumio sudo[1309]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Aug 26 07:49:32 volumio sudo[1309]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 07:49:32 volumio sudo[1309]: pam_unix(sudo:session): session closed for user root Aug 26 07:49:32 volumio sudo[1312]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 26 07:49:32 volumio sudo[1312]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 07:49:32 volumio volumio[1082]: info: [ASDebug] CurState: stop PrevState: na Aug 26 07:49:32 volumio volumio[1082]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Aug 26 07:49:32 volumio sudo[1312]: pam_unix(sudo:session): session closed for user root Aug 26 07:49:32 volumio sudo[1314]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 26 07:49:32 volumio sudo[1314]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 07:49:32 volumio sudo[1314]: pam_unix(sudo:session): session closed for user root Aug 26 07:49:32 volumio volumio[1082]: info: Volumio called home Aug 26 07:49:32 volumio volumio[1082]: info: Spotify config file written Aug 26 07:49:32 volumio volumio[1082]: 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 26 07:49:32 volumio volumio[1082]: 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 26 07:49:32 volumio sudo[1318]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Aug 26 07:49:32 volumio sudo[1318]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 07:49:32 volumio volumio[1082]: 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 26 07:49:32 volumio volumio[1082]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 07:49:32 volumio volumio[1082]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 07:49:32 volumio volumio[1082]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 07:49:32 volumio volumio[1082]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 07:49:32 volumio volumio[1082]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 07:49:32 volumio volumio[1082]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 07:49:32 volumio volumio[1082]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 07:49:32 volumio sudo[1321]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Aug 26 07:49:32 volumio sudo[1321]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 07:49:32 volumio systemd[1]: Started go-librespot Daemon. Aug 26 07:49:32 volumio volumio[1082]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 07:49:32 volumio volumio[1082]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 07:49:32 volumio volumio[1082]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 07:49:32 volumio volumio[1082]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 07:49:32 volumio volumio[1082]: info: No need to fix Spotify hosts Aug 26 07:49:32 volumio sudo[1321]: pam_unix(sudo:session): session closed for user root Aug 26 07:49:32 volumio go-librespot[1322]: go-librespot daemon starting... Aug 26 07:49:32 volumio sudo[1318]: pam_unix(sudo:session): session closed for user root Aug 26 07:49:32 volumio go-librespot[1322]: time="2026-08-26T07:49:32+02:00" level=info msg="running go-librespot 0.4.0" Aug 26 07:49:32 volumio go-librespot[1322]: time="2026-08-26T07:49:32+02:00" level=debug msg="app state loaded" Aug 26 07:49:32 volumio go-librespot[1322]: time="2026-08-26T07:49:32+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 26 07:49:32 volumio go-librespot[1322]: time="2026-08-26T07:49:32+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 26 07:49:32 volumio go-librespot[1322]: time="2026-08-26T07:49:32+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 26 07:49:32 volumio go-librespot[1322]: time="2026-08-26T07:49:32+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 26 07:49:32 volumio go-librespot[1322]: time="2026-08-26T07:49:32+02:00" level=info msg="zeroconf server listening on port 36039" Aug 26 07:49:32 volumio volumio[1082]: verbose: New Socket.io Connection to 192.168.73.20 from 192.168.73.184 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:154.0) Gecko/20100101 Firefox/154.0 Engine version: 3 Transport: polling Total Clients: 5 Aug 26 07:49:32 volumio mpd[1300]: Aug 26 07:49 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 26 07:49:32 volumio systemd[1]: Started Music Player Daemon. Aug 26 07:49:32 volumio sudo[1258]: pam_unix(sudo:session): session closed for user root Aug 26 07:49:32 volumio sudo[1278]: pam_unix(sudo:session): session closed for user root Aug 26 07:49:32 volumio volumio[1082]: info: Starting Shairport Sync Aug 26 07:49:32 volumio volumio[1082]: info: Starting Shairport Sync Aug 26 07:49:32 volumio volumio[1082]: info: Starting Shairport Sync Aug 26 07:49:32 volumio sudo[1345]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 26 07:49:32 volumio sudo[1345]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 07:49:32 volumio sudo[1347]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 26 07:49:32 volumio sudo[1347]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 07:49:32 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 26 07:49:32 volumio volumio[1082]: info: An error occurred while refreshing Spotify Token Error: Bad Request Aug 26 07:49:32 volumio sudo[1352]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 26 07:49:33 volumio sudo[1352]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 07:49:33 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 26 07:49:33 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 26 07:49:33 volumio volumio[1082]: info: CoreCommandRouter::volumioGetState Aug 26 07:49:33 volumio volumio[1082]: info: CorePlayQueue::getTrack 0 Aug 26 07:49:33 volumio volumio[1082]: info: CoreCommandRouter::volumioGetState Aug 26 07:49:33 volumio volumio[1082]: info: CorePlayQueue::getTrack 0 Aug 26 07:49:33 volumio volumio[1082]: info: CoreCommandRouter::volumioGetState Aug 26 07:49:33 volumio volumio[1082]: info: CorePlayQueue::getTrack 0 Aug 26 07:49:33 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 26 07:49:33 volumio sudo[1345]: pam_unix(sudo:session): session closed for user root Aug 26 07:49:33 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 26 07:49:33 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Aug 26 07:49:33 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 26 07:49:33 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 26 07:49:33 volumio volumio[1082]: info: Completed starting Core Plugins Aug 26 07:49:33 volumio volumio[1082]: info: ------------------------------------------- Aug 26 07:49:33 volumio volumio[1082]: info: ----- MyVolumio plugins startup ---- Aug 26 07:49:33 volumio volumio[1082]: info: ------------------------------------------- Aug 26 07:49:33 volumio volumio[1082]: info: [MyVolumio PluginManager] Fetching plans data.... Aug 26 07:49:33 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 26 07:49:33 volumio sudo[1347]: pam_unix(sudo:session): session closed for user root Aug 26 07:49:33 volumio sudo[1352]: pam_unix(sudo:session): session closed for user root Aug 26 07:49:33 volumio volumio[1082]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 26 07:49:33 volumio volumio[1082]: info: CoreCommandRouter::volumioGetVisibleSources Aug 26 07:49:33 volumio volumio[1082]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 26 07:49:33 volumio volumio[1082]: info: Listing playlists Aug 26 07:49:33 volumio volumio[1082]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 26 07:49:33 volumio volumio[1082]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 26 07:49:33 volumio volumio[1082]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 26 07:49:33 volumio volumio[1082]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 26 07:49:33 volumio volumio[1082]: info: Shairport-Sync Started Aug 26 07:49:33 volumio volumio[1082]: Error adding Membership: Error: addMembership EINVAL Aug 26 07:49:33 volumio volumio[1082]: info: [ASDebug] Togle GPIO: OFF Aug 26 07:49:33 volumio volumio[1082]: info: Asound.conf file unchanged, so no further update is needed Aug 26 07:49:33 volumio volumio[1082]: info: Output device has changed, restarting MPD Aug 26 07:49:33 volumio volumio[1082]: info: Output device has changed, restarting Shairport Sync Aug 26 07:49:33 volumio volumio[1082]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 07:49:33 volumio volumio[1082]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 07:49:33 volumio sudo[1361]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 26 07:49:33 volumio sudo[1361]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 07:49:33 volumio sudo[1361]: pam_unix(sudo:session): session closed for user root Aug 26 07:49:33 volumio sudo[1363]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 26 07:49:33 volumio sudo[1363]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 07:49:33 volumio systemd[1]: Stopping Music Player Daemon... Aug 26 07:49:33 volumio volumio[1082]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 26 07:49:33 volumio volumio[1082]: info: Preparing to generate the ALSA configuration file Aug 26 07:49:33 volumio systemd[1]: mpd.service: Succeeded. Aug 26 07:49:33 volumio systemd[1]: Stopped Music Player Daemon. Aug 26 07:49:33 volumio volumio[1082]: error: MPD error: Error: write EPIPE Aug 26 07:49:33 volumio volumio[1082]: error: write EPIPE {"errno":-32,"code":"EPIPE","syscall":"write"} Aug 26 07:49:33 volumio volumio[1082]: error: MPD error: The expression evaluated to a falsy value: Aug 26 07:49:33 volumio volumio[1082]: assert.ok(self.idling) Aug 26 07:49:33 volumio volumio[1082]: error: The expression evaluated to a falsy value: Aug 26 07:49:33 volumio volumio[1082]: assert.ok(self.idling) Aug 26 07:49:33 volumio volumio[1082]: error: MPD error: Error: write EPIPE Aug 26 07:49:33 volumio volumio[1082]: error: write EPIPE {"errno":-32,"code":"EPIPE","syscall":"write"} Aug 26 07:49:33 volumio volumio[1082]: info: Shairport-Sync Started Aug 26 07:49:33 volumio volumio[1082]: info: MPD running with PID1300 Aug 26 07:49:33 volumio volumio[1082]: ,establishing connection Aug 26 07:49:33 volumio volumio[1082]: info: Shairport-Sync Started Aug 26 07:49:33 volumio systemd[1]: Starting Music Player Daemon... Aug 26 07:49:33 volumio volumio[1082]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Aug 26 07:49:33 volumio volumio[1082]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Aug 26 07:49:33 volumio volumio[1082]: info: Reading ALSA contributions from plugins. Aug 26 07:49:33 volumio volumio[1082]: info: MPD Permissions set Aug 26 07:49:33 volumio sudo[1369]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 26 07:49:33 volumio sudo[1369]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 07:49:33 volumio volumio[1082]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 07:49:33 volumio sudo[1369]: pam_unix(sudo:session): session closed for user root Aug 26 07:49:33 volumio volumio[1082]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 07:49:33 volumio volumio[1082]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 07:49:33 volumio volumio[1082]: info: Starting Shairport Sync Aug 26 07:49:33 volumio volumio[1082]: info: CoreCommandRouter::volumioGetState Aug 26 07:49:33 volumio volumio[1082]: info: CorePlayQueue::getTrack 0 Aug 26 07:49:33 volumio volumio[1082]: info: CoreCommandRouter::volumioPause Aug 26 07:49:33 volumio volumio[1082]: info: CoreStateMachine::pause Aug 26 07:49:33 volumio volumio[1082]: info: mpdhttpout --- Volumio set on pause Aug 26 07:49:33 volumio volumio[1082]: info: mpdhttpout ---Configuration successfully added to mpd.conf. Aug 26 07:49:33 volumio volumio[1082]: info: Asound.conf file unchanged, so no further update is needed Aug 26 07:49:33 volumio volumio[1082]: info: Output device has changed, restarting MPD Aug 26 07:49:33 volumio sudo[1378]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 26 07:49:33 volumio sudo[1378]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 07:49:33 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 26 07:49:33 volumio volumio[1082]: info: Output device has changed, restarting Shairport Sync Aug 26 07:49:33 volumio volumio[1082]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 07:49:33 volumio volumio[1082]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 07:49:33 volumio sudo[1381]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 26 07:49:33 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 26 07:49:33 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 26 07:49:33 volumio sudo[1381]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 07:49:33 volumio sudo[1383]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 26 07:49:33 volumio sudo[1383]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 07:49:33 volumio volumio[1082]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 26 07:49:33 volumio sudo[1381]: pam_unix(sudo:session): session closed for user root Aug 26 07:49:33 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 26 07:49:33 volumio sudo[1378]: pam_unix(sudo:session): session closed for user root Aug 26 07:49:33 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Aug 26 07:49:33 volumio systemd[1]: mpd.service: Succeeded. Aug 26 07:49:33 volumio systemd[1]: Stopped Music Player Daemon. Aug 26 07:49:33 volumio systemd[1]: Starting Music Player Daemon... Aug 26 07:49:33 volumio volumio[1082]: info: MPD Permissions set Aug 26 07:49:33 volumio volumio[1082]: info: Shairport-Sync Started Aug 26 07:49:33 volumio volumio[1082]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 07:49:33 volumio volumio[1082]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 07:49:33 volumio volumio[1082]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 07:49:33 volumio volumio[1082]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 07:49:33 volumio volumio[1082]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 07:49:33 volumio volumio[1082]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 07:49:33 volumio volumio[1082]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 07:49:33 volumio volumio[1082]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 07:49:33 volumio volumio[1082]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 07:49:33 volumio volumio[1082]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 07:49:33 volumio volumio[1082]: info: Starting Shairport Sync Aug 26 07:49:33 volumio sudo[1394]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 26 07:49:33 volumio sudo[1394]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 07:49:33 volumio sudo[1394]: pam_unix(sudo:session): session closed for user root Aug 26 07:49:33 volumio volumio[1082]: info: mpdhttpout ---Configuration successfully replaced in mpd.conf. Aug 26 07:49:33 volumio volumio[1082]: info: CoreCommandRouter::volumioGetState Aug 26 07:49:33 volumio volumio[1082]: info: CorePlayQueue::getTrack 0 Aug 26 07:49:33 volumio volumio[1082]: info: CoreCommandRouter::volumioPause Aug 26 07:49:33 volumio volumio[1082]: info: CoreStateMachine::pause Aug 26 07:49:33 volumio volumio[1082]: info: mpdhttpout --- Volumio set on pause Aug 26 07:49:33 volumio sudo[1403]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 26 07:49:33 volumio sudo[1403]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 07:49:33 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 26 07:49:33 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 26 07:49:33 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 26 07:49:33 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 26 07:49:33 volumio sudo[1403]: pam_unix(sudo:session): session closed for user root Aug 26 07:49:33 volumio volumio[1082]: info: Shairport-Sync Started Aug 26 07:49:34 volumio volumio[1082]: info: camilladsp service started and running in background, instance 1 Aug 26 07:49:34 volumio volumio[1082]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 07:49:34 volumio volumio[1082]: info: FusionDsp - AAAAAAAAAAAAAA-> 44100 48000 88200 96000 176400 192000 <-AAAAAAAAAAAAA Aug 26 07:49:34 volumio volumio[1082]: info: FusionDsp loaded Aug 26 07:49:34 volumio volumio[1082]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 26 07:49:34 volumio sudo[1424]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Aug 26 07:49:34 volumio sudo[1424]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 07:49:34 volumio volumio[1082]: info: FusionDsp - Reporting Fusion DSP Enabled Aug 26 07:49:34 volumio volumio[1082]: info: Adding Signal Path Element [object Object] Aug 26 07:49:34 volumio volumio[1082]: info: Adding fusiondspeq DSP Signal Path Element Aug 26 07:49:34 volumio volumio[1082]: info: FusionDsp - ---- installed callbackRead Aug 26 07:49:34 volumio volumio[1082]: info: FusionDsp - If filter freq >samplerate/2 then disable it Aug 26 07:49:34 volumio volumio[1082]: error: FusionDsp - WebSocket error: [object Object] Aug 26 07:49:34 volumio volumio[1082]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Aug 26 07:49:34 volumio sudo[1424]: pam_unix(sudo:session): session closed for user root Aug 26 07:49:34 volumio systemd[1]: Started FusionDsp Daemon. Aug 26 07:49:34 volumio mpd[1402]: Aug 26 07:49 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 26 07:49:34 volumio systemd[1]: Started Music Player Daemon. Aug 26 07:49:34 volumio sudo[1383]: pam_unix(sudo:session): session closed for user root Aug 26 07:49:34 volumio volumio[1082]: error: updateQueue error: null Aug 26 07:49:34 volumio sudo[1363]: pam_unix(sudo:session): session closed for user root Aug 26 07:49:34 volumio volumio[1082]: error: MPD error: The expression evaluated to a falsy value: Aug 26 07:49:34 volumio volumio[1082]: assert.ok(self.idling) Aug 26 07:49:34 volumio volumio[1082]: error: The expression evaluated to a falsy value: Aug 26 07:49:34 volumio volumio[1082]: assert.ok(self.idling) Aug 26 07:49:34 volumio volumio[1082]: error: updateQueue error: null Aug 26 07:49:35 volumio volumio[1082]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Aug 26 07:49:35 volumio sudo[1441]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 26 07:49:35 volumio sudo[1441]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 07:49:35 volumio systemd[1]: Stopping Music Player Daemon... Aug 26 07:49:35 volumio systemd[1]: mpd.service: Succeeded. Aug 26 07:49:35 volumio systemd[1]: Stopped Music Player Daemon. Aug 26 07:49:35 volumio volumio[1082]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 26 07:49:35 volumio volumio[1082]: info: Received Get System Info Aug 26 07:49:35 volumio volumio[1082]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 26 07:49:35 volumio volumio[1082]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 26 07:49:35 volumio volumio[1082]: info: Discovery: Getting this device information Aug 26 07:49:35 volumio volumio[1082]: info: CoreCommandRouter::volumioGetState Aug 26 07:49:35 volumio volumio[1082]: info: CorePlayQueue::getTrack 0 Aug 26 07:49:35 volumio volumio[1082]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 26 07:49:35 volumio systemd[1]: Starting Music Player Daemon... Aug 26 07:49:35 volumio sudo[1459]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 26 07:49:35 volumio sudo[1459]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 07:49:35 volumio sudo[1459]: pam_unix(sudo:session): session closed for user root Aug 26 07:49:35 volumio sudo[1461]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 26 07:49:35 volumio sudo[1461]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 07:49:35 volumio sudo[1461]: pam_unix(sudo:session): session closed for user root Aug 26 07:49:35 volumio sudo[1457]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 26 07:49:35 volumio sudo[1457]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 07:49:35 volumio volumio[1082]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Aug 26 07:49:35 volumio sudo[1457]: pam_unix(sudo:session): session closed for user root Aug 26 07:49:35 volumio sudo[1465]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Aug 26 07:49:35 volumio sudo[1465]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 07:49:35 volumio sudo[1470]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 26 07:49:35 volumio sudo[1470]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 07:49:35 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Aug 26 07:49:35 volumio sudo[1465]: pam_unix(sudo:session): session closed for user root Aug 26 07:49:35 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Aug 26 07:49:35 volumio systemd[1]: mpd.service: Succeeded. Aug 26 07:49:35 volumio volumio[1082]: info: Upmpdcli Daemon Started Aug 26 07:49:35 volumio systemd[1]: Stopped Music Player Daemon. Aug 26 07:49:35 volumio systemd[1]: Starting Music Player Daemon... Aug 26 07:49:35 volumio sudo[1473]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 26 07:49:35 volumio sudo[1473]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 07:49:35 volumio sudo[1473]: pam_unix(sudo:session): session closed for user root Aug 26 07:49:35 volumio volumio[1082]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Aug 26 07:49:35 volumio volumio[1082]: info: go-librespot daemon successfully initialized Aug 26 07:49:36 volumio sudo[1477]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=muz,password=192.168.73.15,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.73.15/music /mnt/NAS/Muziek Aug 26 07:49:36 volumio sudo[1477]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 07:49:36 volumio kernel: CIFS: Attempting to mount //192.168.73.15/music Aug 26 07:49:36 volumio sudo[1477]: pam_unix(sudo:session): session closed for user root Aug 26 07:49:36 volumio volumio[1082]: info: Cannot mount NAS Muziek at system boot, trial number 2 ,retrying in 5 seconds Aug 26 07:49:36 volumio kernel: CIFS: Status code returned 0xc000006d STATUS_LOGON_FAILURE Aug 26 07:49:36 volumio kernel: CIFS: VFS: \\192.168.73.15 Send error in SessSetup = -13 Aug 26 07:49:36 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -13 Aug 26 07:49:36 volumio mpd[1475]: Aug 26 07:49 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 26 07:49:36 volumio systemd[1]: Started Music Player Daemon. Aug 26 07:49:36 volumio sudo[1441]: pam_unix(sudo:session): session closed for user root Aug 26 07:49:36 volumio sudo[1470]: pam_unix(sudo:session): session closed for user root Aug 26 07:49:36 volumio volumio[1082]: error: MPD error: The expression evaluated to a falsy value: Aug 26 07:49:36 volumio volumio[1082]: assert.ok(self.idling) Aug 26 07:49:36 volumio volumio[1082]: error: The expression evaluated to a falsy value: Aug 26 07:49:36 volumio volumio[1082]: assert.ok(self.idling) Aug 26 07:49:36 volumio volumio[1082]: error: updateQueue error: null Aug 26 07:49:36 volumio volumio[1472]: Generating RSA private key, 4096 bit long modulus (2 primes) Aug 26 07:49:36 volumio systemd[1]: systemd-timedated.service: Succeeded. Aug 26 07:49:38 volumio volumio[1082]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Aug 26 07:49:38 volumio volumio[1082]: info: CoreCommandRouter::volumioGetState Aug 26 07:49:38 volumio volumio[1082]: info: CorePlayQueue::getTrack 0 Aug 26 07:49:38 volumio volumio-remote-updater[599]: [2026-08-26 07:49:38] [connect] Successful connection Aug 26 07:49:38 volumio volumio[1082]: 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: 7 Aug 26 07:49:38 volumio volumio-remote-updater[599]: [2026-08-26 07:49:38] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1787723378 101 Aug 26 07:49:38 volumio volumio[1082]: info: Initializing connection to go-librespot Websocket Aug 26 07:49:38 volumio go-librespot[1322]: time="2026-08-26T07:49:38+02:00" level=debug msg="new websocket client" Aug 26 07:49:38 volumio volumio[1082]: info: Connection to go-librespot Websocket established Aug 26 07:49:40 volumio volumio[1472]: ............................................++++ Aug 26 07:49:41 volumio volumio[1082]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Aug 26 07:49:41 volumio volumio[1082]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Aug 26 07:49:41 volumio volumio[1082]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Aug 26 07:49:41 volumio volumio[1082]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Aug 26 07:49:41 volumio volumio[1082]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Aug 26 07:49:41 volumio volumio[1082]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Aug 26 07:49:41 volumio volumio[1082]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Aug 26 07:49:41 volumio volumio[1082]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Aug 26 07:49:41 volumio volumio[1082]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Aug 26 07:49:41 volumio volumio[1082]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Aug 26 07:49:41 volumio volumio[1082]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Aug 26 07:49:41 volumio volumio[1082]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Aug 26 07:49:41 volumio volumio[1082]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Aug 26 07:49:41 volumio volumio[1082]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Aug 26 07:49:41 volumio volumio[1082]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Aug 26 07:49:41 volumio volumio[1082]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Aug 26 07:49:41 volumio volumio[1082]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Aug 26 07:49:41 volumio volumio[1082]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Aug 26 07:49:41 volumio volumio[1082]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Aug 26 07:49:41 volumio volumio[1082]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Aug 26 07:49:41 volumio volumio[1082]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Aug 26 07:49:41 volumio volumio[1082]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Aug 26 07:49:41 volumio volumio[1082]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Aug 26 07:49:41 volumio volumio[1082]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Aug 26 07:49:41 volumio volumio[1082]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Aug 26 07:49:41 volumio volumio[1082]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Aug 26 07:49:41 volumio volumio[1082]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Aug 26 07:49:41 volumio volumio[1082]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Aug 26 07:49:41 volumio volumio[1082]: info: Adding plugin bluetooth to MyMusic Plugins Aug 26 07:49:41 volumio volumio[1082]: info: Adding plugin multiroom to MyMusic Plugins Aug 26 07:49:41 volumio volumio[1082]: info: Adding plugin metavolumio to MyMusic Plugins Aug 26 07:49:41 volumio volumio[1082]: info: Adding plugin cd_controller to MyMusic Plugins Aug 26 07:49:41 volumio volumio[1082]: info: Adding plugin qobuzconnect to MyMusic Plugins Aug 26 07:49:41 volumio volumio[1082]: info: Adding plugin smart_inputs to MyMusic Plugins Aug 26 07:49:41 volumio volumio[1082]: info: Adding plugin tidalconnect to MyMusic Plugins Aug 26 07:49:41 volumio volumio[1082]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Aug 26 07:49:42 volumio volumio[1082]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Aug 26 07:49:42 volumio volumio[1082]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Aug 26 07:49:42 volumio volumio[1082]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 07:49:42 volumio volumio[1082]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 07:49:42 volumio volumio[1082]: info: Starting MyVolumio Remote Streaming Endpoints Aug 26 07:49:42 volumio volumio[1082]: info: MyVolumio login type: Token Aug 26 07:49:42 volumio volumio[1082]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Aug 26 07:49:42 volumio volumio[1082]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Aug 26 07:49:42 volumio go-librespot[1322]: time="2026-08-26T07:49:42+02:00" level=debug msg="obtained new client token: AAF9Y9Yl5nGfDjUze9wQwde2h+6mXpe4mKU1WIIIMX5dHsLJg52pW3yUlHrpQyMcgRkPK42CQu/pDZwW/JTdSPIZCsccXTGS35cAnpECLz9n0spe1MjN/cTn8h4z3fYrmmoC9jinA8Gkk8A3mCqqZDVA8wxcp6g9eFkMEYcoFOmIlioVRYkViIk6vTUegtdLQnV0r07YVcWUmche4Jpy99PzXgqIIY1KEZIcAx9QK1pHHFaN48m+D8+CYw==" Aug 26 07:49:42 volumio go-librespot[1322]: time="2026-08-26T07:49:42+02:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:4070, retrying with a different AP" error="dial tcp 34.158.1.133:4070: connect: connection refused" Aug 26 07:49:42 volumio go-librespot[1322]: time="2026-08-26T07:49:42+02:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:443, retrying with a different AP" error="dial tcp 34.158.1.133:443: connect: connection refused" Aug 26 07:49:42 volumio go-librespot[1322]: time="2026-08-26T07:49:42+02:00" level=debug msg="connected to ap-gew4.spotify.com:80" Aug 26 07:49:42 volumio go-librespot[1322]: time="2026-08-26T07:49:42+02:00" level=debug msg="completed keyexchange" Aug 26 07:49:42 volumio go-librespot[1322]: time="2026-08-26T07:49:42+02:00" level=debug msg="completed challenge" Aug 26 07:49:42 volumio go-librespot[1322]: time="2026-08-26T07:49:42+02:00" level=info msg="authenticated AP" username="ma***********da" Aug 26 07:49:42 volumio go-librespot[1322]: time="2026-08-26T07:49:42+02:00" level=info msg="authenticated Login5" username="ma***********da" Aug 26 07:49:42 volumio go-librespot[1322]: time="2026-08-26T07:49:42+02:00" level=info msg="accepted zeroconf from PCOH01" username="ma***********da" Aug 26 07:49:42 volumio go-librespot[1322]: time="2026-08-26T07:49:42+02:00" level=debug msg="dealer connection opened" Aug 26 07:49:42 volumio go-librespot[1322]: time="2026-08-26T07:49:42+02:00" level=trace msg="starting accesspoint recv loop" Aug 26 07:49:42 volumio go-librespot[1322]: time="2026-08-26T07:49:42+02:00" level=trace msg="starting dealer recv loop" Aug 26 07:49:42 volumio go-librespot[1322]: time="2026-08-26T07:49:42+02:00" level=trace msg="received accesspoint ping" Aug 26 07:49:42 volumio go-librespot[1322]: time="2026-08-26T07:49:42+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Aug 26 07:49:42 volumio go-librespot[1322]: time="2026-08-26T07:49:42+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Aug 26 07:49:42 volumio go-librespot[1322]: time="2026-08-26T07:49:42+02:00" level=debug msg="received connection id: NTE2ZmJkM2YtYzNk...RDBGNTNDRTYwRA==" Aug 26 07:49:42 volumio go-librespot[1322]: time="2026-08-26T07:49:42+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Aug 26 07:49:42 volumio go-librespot[1322]: time="2026-08-26T07:49:42+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Aug 26 07:49:42 volumio go-librespot[1322]: time="2026-08-26T07:49:42+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Aug 26 07:49:42 volumio go-librespot[1322]: time="2026-08-26T07:49:42+02:00" level=trace msg="received accesspoint pong ack" Aug 26 07:49:42 volumio go-librespot[1322]: time="2026-08-26T07:49:42+02:00" level=debug msg="put connect state because NEW_DEVICE" Aug 26 07:49:43 volumio go-librespot[1322]: time="2026-08-26T07:49:43+02:00" level=debug msg="handling transfer player command from 4e78df6e27849033d15b295d6a6916b4652c5b6a" Aug 26 07:49:43 volumio go-librespot[1322]: time="2026-08-26T07:49:43+02:00" level=debug msg="resolved context of track" uri="spotify:playlist:02QAmiNBugYup0WphzxLbv" Aug 26 07:49:43 volumio go-librespot[1322]: time="2026-08-26T07:49:43+02:00" level=trace msg="fetched new page 0 with 38 items (list: 38)" uri="spotify:playlist:02QAmiNBugYup0WphzxLbv" Aug 26 07:49:43 volumio go-librespot[1322]: time="2026-08-26T07:49:43+02:00" level=debug msg="shuffled context with seed 17429107962052930584 (len: 38, keep: 30)" uri="spotify:playlist:02QAmiNBugYup0WphzxLbv" Aug 26 07:49:43 volumio go-librespot[1322]: time="2026-08-26T07:49:43+02:00" level=debug msg="loading track (paused: false, position: 84127ms)" uri="spotify:track:0awWxz7WRKdSpU9RVZ5PVK" Aug 26 07:49:43 volumio go-librespot[1322]: time="2026-08-26T07:49:43+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Aug 26 07:49:43 volumio go-librespot[1322]: time="2026-08-26T07:49:43+02:00" level=trace msg="emitting websocket event: will_play" Aug 26 07:49:43 volumio go-librespot[1322]: time="2026-08-26T07:49:43+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 334" Aug 26 07:49:43 volumio go-librespot[1322]: time="2026-08-26T07:49:43+02:00" level=debug msg="selected format OGG_VORBIS_320 (81c31eb10a48a480ce96be70c360034f6cb10a3b)" uri="spotify:track:0awWxz7WRKdSpU9RVZ5PVK" Aug 26 07:49:43 volumio go-librespot[1322]: time="2026-08-26T07:49:43+02:00" level=debug msg="requested aes key for file 81c31eb10a48a480ce96be70c360034f6cb10a3b, gid: 0awWxz7WRKdSpU9RVZ5PVK" Aug 26 07:49:43 volumio go-librespot[1322]: time="2026-08-26T07:49:43+02:00" level=trace msg="found 2 cdn urls" uri="spotify:track:0awWxz7WRKdSpU9RVZ5PVK" Aug 26 07:49:43 volumio go-librespot[1322]: time="2026-08-26T07:49:43+02:00" level=debug msg="fetched first chunk of 17, total size is 8427308 bytes" uri="spotify:track:0awWxz7WRKdSpU9RVZ5PVK" Aug 26 07:49:43 volumio go-librespot[1322]: time="2026-08-26T07:49:43+02:00" level=warning msg="reducing track normalisation factor to prevent clipping, please add negative pregain to avoid" Aug 26 07:49:43 volumio volumio[1082]: info: Starting Streaming Service Transparent Proxy Aug 26 07:49:43 volumio volumio[1082]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Aug 26 07:49:43 volumio volumio[1082]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Aug 26 07:49:43 volumio volumio[1082]: info: Streaming services startup Aug 26 07:49:43 volumio volumio[1082]: info: Starting Streaming Daemon Aug 26 07:49:43 volumio go-librespot[1322]: time="2026-08-26T07:49:43+02:00" level=debug msg="fetched chunk 2/16, size: 524288" uri="spotify:track:0awWxz7WRKdSpU9RVZ5PVK" Aug 26 07:49:43 volumio kernel: hwmon hwmon1: Undervoltage detected! Aug 26 07:49:43 volumio go-librespot[1322]: time="2026-08-26T07:49:43+02:00" level=debug msg="fetched chunk 3/16, size: 524288" uri="spotify:track:0awWxz7WRKdSpU9RVZ5PVK" Aug 26 07:49:43 volumio go-librespot[1322]: time="2026-08-26T07:49:43+02:00" level=debug msg="fetched chunk 1/16, size: 524288" uri="spotify:track:0awWxz7WRKdSpU9RVZ5PVK" Aug 26 07:49:43 volumio go-librespot[1322]: time="2026-08-26T07:49:43+02:00" level=debug msg="fetched chunk 6/16, size: 524288" uri="spotify:track:0awWxz7WRKdSpU9RVZ5PVK" Aug 26 07:49:43 volumio volumio[1082]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Aug 26 07:49:43 volumio go-librespot[1322]: time="2026-08-26T07:49:43+02:00" level=debug msg="fetched chunk 5/16, size: 524288" uri="spotify:track:0awWxz7WRKdSpU9RVZ5PVK" Aug 26 07:49:43 volumio go-librespot[1322]: time="2026-08-26T07:49:43+02:00" level=debug msg="vorbis: corrupt or missing data in bitstream" uri="spotify:track:0awWxz7WRKdSpU9RVZ5PVK" Aug 26 07:49:43 volumio go-librespot[1322]: time="2026-08-26T07:49:43+02:00" level=trace msg="seek to 84127ms (diff: 157ms, samples: 3710000, bytes: 2716455)" uri="spotify:track:0awWxz7WRKdSpU9RVZ5PVK" Aug 26 07:49:43 volumio go-librespot[1322]: time="2026-08-26T07:49:43+02:00" level=debug msg="created new output device" Aug 26 07:49:43 volumio sudo[1510]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 26 07:49:43 volumio sudo[1510]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 07:49:44 volumio go-librespot[1322]: time="2026-08-26T07:49:44+02:00" level=debug msg="fetched chunk 7/16, size: 524288" uri="spotify:track:0awWxz7WRKdSpU9RVZ5PVK" Aug 26 07:49:44 volumio go-librespot[1322]: time="2026-08-26T07:49:44+02:00" level=debug msg="fetched chunk 8/16, size: 524288" uri="spotify:track:0awWxz7WRKdSpU9RVZ5PVK" Aug 26 07:49:44 volumio sudo[1510]: pam_unix(sudo:session): session closed for user root Aug 26 07:49:44 volumio go-librespot[1322]: time="2026-08-26T07:49:44+02:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 124988 us, period size = 5512 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 4 frames, PCM format = FLOAT_LE" Aug 26 07:49:44 volumio go-librespot[1322]: time="2026-08-26T07:49:44+02:00" level=info msg="loaded track \"Peer Gynt Suite No. 1, Op. 46: I. Morning Mood\" (paused: false, position: 84127ms, duration: 253440ms, prefetched: false)" uri="spotify:track:0awWxz7WRKdSpU9RVZ5PVK" Aug 26 07:49:44 volumio go-librespot[1322]: time="2026-08-26T07:49:44+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Aug 26 07:49:44 volumio go-librespot[1322]: time="2026-08-26T07:49:44+02:00" level=trace msg="scheduling prefetch in 139s" Aug 26 07:49:44 volumio go-librespot[1322]: time="2026-08-26T07:49:44+02:00" level=trace msg="emitting websocket event: metadata" Aug 26 07:49:44 volumio go-librespot[1322]: time="2026-08-26T07:49:44+02:00" level=trace msg="emitting websocket event: active" Aug 26 07:49:44 volumio go-librespot[1322]: time="2026-08-26T07:49:44+02:00" level=debug msg="sending successful reply for dealer request" Aug 26 07:49:44 volumio go-librespot[1322]: time="2026-08-26T07:49:44+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Aug 26 07:49:44 volumio go-librespot[1322]: time="2026-08-26T07:49:44+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Aug 26 07:49:44 volumio go-librespot[1322]: time="2026-08-26T07:49:44+02:00" level=trace msg="emitting websocket event: playing" Aug 26 07:49:44 volumio sudo[1521]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=muz,password=192.168.73.15,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.73.15/music /mnt/NAS/Muziek Aug 26 07:49:44 volumio sudo[1521]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 07:49:44 volumio volumio[1082]: info: Getting Spotify volume Aug 26 07:49:44 volumio kernel: CIFS: Attempting to mount //192.168.73.15/music Aug 26 07:49:44 volumio kernel: CIFS: Status code returned 0xc000006d STATUS_LOGON_FAILURE Aug 26 07:49:44 volumio kernel: CIFS: VFS: \\192.168.73.15 Send error in SessSetup = -13 Aug 26 07:49:44 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -13 Aug 26 07:49:44 volumio sudo[1521]: pam_unix(sudo:session): session closed for user root Aug 26 07:49:44 volumio volumio[1082]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:playlist:02QAmiNBugYup0WphzxLbv","uri":"spotify:track:0awWxz7WRKdSpU9RVZ5PVK","play_origin":"playlist"}} Aug 26 07:49:44 volumio volumio[1082]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:0awWxz7WRKdSpU9RVZ5PVK","name":"Peer Gynt Suite No. 1, Op. 46: I. Morning Mood","artist_names":["Edvard Grieg","Berliner Philharmoniker","Herbert von Karajan"],"album_name":"Grieg: Peer Gynt Suites / Sibelius: Valse triste","album_cover_url":"https://i.scdn.co/image/ab67616d00001e029df8b31447375af1baa0c05f","position":84127,"duration":253440,"release_date":"year:1993 month:1 day:1","track_number":1,"disc_number":1}} Aug 26 07:49:44 volumio volumio[1082]: SPOTIFY: received: {"type":"active","data":null} Aug 26 07:49:44 volumio volumio[1082]: info: Aligning Spotify Volume to Volumio Volume Aug 26 07:49:44 volumio volumio[1082]: info: CoreCommandRouter::volumioGetState Aug 26 07:49:44 volumio volumio[1082]: info: CorePlayQueue::getTrack 0 Aug 26 07:49:44 volumio volumio[1082]: info: Setting Spotify Volume from Volumio: 40 Aug 26 07:49:44 volumio volumio[1082]: SPOTIFY: received: {"type":"playing","data":{"context_uri":"spotify:playlist:02QAmiNBugYup0WphzxLbv","uri":"spotify:track:0awWxz7WRKdSpU9RVZ5PVK","resume":false,"play_origin":"playlist"}} Aug 26 07:49:44 volumio volumio[1082]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 26 07:49:44 volumio volumio[1082]: TypeError: Cannot read property 'service' of undefined Aug 26 07:49:44 volumio volumio[1082]: at ControllerSpotify.identifyPlaybackMode (/data/plugins/music_service/spop/index.js:346:50) Aug 26 07:49:44 volumio volumio[1082]: at ControllerSpotify.parseEventState (/data/plugins/music_service/spop/index.js:272:18) Aug 26 07:49:44 volumio volumio[1082]: at WebSocket.message (/data/plugins/music_service/spop/index.js:199:14) Aug 26 07:49:44 volumio volumio[1082]: at WebSocket.emit (events.js:315:20) Aug 26 07:49:44 volumio volumio[1082]: at Receiver.receiverOnMessage (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1220:20) Aug 26 07:49:44 volumio volumio[1082]: at Receiver.emit (events.js:315:20) Aug 26 07:49:44 volumio volumio[1082]: at Receiver.dataMessage (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:596:14) Aug 26 07:49:44 volumio volumio[1082]: at Receiver.getData (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:496:10) Aug 26 07:49:44 volumio volumio[1082]: at Receiver.startLoop (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:167:16) Aug 26 07:49:44 volumio volumio[1082]: at Receiver._write (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:94:10) Aug 26 07:49:44 volumio volumio[1082]: at writeOrBuffer (internal/streams/writable.js:358:12) Aug 26 07:49:44 volumio volumio[1082]: at Receiver.Writable.write (internal/streams/writable.js:303:10) Aug 26 07:49:44 volumio volumio[1082]: at Socket.socketOnData (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1355:35) Aug 26 07:49:44 volumio volumio[1082]: at Socket.emit (events.js:315:20) Aug 26 07:49:44 volumio volumio[1082]: at addChunk (internal/streams/readable.js:309:12) Aug 26 07:49:44 volumio volumio[1082]: at readableAddChunk (internal/streams/readable.js:284:9) Aug 26 07:49:44 volumio volumio[1082]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 26 07:49:45 volumio sudo[1535]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-08-26 07:48 Aug 26 07:49:45 volumio sudo[1535]: 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="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"