-- Logs begin at Thu 2019-02-14 12:11:58 IST, end at Wed 2025-03-12 20:16:00 IST. -- Mar 12 20:15:00 hifi wireless.js[600]: trying... Mar 12 20:15:00 hifi sudo[916]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 12 20:15:00 hifi sudo[916]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 20:15:00 hifi sudo[916]: pam_unix(sudo:session): session closed for user root Mar 12 20:15:00 hifi kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Mar 12 20:15:01 hifi kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Mar 12 20:15:01 hifi kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Mar 12 20:15:01 hifi kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Mar 12 20:15:01 hifi wireless.js[600]: trying... Mar 12 20:15:01 hifi sudo[919]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 12 20:15:01 hifi sudo[919]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 20:15:01 hifi sudo[919]: pam_unix(sudo:session): session closed for user root Mar 12 20:15:01 hifi volumio-remote-updater[583]: [2025-03-12 20:15:01] [info] asio async_connect error: system:111 (Connection refused) Mar 12 20:15:01 hifi volumio-remote-updater[583]: [2025-03-12 20:15:01] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Mar 12 20:15:01 hifi volumio-remote-updater[583]: [2025-03-12 20:15:01] [error] handle_connect error: Underlying Transport Error Mar 12 20:15:02 hifi wireless.js[600]: trying... Mar 12 20:15:02 hifi sudo[922]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 12 20:15:02 hifi sudo[922]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 20:15:02 hifi sudo[922]: pam_unix(sudo:session): session closed for user root Mar 12 20:15:03 hifi wireless.js[600]: trying... Mar 12 20:15:03 hifi sudo[925]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 12 20:15:03 hifi sudo[925]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 20:15:03 hifi sudo[925]: pam_unix(sudo:session): session closed for user root Mar 12 20:15:03 hifi wpa_supplicant[889]: wlan0: Trying to associate with SSID 'Panic At The Disco' Mar 12 20:15:03 hifi dhcpcd[900]: wlan0: carrier acquired Mar 12 20:15:03 hifi dhcpcd[900]: wlan0: connected to Access Point `Panic At The Disco' Mar 12 20:15:03 hifi wpa_supplicant[889]: wlan0: Associated with bc:df:58:cd:d4:da Mar 12 20:15:03 hifi wpa_supplicant[889]: wlan0: CTRL-EVENT-CONNECTED - Connection to bc:df:58:cd:d4:da completed [id=0 id_str=] Mar 12 20:15:03 hifi wpa_supplicant[889]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Mar 12 20:15:03 hifi wpa_supplicant[889]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=US Mar 12 20:15:03 hifi dhcpcd[900]: DUID 00:01:00:01:2d:69:1d:73:dc:a6:32:cf:6f:ff Mar 12 20:15:03 hifi dhcpcd[900]: wlan0: IAID 32:cf:6f:ff Mar 12 20:15:03 hifi dhcpcd[900]: wlan0: adding address fe80::dea6:32ff:fecf:6fff Mar 12 20:15:03 hifi dhcpcd[900]: ipv6_addaddr1: Permission denied Mar 12 20:15:03 hifi dhcpcd[900]: wlan0: carrier lost Mar 12 20:15:03 hifi dhcpcd[900]: wlan0: carrier acquired Mar 12 20:15:03 hifi dhcpcd[900]: wlan0: IAID 32:cf:6f:ff Mar 12 20:15:04 hifi volumio-time-update[592]: volumio-time-update-util: Fetching time from Volumio... Mar 12 20:15:04 hifi volumio-time-update[592]: volumio-time-update-util: Date not found in response Mar 12 20:15:04 hifi volumio-time-update[592]: volumio-time-update-util: Retrying in 5 seconds... Mar 12 20:15:04 hifi dhcpcd[900]: wlan0: rebinding lease of 192.168.45.65 Mar 12 20:15:04 hifi dhcpcd[900]: wlan0: probing address 192.168.45.65/24 Mar 12 20:15:04 hifi wireless.js[600]: trying... Mar 12 20:15:04 hifi sudo[945]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 12 20:15:04 hifi sudo[945]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 20:15:04 hifi sudo[945]: pam_unix(sudo:session): session closed for user root Mar 12 20:15:04 hifi dhcpcd[900]: wlan0: soliciting an IPv6 router Mar 12 20:15:05 hifi wireless.js[600]: trying... Mar 12 20:15:05 hifi sudo[948]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 12 20:15:05 hifi sudo[948]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 20:15:05 hifi sudo[948]: pam_unix(sudo:session): session closed for user root Mar 12 20:15:06 hifi wireless.js[600]: trying... Mar 12 20:15:06 hifi sudo[951]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 12 20:15:06 hifi sudo[951]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 20:15:06 hifi sudo[951]: pam_unix(sudo:session): session closed for user root Mar 12 20:15:07 hifi wireless.js[600]: trying... Mar 12 20:15:07 hifi sudo[954]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 12 20:15:07 hifi sudo[954]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 20:15:07 hifi sudo[954]: pam_unix(sudo:session): session closed for user root Mar 12 20:15:08 hifi wireless.js[600]: trying... Mar 12 20:15:08 hifi sudo[957]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 12 20:15:08 hifi sudo[957]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 20:15:08 hifi sudo[957]: pam_unix(sudo:session): session closed for user root Mar 12 20:15:08 hifi dhcpcd[900]: wlan0: leased 192.168.45.65 for 86400 seconds Mar 12 20:15:08 hifi avahi-daemon[610]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.45.65. Mar 12 20:15:08 hifi avahi-daemon[610]: New relevant interface wlan0.IPv4 for mDNS. Mar 12 20:15:08 hifi avahi-daemon[610]: Registering new address record for 192.168.45.65 on wlan0.IPv4. Mar 12 20:15:08 hifi dhcpcd[900]: wlan0: adding route to 192.168.45.0/24 Mar 12 20:15:08 hifi dhcpcd[900]: wlan0: adding default route via 192.168.45.1 Mar 12 20:15:09 hifi volumio-time-update[592]: volumio-time-update-util: Fetching time from Volumio... Mar 12 20:15:09 hifi wireless.js[600]: trying... Mar 12 20:15:09 hifi sudo[984]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 12 20:15:09 hifi sudo[984]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 20:15:09 hifi sudo[984]: pam_unix(sudo:session): session closed for user root Mar 12 20:15:09 hifi wireless.js[600]: Connected to: ----Panic At The Disco Mar 12 20:15:09 hifi wireless.js[600]: ---- Mar 12 20:15:09 hifi sudo[987]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 12 20:15:09 hifi sudo[987]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 20:15:09 hifi sudo[987]: pam_unix(sudo:session): session closed for user root Mar 12 20:15:09 hifi wireless.js[600]: ... joined AP, wlan0 IPv4 is 192.168.45.65, ipV6 is undefined Mar 12 20:15:09 hifi wireless.js[600]: It's done! AP Mar 12 20:15:09 hifi kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Mar 12 20:15:09 hifi systemd[1]: Started Wireless Services. Mar 12 20:15:09 hifi systemd[1]: Started Volumio Backend Module. Mar 12 20:15:09 hifi systemd[1]: Started Volumio Cpu Tweaker. Mar 12 20:15:09 hifi volumio-cpu-tweak[993]: Setting RT Priority for mpd Mar 12 20:15:09 hifi volumio-cpu-tweak[993]: Setting MPD Affinity Mar 12 20:15:09 hifi volumio-cpu-tweak[993]: pid 792's current affinity mask: 1 Mar 12 20:15:09 hifi volumio-cpu-tweak[993]: pid 792's new affinity mask: 1 Mar 12 20:15:09 hifi volumio-cpu-tweak[993]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Mar 12 20:15:09 hifi volumio-cpu-tweak[993]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Mar 12 20:15:09 hifi volumio-time-update[592]: volumio-time-update-util: Setting system time to: 2025-03-12 20:15:36 Mar 12 20:15:09 hifi sudo[1010]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-03-12 20:15:36 Mar 12 20:15:09 hifi sudo[1010]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 20:15:09 hifi systemd[1]: volumio_cpu_tweak.service: Succeeded. Mar 12 20:15:09 hifi dbus-daemon[619]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.9' (uid=0 pid=1011 comm="timedatectl set-time 2025-03-12 20:15:36 ") Mar 12 20:15:10 hifi systemd[1]: Starting Time & Date Service... Mar 12 20:15:10 hifi dbus-daemon[619]: [system] Successfully activated service 'org.freedesktop.timedate1' Mar 12 20:15:10 hifi systemd[1]: Started Time & Date Service. Mar 12 20:15:36 hifi systemd-timedated[1012]: Changed local time to Wed Mar 12 20:15:36 2025 Mar 12 20:15:36 hifi sudo[1010]: pam_unix(sudo:session): session closed for user root Mar 12 20:15:36 hifi volumio-time-update[592]: volumio-time-update-util: System time updated successfully. Mar 12 20:15:36 hifi systemd[1]: Started Volumio Time Update Utility. Mar 12 20:15:36 hifi ntpd[783]: Listen normally on 3 wlan0 192.168.45.65:123 Mar 12 20:15:36 hifi ntpd[783]: new interface(s) found: waking up resolver Mar 12 20:15:36 hifi nmbd[751]: [2025/03/12 20:15:36.753364, 0] ../lib/util/become_daemon.c:138(daemon_ready) Mar 12 20:15:36 hifi systemd[1]: Started Samba NMB Daemon. Mar 12 20:15:36 hifi nmbd[751]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Mar 12 20:15:36 hifi systemd[1]: Starting Samba Winbind Daemon... Mar 12 20:15:37 hifi winbindd[1013]: [2025/03/12 20:15:37.033089, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Mar 12 20:15:37 hifi winbindd[1013]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Mar 12 20:15:37 hifi volumio-remote-updater[583]: [2025-03-12 20:15:37] [connect] Successful connection Mar 12 20:15:37 hifi winbindd[1013]: [2025/03/12 20:15:37.634973, 0] ../lib/util/become_daemon.c:138(daemon_ready) Mar 12 20:15:37 hifi systemd[1]: Started Samba Winbind Daemon. Mar 12 20:15:37 hifi winbindd[1013]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Mar 12 20:15:37 hifi systemd[1]: Starting Samba SMB Daemon... Mar 12 20:15:38 hifi volumio[992]: info: ------------------------------------------- Mar 12 20:15:38 hifi volumio[992]: info: ----- Volumio3 ---- Mar 12 20:15:38 hifi volumio[992]: info: ------------------------------------------- Mar 12 20:15:38 hifi volumio[992]: info: ----- System startup ---- Mar 12 20:15:38 hifi volumio[992]: info: ------------------------------------------- Mar 12 20:15:38 hifi smbd[1018]: [2025/03/12 20:15:38.038285, 0] ../lib/util/become_daemon.c:138(daemon_ready) Mar 12 20:15:38 hifi systemd[1]: Started Samba SMB Daemon. Mar 12 20:15:38 hifi smbd[1018]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Mar 12 20:15:38 hifi systemd[1]: Reached target Multi-User System. Mar 12 20:15:38 hifi systemd[1]: Reached target Graphical Interface. Mar 12 20:15:38 hifi systemd[1]: Starting Update UTMP about System Runlevel Changes... Mar 12 20:15:38 hifi systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Mar 12 20:15:38 hifi systemd[1]: Started Update UTMP about System Runlevel Changes. Mar 12 20:15:38 hifi systemd[1]: Startup finished in 11.951s (kernel) + 27.212s (userspace) = 39.164s. Mar 12 20:15:38 hifi volumio[992]: info: MYVOLUMIO Environment detected Mar 12 20:15:38 hifi volumio[992]: info: Plugin folders cleanup Mar 12 20:15:38 hifi volumio[992]: info: Scanning into folder /volumio/app/plugins/ Mar 12 20:15:38 hifi volumio[992]: info: Scanning category audio_interface Mar 12 20:15:38 hifi volumio[992]: info: Scanning category miscellanea Mar 12 20:15:38 hifi volumio[992]: info: Scanning category music_service Mar 12 20:15:38 hifi volumio[992]: info: Scanning category plugins.json Mar 12 20:15:38 hifi volumio[992]: info: Scanning category system_controller Mar 12 20:15:38 hifi volumio[992]: info: Scanning category user_interface Mar 12 20:15:38 hifi volumio[992]: info: Scanning into folder /data/plugins/ Mar 12 20:15:38 hifi volumio[992]: info: Scanning category music_service Mar 12 20:15:38 hifi volumio[992]: info: Scanning category system_controller Mar 12 20:15:38 hifi volumio[992]: info: Scanning category system_hardware Mar 12 20:15:38 hifi volumio[992]: info: Scanning category user_interface Mar 12 20:15:38 hifi volumio[992]: info: Plugin folders cleanup completed Mar 12 20:15:38 hifi volumio[992]: info: ------------------------------------------- Mar 12 20:15:38 hifi volumio[992]: info: ----- Core plugins startup ---- Mar 12 20:15:38 hifi volumio[992]: info: ------------------------------------------- Mar 12 20:15:38 hifi volumio[992]: info: Loading plugins from folder /volumio/app/plugins/ Mar 12 20:15:38 hifi volumio[992]: info: Adding plugin upnp to MyMusic Plugins Mar 12 20:15:38 hifi volumio[992]: info: Adding plugin airplay_emulation to MyMusic Plugins Mar 12 20:15:38 hifi volumio[992]: info: Adding plugin upnp_browser to MyMusic Plugins Mar 12 20:15:38 hifi volumio[992]: info: Loading plugins from folder /data/plugins/ Mar 12 20:15:38 hifi volumio[992]: info: Loading plugin "system"... Mar 12 20:15:38 hifi volumio[992]: info: Loading plugin "ampswitch"... Mar 12 20:15:39 hifi volumio[992]: info: Applying required configuration parameters for plugin ampswitch Mar 12 20:15:39 hifi volumio[992]: info: Loading plugin "appearance"... Mar 12 20:15:40 hifi volumio[992]: info: Loading plugin "network"... Mar 12 20:15:40 hifi volumio[992]: info: Refreshing Cached IP Addresses Mar 12 20:15:40 hifi sudo[1045]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 12 20:15:40 hifi sudo[1045]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 20:15:40 hifi sudo[1047]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 12 20:15:40 hifi sudo[1047]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 20:15:40 hifi sudo[1045]: pam_unix(sudo:session): session closed for user root Mar 12 20:15:40 hifi sudo[1047]: pam_unix(sudo:session): session closed for user root Mar 12 20:15:40 hifi volumio[992]: info: Loading plugin "services"... Mar 12 20:15:40 hifi volumio[992]: info: Loading plugin "alsa_controller"... Mar 12 20:15:40 hifi sudo[1053]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Mar 12 20:15:40 hifi sudo[1053]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 20:15:40 hifi volumio[992]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 12 20:15:40 hifi volumio[992]: info: Loading plugin "wizard"... Mar 12 20:15:40 hifi volumio[992]: info: Loading plugin "networkfs"... Mar 12 20:15:40 hifi volumio[992]: info: Starting Udev Watcher for removable devices Mar 12 20:15:40 hifi sudo[1073]: 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.45.4/Music /mnt/NAS/Media Mar 12 20:15:40 hifi sudo[1073]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 20:15:40 hifi volumio[992]: info: Ignoring mount for partition: boot Mar 12 20:15:40 hifi volumio[992]: info: Ignoring mount for partition: volumio Mar 12 20:15:40 hifi volumio[992]: info: Ignoring mount for partition: volumio_data Mar 12 20:15:40 hifi volumio[992]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 12 20:15:40 hifi volumio[992]: info: Loading plugin "volumio_command_line_client"... Mar 12 20:15:40 hifi volumio[992]: info: Loading plugin "upnp"... Mar 12 20:15:40 hifi volumio[992]: info: [1741803340391] Starting Upmpd Daemon Mar 12 20:15:40 hifi volumio[992]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 12 20:15:40 hifi volumio[992]: info: Loading plugin "my_music"... Mar 12 20:15:40 hifi volumio[992]: info: Loading plugin "mpd"... Mar 12 20:15:40 hifi kernel: Key type cifs.spnego registered Mar 12 20:15:40 hifi kernel: Key type cifs.idmap registered Mar 12 20:15:40 hifi 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. Mar 12 20:15:40 hifi kernel: CIFS: Attempting to mount //192.168.45.4/Music Mar 12 20:15:40 hifi kernel: cryptd: max_cpu_qlen set to 1000 Mar 12 20:15:40 hifi volumio[992]: info: Loading plugin "upnp_browser"... Mar 12 20:15:40 hifi volumio[992]: info: Loading plugin "alarm-clock"... Mar 12 20:15:41 hifi volumio[992]: info: Loading plugin "airplay_emulation"... Mar 12 20:15:41 hifi volumio[992]: info: Starting Shairport Sync Mar 12 20:15:41 hifi volumio[992]: info: Loading plugin "last_100"... Mar 12 20:15:41 hifi volumio[992]: info: Loading plugin "webradio"... Mar 12 20:15:41 hifi sudo[1073]: pam_unix(sudo:session): session closed for user root Mar 12 20:15:41 hifi volumio[992]: info: Loading plugin "i2s_dacs"... Mar 12 20:15:41 hifi volumio[992]: info: I2S DAC not set, start Auto-detection Mar 12 20:15:41 hifi volumio[992]: info: Loading plugin "volumiodiscovery"... Mar 12 20:15:41 hifi volumio[992]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 12 20:15:41 hifi node[992]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 12 20:15:41 hifi volumio[992]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 12 20:15:41 hifi volumio[992]: *** WARNING *** For more information see Mar 12 20:15:41 hifi volumio[992]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 12 20:15:41 hifi volumio[992]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 12 20:15:41 hifi volumio[992]: *** WARNING *** For more information see Mar 12 20:15:41 hifi node[992]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 12 20:15:41 hifi node[992]: *** WARNING *** For more information see Mar 12 20:15:41 hifi node[992]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 12 20:15:41 hifi node[992]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 12 20:15:41 hifi node[992]: *** WARNING *** For more information see Mar 12 20:15:41 hifi volumio[992]: info: Applying required configuration parameters for plugin volumiodiscovery Mar 12 20:15:41 hifi volumio[992]: info: Discovery: Started advertising with name: HiFi Mar 12 20:15:41 hifi volumio[992]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 12 20:15:41 hifi volumio[992]: info: Loading plugin "mixcloud"... Mar 12 20:15:41 hifi volumio[992]: info: Loading plugin "soundcloud"... Mar 12 20:15:42 hifi volumio[992]: info: Loading plugin "spop"... Mar 12 20:15:43 hifi volumio[992]: info: Loading plugin "ytmusic"... Mar 12 20:15:44 hifi systemd[1]: systemd-fsckd.service: Succeeded. Mar 12 20:15:44 hifi sudo[1053]: pam_unix(sudo:session): session closed for user root Mar 12 20:15:44 hifi volumio[992]: info: Loading plugin "outputs"... Mar 12 20:15:44 hifi volumio[992]: info: Loading plugin "albumart"... Mar 12 20:15:44 hifi volumio[992]: info: Plugin example_plugin is not enabled Mar 12 20:15:44 hifi volumio[992]: info: Loading plugin "inputs"... Mar 12 20:15:44 hifi volumio[992]: info: Loading plugin "updater_comm"... Mar 12 20:15:44 hifi volumio[992]: info: Plugin mpdemulation is not enabled Mar 12 20:15:44 hifi volumio[992]: info: Loading plugin "rest_api"... Mar 12 20:15:44 hifi volumio[992]: info: Loading plugin "websocket"... Mar 12 20:15:44 hifi sh[499]: timed out Mar 12 20:15:44 hifi dhcpcd[551]: timed out Mar 12 20:15:44 hifi sh[499]: dhcpcd exited Mar 12 20:15:44 hifi dhcpcd[551]: dhcpcd exited Mar 12 20:15:44 hifi sh[499]: ifup: failed to bring up eth0 Mar 12 20:15:44 hifi volumio[992]: info: Starting Socket.io Server version 2.3.0 Mar 12 20:15:44 hifi volumio[992]: info: Loading plugin "radio_paradise"... Mar 12 20:15:44 hifi volumio[992]: Forking 3 albumart workers Mar 12 20:15:44 hifi systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Mar 12 20:15:44 hifi systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Mar 12 20:15:45 hifi volumio[992]: info: Applying required configuration parameters for plugin radio_paradise Mar 12 20:15:45 hifi volumio[992]: info: [1741803345203] [RadioParadise] API delay: 5 Mar 12 20:15:45 hifi volumio[992]: info: Loading plugin "music_services_shield"... Mar 12 20:15:45 hifi volumio[992]: Starting albumart workers Mar 12 20:15:45 hifi volumio[992]: info: Applying required configuration parameters for plugin music_services_shield Mar 12 20:15:45 hifi volumio[992]: info: Loading plugin "randomizer"... Mar 12 20:15:45 hifi volumio[992]: Starting albumart workers Mar 12 20:15:45 hifi volumio[992]: Starting albumart workers Mar 12 20:15:45 hifi volumio[992]: info: Applying required configuration parameters for plugin randomizer Mar 12 20:15:46 hifi volumio[992]: info: Loading plugin "touch_display"... Mar 12 20:15:46 hifi systemd[1]: systemd-hostnamed.service: Succeeded. Mar 12 20:15:46 hifi volumio[992]: info: Applying required configuration parameters for plugin touch_display Mar 12 20:15:46 hifi volumio[992]: info: Loading i18n strings for locale en Mar 12 20:15:46 hifi volumio[992]: Updating browse sources language Mar 12 20:15:46 hifi volumio[992]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 12 20:15:46 hifi volumio[992]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 12 20:15:46 hifi volumio[992]: info: CoreCommandRouter::initPlayerControls Mar 12 20:15:46 hifi volumio[992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 20:15:46 hifi volumio[992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 20:15:46 hifi volumio[992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 20:15:46 hifi volumio[992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 20:15:46 hifi volumio[992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 20:15:46 hifi volumio[992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 20:15:46 hifi volumio[992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 20:15:46 hifi volumio[992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 20:15:46 hifi volumio[992]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 12 20:15:46 hifi volumio[992]: Express server listening on port 3000 Mar 12 20:15:46 hifi volumio[992]: [Metrics] WebUI: 9s 877.01ms Mar 12 20:15:46 hifi volumio[992]: info: CoreStateMachine::resetVolumioState Mar 12 20:15:46 hifi volumio[992]: info: CoreStateMachine::getcurrentVolume Mar 12 20:15:46 hifi volumio[992]: info: CoreCommandRouter::volumioRetrievevolume Mar 12 20:15:46 hifi volumio[992]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 12 20:15:46 hifi volumio[992]: info: Volumio Network Manager: Network status updated: 2 Mar 12 20:15:46 hifi volumio[992]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Mar 12 20:15:46 hifi volumio[992]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Mar 12 20:15:46 hifi volumio[992]: info: Reloading queue from file Mar 12 20:15:46 hifi volumio[992]: info: VolumeController:: Volume=47 Mute =false Mar 12 20:15:46 hifi volumio[992]: info: CoreStateMachine::pushState Mar 12 20:15:46 hifi volumio[992]: info: CorePlayQueue::getTrack 0 Mar 12 20:15:46 hifi volumio[992]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 12 20:15:46 hifi volumio[992]: info: CoreCommandRouter::volumioPushState Mar 12 20:15:46 hifi volumio[992]: info: CoreStateMachine::updateTrackBlock Mar 12 20:15:46 hifi volumio[992]: info: CorePlayQueue::getTrackBlock Mar 12 20:15:46 hifi volumio[992]: info: CoreCommandRouter::volumioRetrievevolume Mar 12 20:15:46 hifi volumio[992]: info: Setting Device type: Raspberry PI Mar 12 20:15:46 hifi volumio[992]: info: CoreStateMachine::setRepeat null single undefined Mar 12 20:15:46 hifi volumio[992]: info: CoreStateMachine::pushState Mar 12 20:15:46 hifi volumio[992]: info: CorePlayQueue::getTrack 0 Mar 12 20:15:46 hifi volumio[992]: info: CoreCommandRouter::volumioPushState Mar 12 20:15:46 hifi volumio[992]: info: CoreStateMachine::setRandom true Mar 12 20:15:46 hifi volumio[992]: info: CoreStateMachine::pushState Mar 12 20:15:46 hifi volumio[992]: info: CorePlayQueue::getTrack 0 Mar 12 20:15:46 hifi volumio[992]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 12 20:15:46 hifi volumio[992]: info: CoreCommandRouter::volumioPushState Mar 12 20:15:46 hifi volumio[992]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Mar 12 20:15:46 hifi volumio[992]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03114 Mar 12 20:15:46 hifi volumio[992]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Mar 12 20:15:46 hifi volumio[992]: info: Discovery: adding 1d2206b0-83be-45c8-9caf-4cf9f591a134 Mar 12 20:15:46 hifi volumio[992]: info: Discovery: Found device HiFi Mar 12 20:15:46 hifi volumio[992]: info: CoreCommandRouter::volumioGetState Mar 12 20:15:46 hifi volumio[992]: info: CorePlayQueue::getTrack 0 Mar 12 20:15:46 hifi volumio[992]: info: VolumeController:: Volume=47 Mute =false Mar 12 20:15:46 hifi volumio[992]: info: CoreStateMachine::pushState Mar 12 20:15:46 hifi volumio[992]: info: CorePlayQueue::getTrack 0 Mar 12 20:15:46 hifi volumio[992]: info: CoreCommandRouter::volumioPushState Mar 12 20:15:46 hifi volumio[992]: info: Completed loading Core Plugins Mar 12 20:15:46 hifi volumio[992]: info: Preparing to generate the ALSA configuration file Mar 12 20:15:47 hifi volumio[992]: info: Asound.conf file unchanged, so no further update is needed Mar 12 20:15:47 hifi volumio[992]: info: Output device has changed, restarting MPD Mar 12 20:15:47 hifi sudo[1182]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 12 20:15:47 hifi volumio[992]: info: Output device has changed, restarting Shairport Sync Mar 12 20:15:47 hifi sudo[1182]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 20:15:47 hifi volumio[992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 20:15:47 hifi volumio[992]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 12 20:15:47 hifi sudo[1182]: pam_unix(sudo:session): session closed for user root Mar 12 20:15:47 hifi sudo[1184]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 12 20:15:47 hifi sudo[1184]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 20:15:47 hifi systemd[1]: musicservicesshield.service: Succeeded. Mar 12 20:15:47 hifi systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Mar 12 20:15:47 hifi systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Mar 12 20:15:47 hifi systemd[1]: Stopping Music Player Daemon... Mar 12 20:15:47 hifi volumio[992]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 12 20:15:47 hifi volumio[992]: info: ___________ START PLUGINS ___________ Mar 12 20:15:47 hifi volumio[992]: info: ControllerMpd::onStart: Initializing MPD Mar 12 20:15:47 hifi volumio[992]: info: Creating MPD Configuration file Mar 12 20:15:47 hifi sudo[1202]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 12 20:15:47 hifi sudo[1202]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 20:15:47 hifi volumio[992]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 12 20:15:47 hifi volumio[992]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 12 20:15:47 hifi volumio[992]: info: [1741803347170] CoreMusicLibrary::Adding element Media Servers Mar 12 20:15:47 hifi volumio[992]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 12 20:15:47 hifi sudo[1202]: pam_unix(sudo:session): session closed for user root Mar 12 20:15:47 hifi sudo[1204]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 12 20:15:47 hifi sudo[1204]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 20:15:47 hifi volumio[992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 20:15:47 hifi volumio[992]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 12 20:15:47 hifi systemd[1]: mpd.service: Succeeded. Mar 12 20:15:47 hifi systemd[1]: Stopped Music Player Daemon. Mar 12 20:15:47 hifi volumio[992]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 12 20:15:47 hifi volumio[992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 20:15:47 hifi volumio[992]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 12 20:15:47 hifi volumio[992]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 12 20:15:47 hifi volumio[992]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 12 20:15:47 hifi volumio[992]: info: [1741803347257] CoreMusicLibrary::Adding element Last_100 Mar 12 20:15:47 hifi volumio[992]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 12 20:15:47 hifi volumio[992]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 12 20:15:47 hifi volumio[992]: info: [1741803347260] CoreMusicLibrary::Adding element Webradio Mar 12 20:15:47 hifi volumio[992]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 12 20:15:47 hifi volumio[992]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 12 20:15:47 hifi volumio[992]: info: Initializing BBC Radios Mar 12 20:15:47 hifi systemd[1]: Starting Music Player Daemon... Mar 12 20:15:47 hifi systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Mar 12 20:15:47 hifi systemd[1]: mpd.service: Succeeded. Mar 12 20:15:47 hifi systemd[1]: Stopped Music Player Daemon. Mar 12 20:15:47 hifi systemd[1]: Starting Music Player Daemon... Mar 12 20:15:47 hifi volumio[992]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 12 20:15:47 hifi volumio[992]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 12 20:15:47 hifi volumio[992]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 12 20:15:47 hifi volumio[992]: info: [1741803347341] CoreMusicLibrary::Adding element Mixcloud Mar 12 20:15:47 hifi volumio[992]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 12 20:15:47 hifi volumio[992]: Cannot find translation for source Mixcloud Mar 12 20:15:47 hifi volumio[992]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 12 20:15:47 hifi volumio[992]: info: [1741803347351] CoreMusicLibrary::Adding element SoundCloud Mar 12 20:15:47 hifi volumio[992]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 12 20:15:47 hifi volumio[992]: Cannot find translation for source Mixcloud Mar 12 20:15:47 hifi volumio[992]: Cannot find translation for source SoundCloud Mar 12 20:15:47 hifi sudo[1216]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 12 20:15:47 hifi sudo[1216]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 20:15:47 hifi volumio[992]: info: Creating Spotify config file Mar 12 20:15:47 hifi sudo[1216]: pam_unix(sudo:session): session closed for user root Mar 12 20:15:47 hifi volumio[992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 20:15:47 hifi volumio[992]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 12 20:15:47 hifi volumio[992]: info: [1741803347415] CoreMusicLibrary::Adding element YouTube Music Mar 12 20:15:47 hifi volumio[992]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 12 20:15:47 hifi volumio[992]: Cannot find translation for source Mixcloud Mar 12 20:15:47 hifi volumio[992]: Cannot find translation for source SoundCloud Mar 12 20:15:47 hifi volumio[992]: Cannot find translation for source YouTube Music Mar 12 20:15:47 hifi volumio[992]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 12 20:15:47 hifi volumio[992]: info: [1741803347422] CoreMusicLibrary::Adding element Radio Paradise Mar 12 20:15:47 hifi volumio[992]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 12 20:15:47 hifi volumio[992]: Cannot find translation for source Mixcloud Mar 12 20:15:47 hifi volumio[992]: Cannot find translation for source SoundCloud Mar 12 20:15:47 hifi volumio[992]: Cannot find translation for source YouTube Music Mar 12 20:15:47 hifi volumio[992]: Cannot find translation for source Radio Paradise Mar 12 20:15:47 hifi volumio[992]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 12 20:15:47 hifi volumio[992]: info: [1741803347592] CoreMusicLibrary::Adding element Randomizer Mar 12 20:15:47 hifi volumio[992]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 12 20:15:47 hifi volumio[992]: Cannot find translation for source Mixcloud Mar 12 20:15:47 hifi volumio[992]: Cannot find translation for source SoundCloud Mar 12 20:15:47 hifi volumio[992]: Cannot find translation for source YouTube Music Mar 12 20:15:47 hifi volumio[992]: Cannot find translation for source Radio Paradise Mar 12 20:15:47 hifi volumio[992]: Cannot find translation for source Randomizer Mar 12 20:15:47 hifi volumio[992]: info: Loading i18n strings for locale en Mar 12 20:15:47 hifi volumio[992]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 12 20:15:47 hifi volumio[992]: info: Volumio Calling Home Mar 12 20:15:47 hifi sudo[1253]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/moveallprocesses.sh Mar 12 20:15:47 hifi sudo[1253]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 20:15:47 hifi sudo[1268]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Mar 12 20:15:47 hifi sudo[1268]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 20:15:47 hifi volumio[992]: info: touch_display: Backlight interface detected. Mar 12 20:15:47 hifi volumio[992]: info: CoreCommandRouter::volumioGetState Mar 12 20:15:47 hifi volumio[992]: info: CorePlayQueue::getTrack 0 Mar 12 20:15:47 hifi sudo[1269]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Mar 12 20:15:47 hifi sudo[1269]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 20:15:47 hifi volumio[992]: info: [ASDebug] CurState: stop PrevState: na Mar 12 20:15:47 hifi volumio[992]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Mar 12 20:15:47 hifi volumio[992]: info: [ASDebug] CurState: stop PrevState: na Mar 12 20:15:47 hifi volumio[992]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Mar 12 20:15:47 hifi sudo[1268]: pam_unix(sudo:session): session closed for user root Mar 12 20:15:47 hifi volumio[992]: info: MPD Permissions set Mar 12 20:15:47 hifi volumio[992]: info: MPD Permissions set Mar 12 20:15:47 hifi sudo[1272]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Mar 12 20:15:47 hifi sudo[1272]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 20:15:47 hifi systemd[1]: Reloading. Mar 12 20:15:47 hifi volumio[992]: info: touch_display: systemctl stop getty@tty1.service succeeded. Mar 12 20:15:47 hifi volumio[992]: info: Spotify config file written Mar 12 20:15:47 hifi volumio[992]: info: CoreCommandRouter::volumioGetState Mar 12 20:15:47 hifi volumio[992]: info: CorePlayQueue::getTrack 0 Mar 12 20:15:47 hifi sudo[1276]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Mar 12 20:15:47 hifi sudo[1276]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 20:15:47 hifi volumio[992]: info: CoreCommandRouter::volumioGetState Mar 12 20:15:47 hifi volumio[992]: info: CorePlayQueue::getTrack 0 Mar 12 20:15:47 hifi volumio[992]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Mar 12 20:15:47 hifi volumio[992]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Mar 12 20:15:47 hifi volumio[992]: info: Volumio called home Mar 12 20:15:47 hifi volumio[992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 20:15:48 hifi volumio[992]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 12 20:15:48 hifi volumio[992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 20:15:48 hifi volumio[992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 20:15:48 hifi volumio[992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 20:15:48 hifi volumio[992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 20:15:48 hifi volumio[992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 20:15:48 hifi volumio[992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 20:15:48 hifi volumio[992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 20:15:48 hifi volumio[992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 20:15:48 hifi volumio[992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 20:15:48 hifi volumio[992]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 12 20:15:48 hifi volumio[992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 20:15:48 hifi volumio[992]: info: No need to fix Spotify hosts Mar 12 20:15:48 hifi volumio[992]: info: Listing playlists Mar 12 20:15:48 hifi volumio[992]: info: Listing playlists Mar 12 20:15:48 hifi volumio[992]: info: Listing playlists Mar 12 20:15:48 hifi volumio[992]: info: Listing playlists Mar 12 20:15:48 hifi 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. Mar 12 20:15:48 hifi sudo[1303]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+w /sys/class/backlight/rpi_backlight/brightness Mar 12 20:15:48 hifi sudo[1303]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 20:15:48 hifi mpd[1226]: Mar 12 20:15 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 12 20:15:48 hifi volumio[992]: info: touch_display: Raspberry Pi Foundation touch screen detected. Mar 12 20:15:48 hifi sudo[1303]: pam_unix(sudo:session): session closed for user root Mar 12 20:15:48 hifi sudo[1306]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Mar 12 20:15:48 hifi sudo[1306]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 20:15:48 hifi volumio[992]: info: Starting Shairport Sync Mar 12 20:15:48 hifi volumio[992]: info: Starting Shairport Sync Mar 12 20:15:48 hifi sudo[1306]: pam_unix(sudo:session): session closed for user root Mar 12 20:15:48 hifi sudo[1309]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 12 20:15:48 hifi sudo[1309]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 20:15:48 hifi volumio[992]: info: Starting Shairport Sync Mar 12 20:15:48 hifi sudo[1312]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 12 20:15:48 hifi sudo[1312]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 20:15:48 hifi volumio[992]: info: CoreCommandRouter::volumioGetState Mar 12 20:15:48 hifi volumio[992]: info: CorePlayQueue::getTrack 0 Mar 12 20:15:48 hifi volumio[992]: info: touch_display: File permissions for backlight brightness control set. Mar 12 20:15:48 hifi sudo[1315]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 12 20:15:48 hifi sudo[1315]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 20:15:48 hifi volumio[992]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Mar 12 20:15:48 hifi volumio[992]: info: [ASDebug] Togle GPIO: OFF Mar 12 20:15:48 hifi volumio[992]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Mar 12 20:15:48 hifi volumio[992]: SPOTIFY: BQBTZeUhr5vioxS8Oh13Ck5BpMRZrB8QNFrzD-_7LG_YrU-XwYhPfzti2xwqq0gDnOXcH8yqcUfgD3CAA9JpwMgHLzKLX7EAqQe9lgFO-KKKqf9BD1cANgFEmYqTixBBTkGD5pGsGVEfM3swFBIUsaYFSEg9PkmZ6MstVP4mtnLv-jWVgOUUp5xHim6QcJZhKGRKoUgRJnsQyNY8GHsH1YJr2UP8jhtS7ZZ9otfKf-7UoiErP7wF5DepLmxvW_0_Xggqluvyt8sIdc8 Mar 12 20:15:48 hifi volumio[992]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Mar 12 20:15:48 hifi volumio[992]: info: New Spotify access token = BQBTZeUhr5vioxS8Oh13Ck5BpMRZrB8QNFrzD-_7LG_YrU-XwYhPfzti2xwqq0gDnOXcH8yqcUfgD3CAA9JpwMgHLzKLX7EAqQe9lgFO-KKKqf9BD1cANgFEmYqTixBBTkGD5pGsGVEfM3swFBIUsaYFSEg9PkmZ6MstVP4mtnLv-jWVgOUUp5xHim6QcJZhKGRKoUgRJnsQyNY8GHsH1YJr2UP8jhtS7ZZ9otfKf-7UoiErP7wF5DepLmxvW_0_Xggqluvyt8sIdc8 Mar 12 20:15:48 hifi volumio[992]: info: Spotify credentials grant success - running version from March 24, 2019 Mar 12 20:15:48 hifi systemd[1]: Started Music Player Daemon. Mar 12 20:15:48 hifi sudo[1204]: pam_unix(sudo:session): session closed for user root Mar 12 20:15:48 hifi sudo[1184]: pam_unix(sudo:session): session closed for user root Mar 12 20:15:48 hifi sudo[1269]: pam_unix(sudo:session): session closed for user root Mar 12 20:15:48 hifi systemd[1]: Reloading. Mar 12 20:15:48 hifi volumio[992]: info: touch_display: systemctl disable getty@tty1.service succeeded. Mar 12 20:15:48 hifi sudo[1253]: pam_unix(sudo:session): session closed for user root Mar 12 20:15:48 hifi sudo[1334]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/addservice.sh Mar 12 20:15:48 hifi sudo[1334]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 20:15:48 hifi volumio[992]: error: MPD error: The expression evaluated to a falsy value: Mar 12 20:15:48 hifi volumio[992]: assert.ok(self.idling) Mar 12 20:15:48 hifi volumio[992]: error: The expression evaluated to a falsy value: Mar 12 20:15:48 hifi volumio[992]: assert.ok(self.idling) Mar 12 20:15:48 hifi volumio[992]: info: MPD running with PID1226 Mar 12 20:15:48 hifi volumio[992]: ,establishing connection Mar 12 20:15:48 hifi volumio[992]: error: updateQueue error: null Mar 12 20:15:48 hifi volumio[992]: error: updateQueue error: null Mar 12 20:15:48 hifi volumio[992]: SPOTIFY: User informations: {"country":"IL","display_name":"Ran Ramati","email":"ran.ramati@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/ranch78"},"followers":{"href":null,"total":14},"href":"https://api.spotify.com/v1/users/ranch78","id":"ranch78","images":[{"height":300,"url":"https://scontent-waw2-2.xx.fbcdn.net/v/t1.6435-1/128527301_10157952136351242_216992296228260597_n.jpg?stp=dst-jpg_s320x320_tt6&_nc_cat=100&ccb=1-7&_nc_sid=79bf43&_nc_ohc=xpH_OpBHp5YQ7kNvgF-7nUg&_nc_oc=Adh4upAo-IDvuj2PBqC2AhrHTLX3rtIZK2IYka0dA925fSxCAEnBX_kiQDcb6SESbstLoe11Y8FUf_pr6GXwOG-R&_nc_zt=24&_nc_ht=scontent-waw2-2.xx&edm=AP4hL3IEAAAA&_nc_gid=APULFS7v_R_1CoZf_dMINT9&oh=00_AYHnvPLTF3Nb8XTlopERi7YaLPPFp4Kxd8atfmq4lZXA8A&oe=67F92421","width":300},{"height":64,"url":"https://scontent-waw2-2.xx.fbcdn.net/v/t1.6435-1/128527301_10157952136351242_216992296228260597_n.jpg?stp=cp0_dst-jpg_s50x50_tt6&_nc_cat=100&ccb=1-7&_nc_sid=fe756c&_nc_ohc=xpH_OpBHp5YQ7kNvgF-7nUg&_nc_oc=Adh4upAo-IDvuj2PBqC2AhrHTLX3rtIZK2IYka0dA925fSxCAEnBX_kiQDcb6SESbstLoe11Y8FUf_pr6GXwOG-R&_nc_zt=24&_nc_ht=scontent-waw2-2.xx&edm=AP4hL3IEAAAA&_nc_gid=APULFS7v_R_1CoZf_dMINT9&oh=00_AYGjrhh1wLrKMXBWBdrwEyCXCU6pfLeR-JCL4qrIJHkdwg&oe=67F92421","width":64}],"product":"premium","type":"user","uri":"spotify:user:ranch78"} Mar 12 20:15:48 hifi volumio[992]: info: Spotify Successfully logged in Mar 12 20:15:48 hifi volumio[992]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 12 20:15:48 hifi volumio[992]: info: [1741803348894] CoreMusicLibrary::Adding element Spotify Mar 12 20:15:48 hifi volumio[992]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 12 20:15:48 hifi volumio[992]: Cannot find translation for source Mixcloud Mar 12 20:15:48 hifi volumio[992]: Cannot find translation for source SoundCloud Mar 12 20:15:48 hifi volumio[992]: Cannot find translation for source YouTube Music Mar 12 20:15:48 hifi volumio[992]: Cannot find translation for source Radio Paradise Mar 12 20:15:48 hifi volumio[992]: Cannot find translation for source Randomizer Mar 12 20:15:48 hifi volumio[992]: Cannot find translation for source Spotify Mar 12 20:15:48 hifi 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. Mar 12 20:15:49 hifi sudo[1272]: pam_unix(sudo:session): session closed for user root Mar 12 20:15:49 hifi volumio[992]: info: touch_display: systemctl daemon-reload succeeded. Mar 12 20:15:49 hifi sudo[1346]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Mar 12 20:15:49 hifi sudo[1346]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 20:15:49 hifi systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 12 20:15:49 hifi systemd[1]: Started go-librespot Daemon. Mar 12 20:15:49 hifi sudo[1276]: pam_unix(sudo:session): session closed for user root Mar 12 20:15:49 hifi go-librespot[1348]: go-librespot daemon starting... Mar 12 20:15:49 hifi systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Mar 12 20:15:49 hifi systemd[1]: Starting Daily apt download activities... Mar 12 20:15:49 hifi systemd[1]: shairport-sync.service: Succeeded. Mar 12 20:15:49 hifi systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 12 20:15:49 hifi systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 12 20:15:49 hifi sudo[1309]: pam_unix(sudo:session): session closed for user root Mar 12 20:15:49 hifi sudo[1315]: pam_unix(sudo:session): session closed for user root Mar 12 20:15:49 hifi sudo[1312]: pam_unix(sudo:session): session closed for user root Mar 12 20:15:49 hifi volumio[992]: info: Shairport-Sync Started Mar 12 20:15:49 hifi volumio[992]: Error adding Membership: Error: addMembership EINVAL Mar 12 20:15:49 hifi volumio[992]: info: Shairport-Sync Started Mar 12 20:15:49 hifi systemd[1]: Started Volumio Kiosk. Mar 12 20:15:49 hifi sudo[1346]: pam_unix(sudo:session): session closed for user root Mar 12 20:15:49 hifi volumio[992]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Mar 12 20:15:49 hifi volumio[992]: info: touch_display: Volumio Kiosk started. Mar 12 20:15:49 hifi volumio[992]: info: CoreCommandRouter::volumioGetState Mar 12 20:15:49 hifi volumio[992]: info: CorePlayQueue::getTrack 0 Mar 12 20:15:49 hifi volumio[992]: info: Completed starting Core Plugins Mar 12 20:15:49 hifi volumio[992]: info: ------------------------------------------- Mar 12 20:15:49 hifi volumio[992]: info: ----- MyVolumio plugins startup ---- Mar 12 20:15:49 hifi volumio[992]: info: ------------------------------------------- Mar 12 20:15:49 hifi volumio[992]: info: [MyVolumio PluginManager] Fetching plans data.... Mar 12 20:15:49 hifi volumio[992]: info: CoreCommandRouter::volumioGetState Mar 12 20:15:49 hifi volumio[992]: info: CorePlayQueue::getTrack 0 Mar 12 20:15:49 hifi volumio[992]: info: touch_display: X display number found: Mar 12 20:15:49 hifi volumio[992]: info: touch_display: X display number found: 0 Mar 12 20:15:49 hifi volumio[992]: info: touch_display: X display number found: 0 Mar 12 20:15:49 hifi startx[1361]: X.Org X Server 1.20.4 Mar 12 20:15:49 hifi startx[1361]: X Protocol Version 11, Revision 0 Mar 12 20:15:49 hifi startx[1361]: Build Operating System: Linux 5.4.0-109-generic armv8l Raspbian Mar 12 20:15:49 hifi startx[1361]: Current Operating System: Linux hifi 6.6.62-v7l+ #1816 SMP Mon Nov 18 15:33:33 GMT 2024 armv7l Mar 12 20:15:49 hifi startx[1361]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 cgroup_disable=memory numa_policy=interleave snd_bcm2835.enable_headphones=1 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=800 bcm2708_fb.fbheight=480 bcm2708_fb.fbswap=1 smsc95xx.macaddr=DC:A6:32:CF:6F:FE 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=db7775c5-9234-4b9a-955e-7341a245d498 imgfile=/volumio_current.sqsh bootpart=UUID=F102-DE29 datapart=UUID=4112086d-38d3-4206-a7af-ef93bf4e4008 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 Mar 12 20:15:49 hifi startx[1361]: Build Date: 04 April 2023 07:50:56AM Mar 12 20:15:49 hifi startx[1361]: xorg-server 2:1.20.4-1+rpt4+deb10u9 (https://www.debian.org/support) Mar 12 20:15:49 hifi startx[1361]: Current version of pixman: 0.36.0 Mar 12 20:15:49 hifi startx[1361]: Before reporting problems, check http://wiki.x.org Mar 12 20:15:49 hifi startx[1361]: to make sure that you have the latest version. Mar 12 20:15:49 hifi startx[1361]: Markers: (--) probed, (**) from config file, (==) default setting, Mar 12 20:15:49 hifi startx[1361]: (++) from command line, (!!) notice, (II) informational, Mar 12 20:15:49 hifi startx[1361]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Mar 12 20:15:49 hifi startx[1361]: (==) Log file: "/var/log/Xorg.0.log", Time: Wed Mar 12 20:15:49 2025 Mar 12 20:15:49 hifi volumio[1349]: cset: --> shielding system active with Mar 12 20:15:49 hifi volumio[1349]: cset: "system" cpuset of CPUSPEC(1-3) with 116 tasks running Mar 12 20:15:49 hifi volumio[1349]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Mar 12 20:15:49 hifi systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Mar 12 20:15:49 hifi startx[1361]: (==) Using config directory: "/etc/X11/xorg.conf.d" Mar 12 20:15:49 hifi startx[1361]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Mar 12 20:15:49 hifi systemd[1]: Reloading. Mar 12 20:15:49 hifi go-librespot[1348]: time="2025-03-12T20:15:49+02:00" level=info msg="running go-librespot 0.2.0" Mar 12 20:15:49 hifi go-librespot[1348]: time="2025-03-12T20:15:49+02:00" level=debug msg="app state loaded" Mar 12 20:15:49 hifi go-librespot[1348]: time="2025-03-12T20:15:49+02:00" level=debug msg="stored credentials not found" Mar 12 20:15:49 hifi go-librespot[1348]: time="2025-03-12T20:15:49+02:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 12 20:15:49 hifi volumio[992]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Mar 12 20:15:50 hifi 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. Mar 12 20:15:50 hifi sudo[1455]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 12 20:15:50 hifi sudo[1455]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 20:15:50 hifi sudo[1455]: pam_unix(sudo:session): session closed for user root Mar 12 20:15:50 hifi sudo[1458]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 12 20:15:50 hifi sudo[1458]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 20:15:50 hifi sudo[1458]: pam_unix(sudo:session): session closed for user root Mar 12 20:15:50 hifi sudo[1461]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Mar 12 20:15:50 hifi sudo[1461]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 20:15:50 hifi go-librespot[1348]: time="2025-03-12T20:15:50+02:00" 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]" Mar 12 20:15:50 hifi go-librespot[1348]: time="2025-03-12T20:15:50+02:00" 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]" Mar 12 20:15:50 hifi go-librespot[1348]: time="2025-03-12T20:15:50+02:00" 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]" Mar 12 20:15:50 hifi go-librespot[1348]: time="2025-03-12T20:15:50+02:00" level=info msg="zeroconf server listening on port 44955" Mar 12 20:15:50 hifi go-librespot[1348]: time="2025-03-12T20:15:50+02:00" level=debug msg="obtained new client token: AAB9xQvD7Fes0iWFxyO5OMG38Lk99tSIWOA0ybWUxmuq7SvxSTAB2/jmvmTKysgehNPLipkwp+dbeZ5QzASSuMEeaUBaIY3eFekqZ7wpXYv+8gNg/ciU6ahQNu65TanSbXrYY9GW14gywkZFpIcXhKJL4YbNCLYGyUetOCFhMOVfwfSrUcdHnkLLEmC/OgU++3znYgj1VXmTHmNiAPJsAiwEq+4I93qf56YraimKsjGDIep5hqYHI2Pq" Mar 12 20:15:50 hifi sudo[1334]: pam_unix(sudo:session): session closed for user root Mar 12 20:15:50 hifi volumio[992]: info: succeeded Mar 12 20:15:50 hifi systemd[1]: Started UPnP Renderer front-end to MPD. Mar 12 20:15:50 hifi volumio[992]: info: touch_display: X display number found: 0 Mar 12 20:15:50 hifi sudo[1461]: pam_unix(sudo:session): session closed for user root Mar 12 20:15:50 hifi volumio[992]: info: Upmpdcli Daemon Started Mar 12 20:15:50 hifi go-librespot[1348]: time="2025-03-12T20:15:50+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Mar 12 20:15:50 hifi go-librespot[1348]: time="2025-03-12T20:15:50+02:00" level=debug msg="completed keyexchange" Mar 12 20:15:50 hifi go-librespot[1348]: time="2025-03-12T20:15:50+02:00" level=debug msg="completed challenge" Mar 12 20:15:51 hifi go-librespot[1348]: time="2025-03-12T20:15:51+02:00" level=info msg="authenticated AP as ranch78" Mar 12 20:15:51 hifi volumio[1463]: Generating RSA private key, 4096 bit long modulus (2 primes) Mar 12 20:15:51 hifi go-librespot[1348]: time="2025-03-12T20:15:51+02:00" level=info msg="authenticated Login5 as ranch78" Mar 12 20:15:51 hifi go-librespot[1348]: time="2025-03-12T20:15:51+02:00" level=debug msg="stored credentials for ranch78" Mar 12 20:15:51 hifi go-librespot[1348]: time="2025-03-12T20:15:51+02:00" level=debug msg="initializing zeroconf session, username: ranch78" Mar 12 20:15:51 hifi go-librespot[1348]: time="2025-03-12T20:15:51+02:00" level=debug msg="dealer connection opened" Mar 12 20:15:51 hifi go-librespot[1348]: time="2025-03-12T20:15:51+02:00" level=trace msg="starting accesspoint recv loop" Mar 12 20:15:51 hifi go-librespot[1348]: time="2025-03-12T20:15:51+02:00" level=trace msg="starting dealer recv loop" Mar 12 20:15:51 hifi go-librespot[1348]: time="2025-03-12T20:15:51+02:00" level=trace msg="received accesspoint ping" Mar 12 20:15:51 hifi go-librespot[1348]: time="2025-03-12T20:15:51+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Mar 12 20:15:51 hifi go-librespot[1348]: time="2025-03-12T20:15:51+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Mar 12 20:15:51 hifi go-librespot[1348]: time="2025-03-12T20:15:51+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Mar 12 20:15:51 hifi go-librespot[1348]: time="2025-03-12T20:15:51+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Mar 12 20:15:51 hifi go-librespot[1348]: time="2025-03-12T20:15:51+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Mar 12 20:15:51 hifi go-librespot[1348]: time="2025-03-12T20:15:51+02:00" level=debug msg="received connection id: NGFjOTdiY2MtOTRkNy00NzYwLTgzNmQtYjliYThlOWQyZDBmK2RlYWxlcit0Y3A6Ly8wYWNhNDA5Yi5pcC5nZXcxLnNwb3RpZnkubmV0OjU3MDArN0VCNUUxRThGMjNDOTE0MkI4MzUyRUEyNkMwMDJCM0IyRTA2NjFDQTI4NEY1NTJEQUEwOUU2MTI2MzdFNDZGRQ==" Mar 12 20:15:51 hifi go-librespot[1348]: time="2025-03-12T20:15:51+02:00" level=trace msg="received accesspoint pong ack" Mar 12 20:15:51 hifi go-librespot[1348]: time="2025-03-12T20:15:51+02:00" level=debug msg="put connect state because NEW_DEVICE" Mar 12 20:15:52 hifi volumio[992]: info: touch_display: Setting screensaver timeout to 120 seconds. Mar 12 20:15:52 hifi volumio[992]: info: go-librespot daemon successfully initialized Mar 12 20:15:52 hifi volumio-remote-updater[583]: [2025-03-12 20:15:52] [connect] Successful connection Mar 12 20:15:52 hifi volumio[992]: 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: 5 Mar 12 20:15:52 hifi volumio-remote-updater[583]: [2025-03-12 20:15:52] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1741803352 101 Mar 12 20:15:52 hifi systemd[1]: apt-daily.service: Succeeded. Mar 12 20:15:52 hifi systemd[1]: Started Daily apt download activities. Mar 12 20:15:53 hifi volumio[992]: info: CoreCommandRouter::volumioGetState Mar 12 20:15:53 hifi volumio[992]: info: CorePlayQueue::getTrack 0 Mar 12 20:15:53 hifi volumio[992]: info: CoreCommandRouter::volumioGetState Mar 12 20:15:53 hifi volumio[992]: info: CorePlayQueue::getTrack 0 Mar 12 20:15:54 hifi volumio[992]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Mar 12 20:15:55 hifi volumio[1463]: .........................................++++ Mar 12 20:15:55 hifi volumio[992]: info: Initializing connection to go-librespot Websocket Mar 12 20:15:55 hifi go-librespot[1348]: time="2025-03-12T20:15:55+02:00" level=debug msg="new websocket client" Mar 12 20:15:55 hifi volumio[992]: info: Connection to go-librespot Websocket established Mar 12 20:15:56 hifi go-librespot[1348]: time="2025-03-12T20:15:56+02:00" level=debug msg="handling transfer player command from 8298f97d03c0f6e75bd2a4334e945b675959a56d" Mar 12 20:15:56 hifi go-librespot[1348]: time="2025-03-12T20:15:56+02:00" level=debug msg="resolved context of track" uri="spotify:playlist:37i9dQZF1E8PgkhsnvZ6ME" Mar 12 20:15:56 hifi go-librespot[1348]: time="2025-03-12T20:15:56+02:00" level=trace msg="fetched new page 0 with 50 items (list: 50)" uri="spotify:playlist:37i9dQZF1E8PgkhsnvZ6ME" Mar 12 20:15:56 hifi go-librespot[1348]: time="2025-03-12T20:15:56+02:00" level=debug msg="shuffled context with seed 17529908074488431798 (len: 50, keep: 24)" uri="spotify:playlist:37i9dQZF1E8PgkhsnvZ6ME" Mar 12 20:15:56 hifi go-librespot[1348]: time="2025-03-12T20:15:56+02:00" level=debug msg="loading track (paused: true, position: 660ms)" uri="spotify:track:35ISBknsCeZQtq66xABI9g" Mar 12 20:15:57 hifi go-librespot[1348]: time="2025-03-12T20:15:57+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Mar 12 20:15:57 hifi go-librespot[1348]: time="2025-03-12T20:15:57+02:00" level=trace msg="emitting websocket event: will_play" Mar 12 20:15:57 hifi volumio[992]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:35ISBknsCeZQtq66xABI9g","play_origin":"playlist/ondemand"}} Mar 12 20:15:57 hifi go-librespot[1348]: time="2025-03-12T20:15:57+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 345" Mar 12 20:15:57 hifi go-librespot[1348]: time="2025-03-12T20:15:57+02:00" level=debug msg="selected format OGG_VORBIS_320 (74f97df5ac794f08bd461486ca5d900d8207dcd0)" uri="spotify:track:35ISBknsCeZQtq66xABI9g" Mar 12 20:15:57 hifi go-librespot[1348]: time="2025-03-12T20:15:57+02:00" level=debug msg="requested aes key for file 74f97df5ac794f08bd461486ca5d900d8207dcd0, gid: 35ISBknsCeZQtq66xABI9g" Mar 12 20:15:57 hifi go-librespot[1348]: time="2025-03-12T20:15:57+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 3163" Mar 12 20:15:57 hifi go-librespot[1348]: time="2025-03-12T20:15:57+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 2024" Mar 12 20:15:57 hifi volumio[1463]: ............................++++ Mar 12 20:15:57 hifi volumio[1463]: e is 65537 (0x010001) Mar 12 20:15:57 hifi volumio[1463]: writing RSA key Mar 12 20:15:58 hifi go-librespot[1348]: time="2025-03-12T20:15:58+02:00" level=debug msg="fetched first chunk of 22, total size is 11089178 bytes" uri="spotify:track:35ISBknsCeZQtq66xABI9g" Mar 12 20:15:58 hifi go-librespot[1348]: time="2025-03-12T20:15:58+02:00" level=trace msg="seek to 660ms (diff: 200ms, samples: 29106, bytes: 5443)" uri="spotify:track:35ISBknsCeZQtq66xABI9g" Mar 12 20:15:58 hifi go-librespot[1348]: time="2025-03-12T20:15:58+02:00" level=debug msg="created new output device" Mar 12 20:15:58 hifi go-librespot[1348]: time="2025-03-12T20:15:58+02:00" level=info msg="loaded track \"Messy\" (paused: true, position: 660ms, duration: 284066ms, prefetched: false)" uri="spotify:track:35ISBknsCeZQtq66xABI9g" Mar 12 20:15:58 hifi volumio[992]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Mar 12 20:15:58 hifi volumio[992]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Mar 12 20:15:58 hifi volumio[992]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Mar 12 20:15:58 hifi volumio[992]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Mar 12 20:15:58 hifi volumio[992]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Mar 12 20:15:58 hifi volumio[992]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Mar 12 20:15:58 hifi volumio[992]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Mar 12 20:15:58 hifi volumio[992]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Mar 12 20:15:58 hifi volumio[992]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Mar 12 20:15:58 hifi volumio[992]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Mar 12 20:15:58 hifi volumio[992]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Mar 12 20:15:58 hifi volumio[992]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Mar 12 20:15:58 hifi volumio[992]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Mar 12 20:15:58 hifi volumio[992]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Mar 12 20:15:58 hifi volumio[992]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Mar 12 20:15:58 hifi volumio[992]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Mar 12 20:15:58 hifi volumio[992]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Mar 12 20:15:58 hifi volumio[992]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Mar 12 20:15:58 hifi volumio[992]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Mar 12 20:15:58 hifi volumio[992]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Mar 12 20:15:58 hifi volumio[992]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Mar 12 20:15:58 hifi volumio[992]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Mar 12 20:15:58 hifi volumio[992]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Mar 12 20:15:58 hifi volumio[992]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Mar 12 20:15:58 hifi volumio[992]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Mar 12 20:15:58 hifi volumio[992]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Mar 12 20:15:58 hifi volumio[992]: info: Adding plugin bluetooth to MyMusic Plugins Mar 12 20:15:58 hifi volumio[992]: info: Adding plugin multiroom to MyMusic Plugins Mar 12 20:15:58 hifi volumio[992]: info: Adding plugin metavolumio to MyMusic Plugins Mar 12 20:15:58 hifi volumio[992]: info: Adding plugin cd_controller to MyMusic Plugins Mar 12 20:15:58 hifi volumio[992]: info: Adding plugin smart_inputs to MyMusic Plugins Mar 12 20:15:58 hifi volumio[992]: info: Adding plugin tidalconnect to MyMusic Plugins Mar 12 20:15:58 hifi volumio[992]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Mar 12 20:15:58 hifi go-librespot[1348]: time="2025-03-12T20:15:58+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Mar 12 20:15:58 hifi go-librespot[1348]: time="2025-03-12T20:15:58+02:00" level=trace msg="emitting websocket event: metadata" Mar 12 20:15:58 hifi go-librespot[1348]: time="2025-03-12T20:15:58+02:00" level=trace msg="emitting websocket event: active" Mar 12 20:15:58 hifi go-librespot[1348]: time="2025-03-12T20:15:58+02:00" level=debug msg="sending successful reply for dealer request" Mar 12 20:15:58 hifi go-librespot[1348]: time="2025-03-12T20:15:58+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Mar 12 20:15:58 hifi go-librespot[1348]: time="2025-03-12T20:15:58+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Mar 12 20:15:58 hifi go-librespot[1348]: time="2025-03-12T20:15:58+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Mar 12 20:15:58 hifi go-librespot[1348]: time="2025-03-12T20:15:58+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Mar 12 20:15:58 hifi go-librespot[1348]: time="2025-03-12T20:15:58+02:00" level=trace msg="emitting websocket event: paused" Mar 12 20:15:58 hifi go-librespot[1348]: time="2025-03-12T20:15:58+02:00" level=debug msg="fetched chunk 3/21, size: 524288" uri="spotify:track:35ISBknsCeZQtq66xABI9g" Mar 12 20:15:58 hifi go-librespot[1348]: time="2025-03-12T20:15:58+02:00" level=debug msg="fetched chunk 2/21, size: 524288" uri="spotify:track:35ISBknsCeZQtq66xABI9g" Mar 12 20:15:58 hifi go-librespot[1348]: time="2025-03-12T20:15:58+02:00" level=debug msg="fetched chunk 1/21, size: 524288" uri="spotify:track:35ISBknsCeZQtq66xABI9g" Mar 12 20:15:59 hifi go-librespot[1348]: time="2025-03-12T20:15:59+02:00" level=debug msg="handling resume player command from 8298f97d03c0f6e75bd2a4334e945b675959a56d" Mar 12 20:15:59 hifi go-librespot[1348]: time="2025-03-12T20:15:59+02:00" level=trace msg="seek to 660ms (diff: 200ms, samples: 29106, bytes: 5443)" uri="spotify:track:35ISBknsCeZQtq66xABI9g" Mar 12 20:15:59 hifi volumio[992]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Mar 12 20:15:59 hifi volumio[992]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Mar 12 20:15:59 hifi go-librespot[1348]: time="2025-03-12T20:15:59+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" Mar 12 20:15:59 hifi volumio[992]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 12 20:15:59 hifi go-librespot[1348]: time="2025-03-12T20:15:59+02:00" level=debug msg="resume track at 460ms" Mar 12 20:15:59 hifi volumio[992]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 12 20:15:59 hifi volumio[992]: info: Starting MyVolumio Remote Streaming Endpoints Mar 12 20:15:59 hifi volumio[992]: info: MyVolumio login type: Token Mar 12 20:15:59 hifi volumio[992]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Mar 12 20:15:59 hifi volumio[992]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Mar 12 20:15:59 hifi go-librespot[1348]: time="2025-03-12T20:15:59+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Mar 12 20:15:59 hifi go-librespot[1348]: time="2025-03-12T20:15:59+02:00" level=trace msg="scheduling prefetch in 253s" Mar 12 20:15:59 hifi go-librespot[1348]: time="2025-03-12T20:15:59+02:00" level=debug msg="sending successful reply for dealer request" Mar 12 20:15:59 hifi go-librespot[1348]: time="2025-03-12T20:15:59+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Mar 12 20:15:59 hifi go-librespot[1348]: time="2025-03-12T20:15:59+02:00" level=trace msg="emitting websocket event: playing" Mar 12 20:16:00 hifi volumio[992]: info: Starting Streaming Service Transparent Proxy Mar 12 20:16:00 hifi volumio[992]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Mar 12 20:16:00 hifi volumio[992]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Mar 12 20:16:00 hifi volumio[992]: info: Streaming services startup Mar 12 20:16:00 hifi volumio[992]: info: Starting Streaming Daemon Mar 12 20:16:00 hifi sudo[1729]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 12 20:16:00 hifi sudo[1729]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 20:16:00 hifi sudo[1729]: pam_unix(sudo:session): session closed for user root Mar 12 20:16:00 hifi volumio[992]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Mar 12 20:16:00 hifi volumio[992]: info: Getting Spotify volume Mar 12 20:16:00 hifi volumio[992]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:35ISBknsCeZQtq66xABI9g","name":"Messy","artist_names":["Lola Young"],"album_name":"This Wasn't Meant For You Anyway","album_cover_url":"https://i.scdn.co/image/ab67616d00001e026aa2a180c7b009ca8454ef89","position":660,"duration":284066,"release_date":"year:2024 month:6 day:21","track_number":5,"disc_number":1}} Mar 12 20:16:00 hifi volumio[992]: SPOTIFY: received: {"type":"active","data":null} Mar 12 20:16:00 hifi volumio[992]: info: Aligning Spotify Volume to Volumio Volume Mar 12 20:16:00 hifi volumio[992]: info: CoreCommandRouter::volumioGetState Mar 12 20:16:00 hifi volumio[992]: info: CorePlayQueue::getTrack 0 Mar 12 20:16:00 hifi volumio[992]: info: Setting Spotify Volume from Volumio: 47 Mar 12 20:16:00 hifi volumio[992]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:35ISBknsCeZQtq66xABI9g","play_origin":"playlist/ondemand"}} Mar 12 20:16:00 hifi volumio[992]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 12 20:16:00 hifi volumio[992]: TypeError: Cannot read property 'service' of undefined Mar 12 20:16:00 hifi volumio[992]: at ControllerSpotify.identifyPlaybackMode (/data/plugins/music_service/spop/index.js:340:50) Mar 12 20:16:00 hifi volumio[992]: at ControllerSpotify.parseEventState (/data/plugins/music_service/spop/index.js:274:18) Mar 12 20:16:00 hifi volumio[992]: at WebSocket.message (/data/plugins/music_service/spop/index.js:193:14) Mar 12 20:16:00 hifi volumio[992]: at WebSocket.emit (events.js:315:20) Mar 12 20:16:00 hifi volumio[992]: at Receiver.receiverOnMessage (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1220:20) Mar 12 20:16:00 hifi volumio[992]: at Receiver.emit (events.js:315:20) Mar 12 20:16:00 hifi volumio[992]: at Receiver.dataMessage (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:596:14) Mar 12 20:16:00 hifi volumio[992]: at Receiver.getData (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:496:10) Mar 12 20:16:00 hifi volumio[992]: at Receiver.startLoop (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:167:16) Mar 12 20:16:00 hifi volumio[992]: at Receiver._write (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:94:10) Mar 12 20:16:00 hifi volumio[992]: at writeOrBuffer (internal/streams/writable.js:358:12) Mar 12 20:16:00 hifi volumio[992]: at Receiver.Writable.write (internal/streams/writable.js:303:10) Mar 12 20:16:00 hifi volumio[992]: at Socket.socketOnData (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1355:35) Mar 12 20:16:00 hifi volumio[992]: at Socket.emit (events.js:315:20) Mar 12 20:16:00 hifi volumio[992]: at addChunk (internal/streams/readable.js:309:12) Mar 12 20:16:00 hifi volumio[992]: at readableAddChunk (internal/streams/readable.js:284:9) Mar 12 20:16:00 hifi volumio[992]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 12 20:16:00 hifi sudo[1754]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-12 20:15 Mar 12 20:16:00 hifi sudo[1754]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="a01a833dd28483f88cb390596f5090b7187992af" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 27 Feb 2025 11:42:54 AM CET" VOLUMIO_VERSION="3.795" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4049a67f1c09d4a94e033ba35a80a144"