-- Logs begin at Sun 2025-01-19 22:17:01 CET, end at Sun 2025-01-19 23:04:43 CET. -- Jan 19 23:04:22 imperum-pml systemd[1233]: Time has been changed Jan 19 23:04:22 imperum-pml systemd[1]: Time has been changed Jan 19 23:04:23 imperum-pml volumio[1688]: info: Plugin folders cleanup Jan 19 23:04:23 imperum-pml volumio[1688]: info: Scanning into folder /volumio/app/plugins/ Jan 19 23:04:23 imperum-pml volumio[1688]: info: Scanning category audio_interface Jan 19 23:04:23 imperum-pml volumio[1688]: info: Scanning category miscellanea Jan 19 23:04:23 imperum-pml volumio[1688]: info: Scanning category music_service Jan 19 23:04:23 imperum-pml volumio[1688]: info: Scanning category plugins.json Jan 19 23:04:23 imperum-pml volumio[1688]: info: Scanning category system_controller Jan 19 23:04:23 imperum-pml volumio[1688]: info: Scanning category user_interface Jan 19 23:04:23 imperum-pml volumio[1688]: info: Scanning into folder /data/plugins/ Jan 19 23:04:23 imperum-pml volumio[1688]: info: Scanning category music_service Jan 19 23:04:23 imperum-pml volumio[1688]: info: Plugin folders cleanup completed Jan 19 23:04:23 imperum-pml volumio[1688]: info: ------------------------------------------- Jan 19 23:04:23 imperum-pml volumio[1688]: info: ----- Core plugins startup ---- Jan 19 23:04:23 imperum-pml volumio[1688]: info: ------------------------------------------- Jan 19 23:04:23 imperum-pml volumio[1688]: info: Loading plugins from folder /volumio/app/plugins/ Jan 19 23:04:23 imperum-pml volumio[1688]: info: Adding plugin upnp to MyMusic Plugins Jan 19 23:04:23 imperum-pml volumio[1688]: info: Adding plugin airplay_emulation to MyMusic Plugins Jan 19 23:04:23 imperum-pml volumio[1688]: info: Adding plugin upnp_browser to MyMusic Plugins Jan 19 23:04:23 imperum-pml volumio[1688]: info: Loading plugins from folder /data/plugins/ Jan 19 23:04:23 imperum-pml volumio[1688]: info: Loading plugin "system"... Jan 19 23:04:23 imperum-pml volumio[1688]: info: Loading plugin "appearance"... Jan 19 23:04:25 imperum-pml volumio[1688]: info: Loading plugin "network"... Jan 19 23:04:25 imperum-pml volumio[1688]: info: Loading plugin "services"... Jan 19 23:04:25 imperum-pml volumio[1688]: info: Loading plugin "alsa_controller"... Jan 19 23:04:25 imperum-pml dhcpd[1731]: DHCPREQUEST for 192.168.1.153 from b8:27:eb:07:21:25 via wlan0: ignored (not authoritative). Jan 19 23:04:25 imperum-pml volumio[1688]: info: Loading plugin "wizard"... Jan 19 23:04:25 imperum-pml dhcpcd[1397]: wlan0: soliciting a DHCP lease Jan 19 23:04:25 imperum-pml dhcpd[1731]: DHCPDISCOVER from b8:27:eb:07:21:25 via wlan0 Jan 19 23:04:25 imperum-pml volumio[1688]: info: Loading plugin "volumio_command_line_client"... Jan 19 23:04:25 imperum-pml volumio[1688]: info: Loading plugin "upnp"... Jan 19 23:04:25 imperum-pml volumio[1688]: info: [1737324265413] Starting Upmpd Daemon Jan 19 23:04:25 imperum-pml volumio[1688]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 19 23:04:25 imperum-pml volumio[1688]: info: Loading plugin "cardplay_monitor"... Jan 19 23:04:25 imperum-pml volumio[1688]: info: Loading plugin "fakeAlsaController"... Jan 19 23:04:25 imperum-pml volumio[1688]: info: Loading plugin "fake_spop"... Jan 19 23:04:25 imperum-pml volumio[1688]: info: Loading plugin "mainmenu"... Jan 19 23:04:25 imperum-pml volumio[1688]: info: Loading plugin "menuOptions"... Jan 19 23:04:26 imperum-pml dhcpd[1731]: DHCPOFFER on 192.168.211.9 to b8:27:eb:07:21:25 (imperum-pml) via wlan0 Jan 19 23:04:26 imperum-pml volumio[1688]: info: Loading plugin "my_music"... Jan 19 23:04:26 imperum-pml volumio[1688]: info: Loading plugin "user_data"... Jan 19 23:04:26 imperum-pml volumio[1688]: info: Loading plugin "mpd"... Jan 19 23:04:26 imperum-pml volumio[1688]: info: Loading plugin "upnp_browser"... Jan 19 23:04:27 imperum-pml volumio[1688]: info: Loading plugin "networkfs"... Jan 19 23:04:27 imperum-pml volumio[1688]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 19 23:04:27 imperum-pml volumio[1688]: info: Loading plugin "alarm-clock"... Jan 19 23:04:28 imperum-pml volumio[1688]: info: Loading plugin "airplay_emulation"... Jan 19 23:04:28 imperum-pml volumio[1688]: info: Starting Shairport Sync Jan 19 23:04:28 imperum-pml volumio[1688]: info: Loading plugin "last_100"... Jan 19 23:04:28 imperum-pml volumio[1688]: info: Loading plugin "webradio"... Jan 19 23:04:28 imperum-pml volumio[1688]: info: Loading plugin "i2s_dacs"... Jan 19 23:04:28 imperum-pml volumio[1688]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 19 23:04:28 imperum-pml volumio[1688]: info: Loading plugin "volumiodiscovery"... Jan 19 23:04:28 imperum-pml volumio[1688]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 19 23:04:28 imperum-pml node[1688]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 19 23:04:28 imperum-pml node[1688]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 19 23:04:28 imperum-pml node[1688]: *** WARNING *** For more information see Jan 19 23:04:28 imperum-pml node[1688]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 19 23:04:28 imperum-pml node[1688]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 19 23:04:28 imperum-pml node[1688]: *** WARNING *** For more information see Jan 19 23:04:28 imperum-pml volumio[1688]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 19 23:04:28 imperum-pml volumio[1688]: *** WARNING *** For more information see Jan 19 23:04:28 imperum-pml volumio[1688]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 19 23:04:28 imperum-pml volumio[1688]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 19 23:04:28 imperum-pml volumio[1688]: *** WARNING *** For more information see Jan 19 23:04:28 imperum-pml volumio[1688]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 19 23:04:28 imperum-pml volumio[1688]: Discovery: StartAdv! undefined Jan 19 23:04:28 imperum-pml volumio[1688]: Discovery: Started advertising... imperum-pml - undefined Jan 19 23:04:28 imperum-pml volumio[1688]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 19 23:04:28 imperum-pml volumio[1688]: info: Loading plugin "outputs"... Jan 19 23:04:28 imperum-pml volumio[1688]: info: Loading plugin "albumart"... Jan 19 23:04:28 imperum-pml volumio[1688]: info: Plugin example_plugin is not enabled Jan 19 23:04:28 imperum-pml volumio[1688]: info: Loading plugin "inputs"... Jan 19 23:04:28 imperum-pml volumio[1688]: info: Loading plugin "initialCheck"... Jan 19 23:04:28 imperum-pml volumio[1688]: info: MYVOLUMIO Environment detected Jan 19 23:04:28 imperum-pml sudo[1799]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/vcgencmd display_power 0 Jan 19 23:04:28 imperum-pml sudo[1799]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 23:04:28 imperum-pml sudo[1801]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd_aloop Jan 19 23:04:28 imperum-pml volumio[1688]: info: Loading plugin "installCheck"... Jan 19 23:04:28 imperum-pml sudo[1801]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 23:04:28 imperum-pml volumio[1688]: info: Loading plugin "updater_comm"... Jan 19 23:04:28 imperum-pml sudo[1801]: pam_unix(sudo:session): session closed for user root Jan 19 23:04:28 imperum-pml sudo[1799]: pam_unix(sudo:session): session closed for user root Jan 19 23:04:28 imperum-pml volumio[1688]: info: Plugin mpdemulation is not enabled Jan 19 23:04:28 imperum-pml volumio[1688]: info: Loading plugin "rest_api"... Jan 19 23:04:28 imperum-pml volumio[1688]: Forking 3 albumart workers Jan 19 23:04:28 imperum-pml volumio[1688]: info: Loading plugin "websocket"... Jan 19 23:04:28 imperum-pml volumio[1688]: info: Loading plugin "spop"... Jan 19 23:04:29 imperum-pml dhcpd[1731]: DHCPDISCOVER from b8:27:eb:07:21:25 (imperum-pml) via wlan0 Jan 19 23:04:29 imperum-pml dhcpd[1731]: DHCPOFFER on 192.168.211.9 to b8:27:eb:07:21:25 (imperum-pml) via wlan0 Jan 19 23:04:29 imperum-pml volumio[1688]: Starting albumart workers Jan 19 23:04:29 imperum-pml volumio[1688]: Starting albumart workers Jan 19 23:04:29 imperum-pml volumio[1688]: Starting albumart workers Jan 19 23:04:30 imperum-pml kernel: brcmfmac: brcmf_escan_timeout: timer expired Jan 19 23:04:30 imperum-pml sudo[1721]: pam_unix(sudo:session): session closed for user root Jan 19 23:04:30 imperum-pml sudo[1860]: volumio : TTY=unknown ; PWD=/home/volumio/tcnimperum/bin ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Jan 19 23:04:30 imperum-pml sudo[1860]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 23:04:30 imperum-pml systemd[1]: Stopping Wireless Services... Jan 19 23:04:30 imperum-pml dhcpcd[1397]: received SIGTERM from PID 1, stopping Jan 19 23:04:30 imperum-pml dhcpcd[1397]: wlan0: removing interface Jan 19 23:04:30 imperum-pml dhcpcd[1397]: eth0: adding route to 172.16.0.0/24 Jan 19 23:04:30 imperum-pml dhcpcd[1397]: eth0: adding default route via 172.16.0.1 Jan 19 23:04:30 imperum-pml dhcpcd[1397]: eth0: removing interface Jan 19 23:04:30 imperum-pml dhcpcd[1397]: eth0: deleting host route to 172.16.0.157 via 127.0.0.1 Jan 19 23:04:30 imperum-pml dhcpcd[1397]: eth0: deleting route to 172.16.0.0/24 Jan 19 23:04:30 imperum-pml dhcpcd[1397]: eth0: deleting default route via 172.16.0.1 Jan 19 23:04:30 imperum-pml dhcpcd[644]: eth0: removing IP address 172.16.0.157/24 Jan 19 23:04:30 imperum-pml avahi-daemon[803]: Withdrawing address record for 172.16.0.157 on eth0. Jan 19 23:04:30 imperum-pml avahi-daemon[803]: Leaving mDNS multicast group on interface eth0.IPv4 with address 172.16.0.157. Jan 19 23:04:30 imperum-pml avahi-daemon[803]: Interface eth0.IPv4 no longer relevant for mDNS. Jan 19 23:04:30 imperum-pml dhcpcd[1397]: exited Jan 19 23:04:30 imperum-pml systemd[1]: wireless.service: main process exited, code=exited, status=1/FAILURE Jan 19 23:04:30 imperum-pml systemd[1]: Unit wireless.service entered failed state. Jan 19 23:04:30 imperum-pml systemd[1]: Starting Wireless Services... Jan 19 23:04:30 imperum-pml sudo[1904]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 set power_save off Jan 19 23:04:30 imperum-pml sudo[1904]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 23:04:30 imperum-pml sudo[1904]: pam_unix(sudo:session): session closed for user root Jan 19 23:04:30 imperum-pml volumio-remote-updater[746]: [2025-01-19 23:04:30] [connect] Successful connection Jan 19 23:04:31 imperum-pml wireless.js[1913]: Cleaning previous... Jan 19 23:04:31 imperum-pml systemd[1]: Stopping dhcpd.service... Jan 19 23:04:31 imperum-pml systemd[1]: Stopping Host and Network Name Lookups. Jan 19 23:04:31 imperum-pml systemd[1]: Stopped target Host and Network Name Lookups. Jan 19 23:04:31 imperum-pml systemd[1]: Stopping dnsmasq.service... Jan 19 23:04:31 imperum-pml dnsmasq[1706]: exiting on receipt of SIGTERM Jan 19 23:04:31 imperum-pml systemd[1]: Stopped dnsmasq.service. Jan 19 23:04:31 imperum-pml volumio[1688]: info: Loading plugin "volspotconnect2"... Jan 19 23:04:31 imperum-pml volumio[1688]: info: Loading plugin "zoutputDevice"... Jan 19 23:04:31 imperum-pml systemd[1]: Stopped dhcpd.service. Jan 19 23:04:31 imperum-pml systemd[1]: Stopping hotspot.service... Jan 19 23:04:31 imperum-pml volumio[1688]: info: CoreCommandRouter::executeOnPlugin: spop , rebuildSPOPDAndRestartDaemon Jan 19 23:04:31 imperum-pml volumio[1688]: info: CoreCommandRouter::executeOnPlugin: volspotconnect2 , rebuildRestartDaemon Jan 19 23:04:31 imperum-pml volumio[1688]: Creating VLS config file Jan 19 23:04:31 imperum-pml volumio[1688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 23:04:31 imperum-pml volumio[1688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 23:04:31 imperum-pml volumio[1688]: info: CoreCommandRouter::executeOnPlugin: mpd , mpdInit Jan 19 23:04:31 imperum-pml volumio[1688]: info: Creating MPD Configuration file Jan 19 23:04:31 imperum-pml sudo[1926]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 19 23:04:31 imperum-pml sudo[1926]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 23:04:31 imperum-pml volumio[1688]: info: ___________ START PLUGINS ___________ Jan 19 23:04:31 imperum-pml sudo[1928]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 19 23:04:31 imperum-pml sudo[1926]: pam_unix(sudo:session): session closed for user root Jan 19 23:04:31 imperum-pml volumio[1688]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 19 23:04:31 imperum-pml volumio[1688]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 19 23:04:31 imperum-pml volumio[1688]: info: [1737324271698] CoreMusicLibrary::Adding element Media Servers Jan 19 23:04:31 imperum-pml volumio[1688]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 19 23:04:31 imperum-pml sudo[1928]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 23:04:31 imperum-pml systemd[1]: Stopping Music Player Daemon... Jan 19 23:04:31 imperum-pml ntpd[938]: Deleting interface #3 eth0, 172.16.0.157#123, interface stats: received=27, sent=27, dropped=4, active_time=16 secs Jan 19 23:04:31 imperum-pml ntpd[938]: 178.255.228.77 interface 172.16.0.157 -> (none) Jan 19 23:04:31 imperum-pml ntpd[938]: 162.159.200.123 interface 172.16.0.157 -> (none) Jan 19 23:04:31 imperum-pml ntpd[938]: 185.198.109.227 interface 172.16.0.157 -> (none) Jan 19 23:04:31 imperum-pml ntpd[938]: 158.227.98.15 interface 172.16.0.157 -> (none) Jan 19 23:04:31 imperum-pml ntpd[938]: peers refreshed Jan 19 23:04:31 imperum-pml volumio[1688]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 19 23:04:31 imperum-pml volumio[1688]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 19 23:04:31 imperum-pml volumio[1688]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 19 23:04:31 imperum-pml volumio[1688]: info: [1737324271907] CoreMusicLibrary::Adding element Last_100 Jan 19 23:04:31 imperum-pml volumio[1688]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 19 23:04:31 imperum-pml volumio[1688]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 19 23:04:31 imperum-pml volumio[1688]: info: [1737324271912] CoreMusicLibrary::Adding element Webradio Jan 19 23:04:31 imperum-pml volumio[1688]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 19 23:04:31 imperum-pml volumio[1688]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 19 23:04:31 imperum-pml systemd[1]: Starting Music Player Daemon... Jan 19 23:04:31 imperum-pml volumio[1688]: Creating VLS config file Jan 19 23:04:31 imperum-pml volumio[1688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 23:04:31 imperum-pml volumio[1688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 23:04:31 imperum-pml volumio[1688]: [SpotifyConnect] Starting metadata listener Jan 19 23:04:31 imperum-pml volumio[1688]: info: ------------------------------------------- Jan 19 23:04:31 imperum-pml volumio[1688]: info: ----- MyVolumio plugins startup ---- Jan 19 23:04:31 imperum-pml volumio[1688]: info: ------------------------------------------- Jan 19 23:04:31 imperum-pml volumio[1688]: info: [MyVolumio PluginManager] Fetching plans data.... Jan 19 23:04:31 imperum-pml sudo[1948]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start spop.service Jan 19 23:04:31 imperum-pml volumio[1688]: info: Loading i18n strings for locale es Jan 19 23:04:32 imperum-pml volumio[1688]: Updating browse sources language Jan 19 23:04:32 imperum-pml volumio[1688]: Cannot find translation for sourceMedia Servers Jan 19 23:04:32 imperum-pml volumio[1688]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 19 23:04:32 imperum-pml sudo[1951]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect2.service Jan 19 23:04:32 imperum-pml sudo[1948]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 23:04:32 imperum-pml sudo[1951]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 23:04:32 imperum-pml systemd[1]: Configuration file /lib/systemd/system/spop.service is marked executable. Please remove executable permission bits. Proceeding anyway. Jan 19 23:04:32 imperum-pml systemd[1]: Configuration file /lib/systemd/system/spop.service is marked world-writable. Please remove world writability permission bits. Proceeding anyway. Jan 19 23:04:32 imperum-pml volumio[1688]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 19 23:04:32 imperum-pml volumio[1688]: info: CoreCommandRouter::initPlayerControls Jan 19 23:04:32 imperum-pml systemd[1]: Starting Spotify Daemon... Jan 19 23:04:32 imperum-pml systemd[1]: Started Spotify Daemon. Jan 19 23:04:32 imperum-pml volumio[1688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 23:04:32 imperum-pml volumio[1688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Jan 19 23:04:32 imperum-pml volumio[1688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 23:04:32 imperum-pml volumio[1688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 23:04:32 imperum-pml volumio[1688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 23:04:32 imperum-pml volumio[1688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 23:04:32 imperum-pml volumio[1688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 23:04:32 imperum-pml volumio[1688]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 19 23:04:32 imperum-pml volumio[1688]: info: BOOT COMPLETED Jan 19 23:04:32 imperum-pml volumio[1688]: [Metrics] CommandRouter: 9s 769.69ms Jan 19 23:04:32 imperum-pml volumio[1688]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 19 23:04:32 imperum-pml volumio[1960]: 2025-01-19 23:04:32 [SPTF] 22:04:32.228 I [offline_authorizer.cpp:297] Unable to login offline: no such user Jan 19 23:04:32 imperum-pml volumio[1960]: 2025-01-19 23:04:32 [SPTF] 22:04:32.229 I [ap:1752] Connecting to AP ap.spotify.com:4070 Jan 19 23:04:32 imperum-pml systemd[1]: Starting Volspotconnect2 Daemon... Jan 19 23:04:32 imperum-pml sudo[1948]: pam_unix(sudo:session): session closed for user root Jan 19 23:04:32 imperum-pml sudo[1951]: pam_unix(sudo:session): session closed for user root Jan 19 23:04:32 imperum-pml kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Jan 19 23:04:32 imperum-pml systemd[1]: Started Volspotconnect2 Daemon. Jan 19 23:04:32 imperum-pml volumio[1968]: vollibrespot v0.2.0 0c51076 2020-04-17 (librespot 8d65d82 2020-03-10) -- Built On 2020-04-17 Jan 19 23:04:32 imperum-pml volumio[1968]: Reading Config from "volspotify.toml" Jan 19 23:04:32 imperum-pml volumio[1968]: Malformed config key: missing field `Authentication` Jan 19 23:04:32 imperum-pml systemd[1]: volspotconnect2.service: main process exited, code=exited, status=1/FAILURE Jan 19 23:04:32 imperum-pml systemd[1]: Unit volspotconnect2.service entered failed state. Jan 19 23:04:32 imperum-pml volumio[1960]: 2025-01-19 23:04:32 [SPTF] 22:04:32.669 E [ap:1694] AP Socket Error: Host not found (20001) Jan 19 23:04:32 imperum-pml volumio[1960]: 2025-01-19 23:04:32 [SPTF] 22:04:32.669 E [ap:3953] Connection error: 4 Jan 19 23:04:32 imperum-pml volumio[1960]: 2025-01-19 23:04:32 [SPTF] 22:04:32.670 I [ap:1752] Connecting to AP ap.spotify.com:443 Jan 19 23:04:32 imperum-pml systemd[1]: Started Music Player Daemon. Jan 19 23:04:32 imperum-pml sudo[1928]: pam_unix(sudo:session): session closed for user root Jan 19 23:04:32 imperum-pml volumio[1960]: 2025-01-19 23:04:32 [SPTF] 22:04:32.922 E [ap:1694] AP Socket Error: Host not found (20001) Jan 19 23:04:32 imperum-pml volumio[1960]: 2025-01-19 23:04:32 [SPTF] 22:04:32.923 E [ap:3953] Connection error: 4 Jan 19 23:04:32 imperum-pml volumio[1960]: 2025-01-19 23:04:32 [SPTF] 22:04:32.923 I [ap:1752] Connecting to AP ap.spotify.com:80 Jan 19 23:04:33 imperum-pml volumio[1960]: 2025-01-19 23:04:33 [SPTF] 22:04:33.175 E [ap:1694] AP Socket Error: Host not found (20001) Jan 19 23:04:33 imperum-pml volumio[1960]: 2025-01-19 23:04:33 [SPTF] 22:04:33.175 E [ap:3953] Connection error: 4 Jan 19 23:04:33 imperum-pml volumio[1960]: 2025-01-19 23:04:33 [SPTF] 22:04:33.175 I [ap:1752] Connecting to AP ap.spotify.com:4070 Jan 19 23:04:33 imperum-pml volumio[1960]: 2025-01-19 23:04:33 [SPTF] 22:04:33.428 E [ap:1694] AP Socket Error: Host not found (20001) Jan 19 23:04:33 imperum-pml volumio[1960]: 2025-01-19 23:04:33 [SPTF] 22:04:33.428 E [ap:3953] Connection error: 4 Jan 19 23:04:33 imperum-pml volumio[1960]: 2025-01-19 23:04:33 [SPTF] 22:04:33.428 I [ap:1752] Connecting to AP ap.spotify.com:443 Jan 19 23:04:33 imperum-pml volumio[1960]: 2025-01-19 23:04:33 [SPTF] 22:04:33.680 E [ap:1694] AP Socket Error: Host not found (20001) Jan 19 23:04:33 imperum-pml volumio[1960]: 2025-01-19 23:04:33 [SPTF] 22:04:33.681 E [ap:3953] Connection error: 4 Jan 19 23:04:33 imperum-pml volumio[1960]: 2025-01-19 23:04:33 [SPTF] 22:04:33.681 I [ap:1752] Connecting to AP ap.spotify.com:80 Jan 19 23:04:33 imperum-pml volumio[1960]: 2025-01-19 23:04:33 [SPTF] 22:04:33.933 E [ap:1694] AP Socket Error: Host not found (20001) Jan 19 23:04:33 imperum-pml volumio[1960]: 2025-01-19 23:04:33 [SPTF] 22:04:33.933 E [ap:3953] Connection error: 4 Jan 19 23:04:33 imperum-pml volumio[1960]: 2025-01-19 23:04:33 [WARN] Login failed: General permanent error Jan 19 23:04:33 imperum-pml volumio[1960]: 2025-01-19 23:04:33 [ERR ] Could not get the playlist container. Jan 19 23:04:33 imperum-pml systemd[1]: spop.service: main process exited, code=killed, status=5/TRAP Jan 19 23:04:33 imperum-pml systemd[1]: Unit spop.service entered failed state. Jan 19 23:04:34 imperum-pml systemd[1]: volspotconnect2.service holdoff time over, scheduling restart. Jan 19 23:04:34 imperum-pml systemd[1]: Stopping Volspotconnect2 Daemon... Jan 19 23:04:34 imperum-pml systemd[1]: Starting Volspotconnect2 Daemon... Jan 19 23:04:34 imperum-pml systemd[1]: Started Volspotconnect2 Daemon. Jan 19 23:04:34 imperum-pml volumio[1986]: vollibrespot v0.2.0 0c51076 2020-04-17 (librespot 8d65d82 2020-03-10) -- Built On 2020-04-17 Jan 19 23:04:34 imperum-pml volumio[1986]: Reading Config from "volspotify.toml" Jan 19 23:04:34 imperum-pml volumio[1986]: Malformed config key: missing field `Authentication` Jan 19 23:04:34 imperum-pml systemd[1]: volspotconnect2.service: main process exited, code=exited, status=1/FAILURE Jan 19 23:04:34 imperum-pml systemd[1]: Unit volspotconnect2.service entered failed state. Jan 19 23:04:36 imperum-pml hotspot[1924]: Killing Hostapd Jan 19 23:04:36 imperum-pml sudo[1996]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall hostapd Jan 19 23:04:36 imperum-pml sudo[1996]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 23:04:36 imperum-pml hotspot[1924]: hostapd: no process found Jan 19 23:04:36 imperum-pml sudo[1996]: pam_unix(sudo:session): session closed for user root Jan 19 23:04:36 imperum-pml hotspot[1924]: Killing Dhcpd Jan 19 23:04:36 imperum-pml sudo[2004]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall dhcpd Jan 19 23:04:36 imperum-pml sudo[2004]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 23:04:36 imperum-pml hotspot[1924]: dhcpd: no process found Jan 19 23:04:36 imperum-pml sudo[2004]: pam_unix(sudo:session): session closed for user root Jan 19 23:04:36 imperum-pml systemd[1]: hotspot.service: control process exited, code=exited status=1 Jan 19 23:04:36 imperum-pml systemd[1]: volspotconnect2.service holdoff time over, scheduling restart. Jan 19 23:04:36 imperum-pml systemd[1]: Stopping Volspotconnect2 Daemon... Jan 19 23:04:36 imperum-pml systemd[1]: Starting Volspotconnect2 Daemon... Jan 19 23:04:36 imperum-pml systemd[1]: Started Volspotconnect2 Daemon. Jan 19 23:04:36 imperum-pml volumio[2013]: vollibrespot v0.2.0 0c51076 2020-04-17 (librespot 8d65d82 2020-03-10) -- Built On 2020-04-17 Jan 19 23:04:36 imperum-pml volumio[2013]: Reading Config from "volspotify.toml" Jan 19 23:04:36 imperum-pml volumio[2013]: Malformed config key: missing field `Authentication` Jan 19 23:04:36 imperum-pml systemd[1]: volspotconnect2.service: main process exited, code=exited, status=1/FAILURE Jan 19 23:04:36 imperum-pml systemd[1]: Unit volspotconnect2.service entered failed state. Jan 19 23:04:36 imperum-pml systemd[1]: Stopped hotspot.service. Jan 19 23:04:36 imperum-pml systemd[1]: Unit hotspot.service entered failed state. Jan 19 23:04:36 imperum-pml sudo[2018]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jan 19 23:04:36 imperum-pml sudo[2018]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 23:04:36 imperum-pml avahi-daemon[803]: Withdrawing address record for 192.168.211.1 on wlan0. Jan 19 23:04:36 imperum-pml avahi-daemon[803]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Jan 19 23:04:36 imperum-pml avahi-daemon[803]: Interface wlan0.IPv4 no longer relevant for mDNS. Jan 19 23:04:36 imperum-pml sudo[2018]: pam_unix(sudo:session): session closed for user root Jan 19 23:04:36 imperum-pml sudo[2026]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jan 19 23:04:36 imperum-pml sudo[2026]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 23:04:37 imperum-pml volumio[1688]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Jan 19 23:04:37 imperum-pml volumio[1688]: info: CoreCommandRouter::Close All Modals sent Jan 19 23:04:37 imperum-pml volumio[1688]: info: CoreCommandRouter::Close All Modals sent Jan 19 23:04:37 imperum-pml volumio[1688]: Express server listening on port 3000 Jan 19 23:04:37 imperum-pml volumio[1688]: [Metrics] WebUI: 15s 711.22ms Jan 19 23:04:37 imperum-pml volumio[1688]: Volumio Calling Home Jan 19 23:04:37 imperum-pml volumio[1688]: (node:1688) [DEP0013] DeprecationWarning: Calling an asynchronous function without callback is deprecated. Jan 19 23:04:37 imperum-pml volumio[1688]: (node:1688) [DEP0013] DeprecationWarning: Calling an asynchronous function without callback is deprecated. Jan 19 23:04:37 imperum-pml sudo[2026]: pam_unix(sudo:session): session closed for user root Jan 19 23:04:37 imperum-pml volumio[1688]: (node:1688) [DEP0013] DeprecationWarning: Calling an asynchronous function without callback is deprecated. Jan 19 23:04:37 imperum-pml volumio[1688]: (node:1688) [DEP0013] DeprecationWarning: Calling an asynchronous function without callback is deprecated. Jan 19 23:04:37 imperum-pml volumio[1688]: info: CoreStateMachine::resetVolumioState Jan 19 23:04:37 imperum-pml volumio[1688]: info: CoreStateMachine::getcurrentVolume Jan 19 23:04:37 imperum-pml volumio[1688]: info: CoreCommandRouter::volumioRetrievevolume Jan 19 23:04:37 imperum-pml sudo[1860]: pam_unix(sudo:session): session closed for user root Jan 19 23:04:37 imperum-pml volumio[1688]: [SpotifyConnect] Restarting Vollibrespot Daemon Jan 19 23:04:37 imperum-pml wireless.js[1913]: Stopped aP Jan 19 23:04:37 imperum-pml wireless.js[1913]: Start wireless flow Jan 19 23:04:37 imperum-pml wireless.js[1913]: Stopped hotspot (if there).. Jan 19 23:04:37 imperum-pml sudo[2051]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jan 19 23:04:37 imperum-pml sudo[2049]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect2.service Jan 19 23:04:37 imperum-pml sudo[2049]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 23:04:38 imperum-pml sudo[2051]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 23:04:38 imperum-pml systemd[1]: Stopping Volspotconnect2 Daemon... Jan 19 23:04:38 imperum-pml systemd[1]: Starting Volspotconnect2 Daemon... Jan 19 23:04:38 imperum-pml systemd[1]: Started Volspotconnect2 Daemon. Jan 19 23:04:38 imperum-pml sudo[2051]: pam_unix(sudo:session): session closed for user root Jan 19 23:04:38 imperum-pml sudo[2049]: pam_unix(sudo:session): session closed for user root Jan 19 23:04:38 imperum-pml volumio[2060]: vollibrespot v0.2.0 0c51076 2020-04-17 (librespot 8d65d82 2020-03-10) -- Built On 2020-04-17 Jan 19 23:04:38 imperum-pml volumio[2060]: Reading Config from "volspotify.toml" Jan 19 23:04:38 imperum-pml volumio[2060]: Malformed config key: missing field `Authentication` Jan 19 23:04:38 imperum-pml systemd[1]: volspotconnect2.service: main process exited, code=exited, status=1/FAILURE Jan 19 23:04:38 imperum-pml systemd[1]: Unit volspotconnect2.service entered failed state. Jan 19 23:04:38 imperum-pml sudo[2068]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jan 19 23:04:38 imperum-pml sudo[2068]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 23:04:38 imperum-pml sudo[2068]: pam_unix(sudo:session): session closed for user root Jan 19 23:04:38 imperum-pml wireless.js[1913]: DHCP IP Jan 19 23:04:38 imperum-pml wpa_supplicant[2082]: Successfully initialized wpa_supplicant Jan 19 23:04:38 imperum-pml wpa_supplicant[2082]: nl80211: Could not configure driver mode Jan 19 23:04:38 imperum-pml wireless.js[1913]: Start ap Jan 19 23:04:38 imperum-pml kernel: brcmfmac: power management disabled Jan 19 23:04:38 imperum-pml volumio[1688]: info: CoreCommandRouter::Close All Modals sent Jan 19 23:04:38 imperum-pml sudo[2083]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Jan 19 23:04:38 imperum-pml sudo[2083]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 23:04:38 imperum-pml dhcpcd[2089]: version 6.0.5 starting Jan 19 23:04:38 imperum-pml sudo[2097]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 19 23:04:38 imperum-pml sudo[2097]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 23:04:38 imperum-pml sudo[2097]: pam_unix(sudo:session): session closed for user root Jan 19 23:04:38 imperum-pml dhcpcd[2089]: eth0: soliciting an IPv6 router Jan 19 23:04:38 imperum-pml dhcpcd[2089]: eth0: rebinding lease of 172.16.0.157 Jan 19 23:04:38 imperum-pml dhcpcd[2089]: wlan0: waiting for carrier Jan 19 23:04:38 imperum-pml volumio[1688]: [SpotifyConnect] Vollibrespot Daemon service restarted! Jan 19 23:04:38 imperum-pml volumio[1688]: info: Setting Device type: Raspberry PI Jan 19 23:04:38 imperum-pml volumio[1688]: [SpotifyConnect] Vollibrespot Daemon service started! Jan 19 23:04:38 imperum-pml volumio[1688]: [Metrics] SpotifyConnect: 6s 687.47ms Jan 19 23:04:38 imperum-pml volumio[1688]: info: SpopD Daemon Started Jan 19 23:04:38 imperum-pml volumio[1688]: info: MPD Permissions set Jan 19 23:04:38 imperum-pml volumio[1688]: info: MPD running with PID806 ,establishing connection Jan 19 23:04:38 imperum-pml volumio[1688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jan 19 23:04:38 imperum-pml volumio[1688]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Jan 19 23:04:38 imperum-pml volumio[1688]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jan 19 23:04:38 imperum-pml volumio[1688]: info: MPD running with PID1949 ,establishing connection Jan 19 23:04:38 imperum-pml volumio[1688]: info: VolumeController:: Volume=75 Mute =false Jan 19 23:04:38 imperum-pml volumio[1688]: info: CoreStateMachine::pushState Jan 19 23:04:38 imperum-pml volumio[1688]: info: CoreStateMachine::getState Jan 19 23:04:38 imperum-pml volumio[1688]: info: CorePlayQueue::getTrack 0 Jan 19 23:04:38 imperum-pml volumio[1688]: info: CoreCommandRouter::volumioPushState Jan 19 23:04:38 imperum-pml volumio[1688]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 23:04:38 imperum-pml volumio[1688]: info: CoreStateMachine::updateTrackBlock Jan 19 23:04:38 imperum-pml volumio[1688]: info: CorePlayQueue::getTrackBlock Jan 19 23:04:38 imperum-pml volumio[1688]: info: CoreCommandRouter::volumioRetrievevolume Jan 19 23:04:38 imperum-pml wpa_supplicant[2126]: wlan0: Trying to associate with a0:18:42:51:d2:a2 (SSID='CASAPATRONA_2G' freq=2412 MHz) Jan 19 23:04:38 imperum-pml volumio[1688]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'username' of undefined Jan 19 23:04:38 imperum-pml volumio[1688]: at autorizacion (/volumio/http/index.js:53:62) Jan 19 23:04:38 imperum-pml volumio[1688]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jan 19 23:04:38 imperum-pml volumio[1688]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Jan 19 23:04:38 imperum-pml volumio[1688]: at /volumio/node_modules/express/lib/router/index.js:284:7 Jan 19 23:04:38 imperum-pml volumio[1688]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Jan 19 23:04:38 imperum-pml volumio[1688]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Jan 19 23:04:38 imperum-pml volumio[1688]: at allowCrossDomain (/volumio/http/index.js:83:9) Jan 19 23:04:38 imperum-pml volumio[1688]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jan 19 23:04:38 imperum-pml volumio[1688]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Jan 19 23:04:38 imperum-pml volumio[1688]: at /volumio/node_modules/express/lib/router/index.js:284:7 Jan 19 23:04:38 imperum-pml volumio[1688]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Jan 19 23:04:38 imperum-pml volumio[1688]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Jan 19 23:04:38 imperum-pml volumio[1688]: at /volumio/node_modules/connect-busboy/index.js:14:14 Jan 19 23:04:38 imperum-pml volumio[1688]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jan 19 23:04:38 imperum-pml volumio[1688]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Jan 19 23:04:38 imperum-pml volumio[1688]: at /volumio/node_modules/express/lib/router/index.js:284:7 Jan 19 23:04:38 imperum-pml volumio[1688]: error: MPD error: false == true Jan 19 23:04:38 imperum-pml volumio[1688]: error: MPD error: false == true Jan 19 23:04:38 imperum-pml volumio[1688]: error: MPD error: false == true Jan 19 23:04:38 imperum-pml wpa_supplicant[2126]: wlan0: Associated with a0:18:42:51:d2:a2 Jan 19 23:04:39 imperum-pml wpa_supplicant[2126]: wlan0: WPA: Key negotiation completed with a0:18:42:51:d2:a2 [PTK=CCMP GTK=CCMP] Jan 19 23:04:39 imperum-pml wpa_supplicant[2126]: wlan0: CTRL-EVENT-CONNECTED - Connection to a0:18:42:51:d2:a2 completed [id=0 id_str=] Jan 19 23:04:39 imperum-pml dhcpcd[2089]: wlan0: carrier acquired Jan 19 23:04:39 imperum-pml dhcpcd[2089]: wlan0: soliciting an IPv6 router Jan 19 23:04:39 imperum-pml volumio[1688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 23:04:39 imperum-pml volumio[1688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 23:04:39 imperum-pml volumio[1688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 23:04:39 imperum-pml volumio[1688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 23:04:39 imperum-pml volumio[1688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 23:04:39 imperum-pml volumio[1688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 23:04:39 imperum-pml volumio[1688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 23:04:39 imperum-pml volumio[1688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 23:04:39 imperum-pml volumio[1688]: info: Reloading queue from file Jan 19 23:04:39 imperum-pml dhcpcd[2089]: wlan0: rebinding lease of 192.168.1.153 Jan 19 23:04:39 imperum-pml volumio[1688]: info: CoreStateMachine::setRepeat true single undefined Jan 19 23:04:39 imperum-pml volumio[1688]: info: CoreStateMachine::pushState Jan 19 23:04:39 imperum-pml volumio[1688]: info: CoreStateMachine::getState Jan 19 23:04:39 imperum-pml volumio[1688]: info: CorePlayQueue::getTrack 0 Jan 19 23:04:39 imperum-pml volumio[1688]: info: CoreCommandRouter::volumioPushState Jan 19 23:04:39 imperum-pml volumio[1688]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 23:04:39 imperum-pml volumio[1688]: info: CoreStateMachine::setRandom true Jan 19 23:04:39 imperum-pml volumio[1688]: info: CoreStateMachine::pushState Jan 19 23:04:39 imperum-pml volumio[1688]: info: CoreStateMachine::getState Jan 19 23:04:39 imperum-pml volumio[1688]: info: CorePlayQueue::getTrack 0 Jan 19 23:04:39 imperum-pml volumio[1688]: info: CoreCommandRouter::volumioPushState Jan 19 23:04:39 imperum-pml volumio[1688]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 23:04:39 imperum-pml wireless.js[1913]: trying... Jan 19 23:04:39 imperum-pml volumio[1688]: info: Jan 19 23:04:39 imperum-pml sudo[2160]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 19 23:04:39 imperum-pml volumio[1688]: info: message= [50@0] {} No such directory, stack=Error: [50@0] {} No such directory Jan 19 23:04:39 imperum-pml volumio[1688]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:63:14) Jan 19 23:04:39 imperum-pml volumio[1688]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:10) Jan 19 23:04:39 imperum-pml volumio[1688]: at emitOne (events.js:116:13) Jan 19 23:04:39 imperum-pml volumio[1688]: at Socket.emit (events.js:211:7) Jan 19 23:04:39 imperum-pml volumio[1688]: at addChunk (_stream_readable.js:263:12) Jan 19 23:04:39 imperum-pml volumio[1688]: at readableAddChunk (_stream_readable.js:246:13) Jan 19 23:04:39 imperum-pml volumio[1688]: at Socket.Readable.push (_stream_readable.js:208:10) Jan 19 23:04:39 imperum-pml volumio[1688]: at Pipe.onread (net.js:607:20) Jan 19 23:04:39 imperum-pml sudo[2160]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 23:04:39 imperum-pml volumio[1688]: info: VolumeController:: Volume=75 Mute =false Jan 19 23:04:39 imperum-pml volumio[1688]: info: CoreStateMachine::pushState Jan 19 23:04:39 imperum-pml volumio[1688]: info: CoreStateMachine::getState Jan 19 23:04:39 imperum-pml volumio[1688]: info: CorePlayQueue::getTrack 0 Jan 19 23:04:39 imperum-pml volumio[1688]: info: CoreCommandRouter::volumioPushState Jan 19 23:04:39 imperum-pml volumio[1688]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 23:04:39 imperum-pml sudo[2160]: pam_unix(sudo:session): session closed for user root Jan 19 23:04:39 imperum-pml volumio[1688]: info: Starting Shairport Sync Jan 19 23:04:39 imperum-pml volumio[1688]: info: Starting Shairport Sync Jan 19 23:04:39 imperum-pml sudo[2169]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 19 23:04:39 imperum-pml sudo[2169]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 23:04:39 imperum-pml sudo[2171]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 19 23:04:39 imperum-pml sudo[2171]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 23:04:39 imperum-pml volumio[1688]: info: mDNS: A device disapperared from network Jan 19 23:04:39 imperum-pml volumio[1688]: info: mDNS Browse raised the following error TypeError: Cannot read property 'toLowerCase' of undefined Jan 19 23:04:39 imperum-pml systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Jan 19 23:04:39 imperum-pml sudo[2178]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jan 19 23:04:39 imperum-pml systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 19 23:04:39 imperum-pml sudo[2169]: pam_unix(sudo:session): session closed for user root Jan 19 23:04:39 imperum-pml sudo[2178]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 23:04:39 imperum-pml systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 19 23:04:39 imperum-pml systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Jan 19 23:04:39 imperum-pml systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 19 23:04:39 imperum-pml sudo[2171]: pam_unix(sudo:session): session closed for user root Jan 19 23:04:39 imperum-pml volumio[1688]: info: mDNS: A device disapperared from network Jan 19 23:04:39 imperum-pml volumio[1688]: info: mDNS Browse raised the following error TypeError: Cannot read property 'toLowerCase' of undefined Jan 19 23:04:39 imperum-pml systemd[1]: Starting UPnP Renderer front-end to MPD... Jan 19 23:04:39 imperum-pml systemd[1]: Started UPnP Renderer front-end to MPD. Jan 19 23:04:39 imperum-pml sudo[2178]: pam_unix(sudo:session): session closed for user root Jan 19 23:04:39 imperum-pml volumio[1688]: info: Setting volume on startup at 100 Jan 19 23:04:39 imperum-pml volumio[1688]: info: Shairport-Sync Started Jan 19 23:04:39 imperum-pml volumio[1688]: Error adding Membership: Error: addMembership EINVAL Jan 19 23:04:39 imperum-pml volumio[1688]: info: Shairport-Sync Started Jan 19 23:04:39 imperum-pml volumio[1688]: info: Upmpdcli Daemon Started Jan 19 23:04:39 imperum-pml volumio[1688]: info: CoreCommandRouter::volumioGetState Jan 19 23:04:39 imperum-pml volumio[1688]: info: CoreStateMachine::getState Jan 19 23:04:39 imperum-pml volumio[1688]: info: CorePlayQueue::getTrack 0 Jan 19 23:04:40 imperum-pml systemd[1]: volspotconnect2.service holdoff time over, scheduling restart. Jan 19 23:04:40 imperum-pml systemd[1]: Stopping Volspotconnect2 Daemon... Jan 19 23:04:40 imperum-pml systemd[1]: Starting Volspotconnect2 Daemon... Jan 19 23:04:40 imperum-pml systemd[1]: Started Volspotconnect2 Daemon. Jan 19 23:04:40 imperum-pml wireless.js[1913]: trying... Jan 19 23:04:40 imperum-pml volumio[2212]: vollibrespot v0.2.0 0c51076 2020-04-17 (librespot 8d65d82 2020-03-10) -- Built On 2020-04-17 Jan 19 23:04:40 imperum-pml volumio[2212]: Reading Config from "volspotify.toml" Jan 19 23:04:40 imperum-pml sudo[2215]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 19 23:04:40 imperum-pml volumio[2212]: [Vollibrespot] : Using Alsa backend with device: compresor Jan 19 23:04:40 imperum-pml sudo[2215]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 23:04:40 imperum-pml sudo[2215]: pam_unix(sudo:session): session closed for user root Jan 19 23:04:40 imperum-pml ntpd[938]: Deleting interface #4 wlan0, 192.168.211.1#123, interface stats: received=0, sent=0, dropped=0, active_time=19 secs Jan 19 23:04:40 imperum-pml ntpd[938]: peers refreshed Jan 19 23:04:41 imperum-pml wireless.js[1913]: trying... Jan 19 23:04:41 imperum-pml sudo[2233]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 19 23:04:41 imperum-pml sudo[2233]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 23:04:41 imperum-pml sudo[2233]: pam_unix(sudo:session): session closed for user root Jan 19 23:04:41 imperum-pml volumio[2212]: thread 'main' panicked at 'Discovery error!: Os { code: 19, kind: Other, message: "No such device" }', src/main.rs:229:17 Jan 19 23:04:41 imperum-pml volumio[2212]: stack backtrace: Jan 19 23:04:41 imperum-pml cron[765]: 2025-01-19 23:04:41 1tZdPN-0000aG-Ol Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=114 egid=120 Jan 19 23:04:41 imperum-pml cron[765]: 2025-01-19 23:04:41 1tZdPN-0000aG-Ol <= volumio@localhost U=volumio P=local S=780 Jan 19 23:04:41 imperum-pml cron[765]: 2025-01-19 23:04:41 1tZdPN-0000aG-Ol Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=114 egid=120 Jan 19 23:04:41 imperum-pml exim[2248]: 2025-01-19 23:04:41 1tZdPN-0000aG-Ol <= volumio@localhost U=volumio P=local S=780 Jan 19 23:04:41 imperum-pml exim[2248]: 2025-01-19 23:04:41 1tZdPN-0000aG-Ol Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=114 egid=120 Jan 19 23:04:41 imperum-pml exim[2248]: exim: could not open panic log - aborting: see message(s) above Jan 19 23:04:41 imperum-pml cron[765]: exim: could not open panic log - aborting: see message(s) above Jan 19 23:04:41 imperum-pml CRON[1606]: (volumio) MAIL (mailed 155 bytes of output but got status 0x0001 from MTA ) Jan 19 23:04:41 imperum-pml CRON[1606]: pam_unix(cron:session): session closed for user volumio Jan 19 23:04:42 imperum-pml CRON[2301]: pam_unix(cron:session): session opened for user volumio by (uid=0) Jan 19 23:04:42 imperum-pml CRON[2302]: pam_unix(cron:session): session opened for user volumio by (uid=0) Jan 19 23:04:42 imperum-pml CRON[2300]: pam_unix(cron:session): session opened for user volumio by (uid=0) Jan 19 23:04:42 imperum-pml CRON[2315]: (volumio) CMD (/home/volumio/protocoloTCN/bin/client > /dev/null 2>&1) Jan 19 23:04:42 imperum-pml CRON[2316]: (volumio) CMD (/home/volumio/tcnimperum/process > /home/volumio/test.txt) Jan 19 23:04:42 imperum-pml CRON[2317]: (volumio) CMD (/home/volumio/tcnimperum/send > /home/volumio/testsend.txt) Jan 19 23:04:42 imperum-pml CRON[2302]: pam_unix(cron:session): session closed for user volumio Jan 19 23:04:42 imperum-pml volumio[2212]: 0: 0xa6f730 - backtrace::backtrace::libunwind::trace::h100c6c70e4af496c Jan 19 23:04:42 imperum-pml volumio[2212]: at /cargo/registry/src/github.com-1ecc6299db9ec823/backtrace-0.3.40/src/backtrace/libunwind.rs:88 Jan 19 23:04:42 imperum-pml volumio[2212]: 1: 0xa6f730 - backtrace::backtrace::trace_unsynchronized::hf78ddc194bf45009 Jan 19 23:04:42 imperum-pml volumio[2212]: at /cargo/registry/src/github.com-1ecc6299db9ec823/backtrace-0.3.40/src/backtrace/mod.rs:66 Jan 19 23:04:42 imperum-pml volumio[2212]: 2: 0xa6f730 - std::sys_common::backtrace::_print_fmt::h967a212a8894e4a4 Jan 19 23:04:42 imperum-pml volumio[2212]: at src/libstd/sys_common/backtrace.rs:77 Jan 19 23:04:42 imperum-pml sudo[2327]: volumio : TTY=unknown ; PWD=/home/volumio ; USER=root ; COMMAND=/bin/rm -r /home/volumio/tcnimperum/reciente Jan 19 23:04:42 imperum-pml volumio[2212]: 3: 0xa6f730 - ::fmt::h5fe383ce2118859a Jan 19 23:04:42 imperum-pml volumio[2212]: at src/libstd/sys_common/backtrace.rs:59 Jan 19 23:04:42 imperum-pml sudo[2328]: volumio : TTY=unknown ; PWD=/home/volumio ; USER=root ; COMMAND=/bin/rm -r /home/volumio/tcnimperum/sendpoint Jan 19 23:04:42 imperum-pml sudo[2327]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 23:04:42 imperum-pml sudo[2328]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 23:04:42 imperum-pml sudo[2327]: pam_unix(sudo:session): session closed for user root Jan 19 23:04:42 imperum-pml volumio[2212]: 4: 0xa8af20 - core::fmt::write::h937f59172f6eeeaf Jan 19 23:04:42 imperum-pml volumio[2212]: at src/libcore/fmt/mod.rs:1052 Jan 19 23:04:42 imperum-pml volumio[2212]: 5: 0xa696ac - std::io::Write::write_fmt::hc70e2634948b89c8 Jan 19 23:04:42 imperum-pml volumio[2212]: at src/libstd/io/mod.rs:1426 Jan 19 23:04:42 imperum-pml volumio[2212]: 6: 0xa71bf8 - std::sys_common::backtrace::_print::hbfc81757b29a60f4 Jan 19 23:04:42 imperum-pml volumio[2212]: at src/libstd/sys_common/backtrace.rs:62 Jan 19 23:04:42 imperum-pml volumio[2212]: 7: 0xa71bf8 - std::sys_common::backtrace::print::h8bc349c117633211 Jan 19 23:04:42 imperum-pml volumio[2212]: at src/libstd/sys_common/backtrace.rs:49 Jan 19 23:04:42 imperum-pml volumio[2212]: 8: 0xa71bf8 - std::panicking::default_hook::{{closure}}::h82c47dcbb8503118 Jan 19 23:04:42 imperum-pml volumio[2212]: at src/libstd/panicking.rs:204 Jan 19 23:04:42 imperum-pml volumio[2212]: 9: 0xa7186c - std::panicking::default_hook::hc04800be16711189 Jan 19 23:04:42 imperum-pml volumio[2212]: at src/libstd/panicking.rs:224 Jan 19 23:04:42 imperum-pml volumio[2212]: 10: 0xa722a8 - std::panicking::rust_panic_with_hook::h2413ff75e44cd581 Jan 19 23:04:42 imperum-pml volumio[2212]: at src/libstd/panicking.rs:472 Jan 19 23:04:42 imperum-pml volumio[2212]: 11: 0xa71ebc - rust_begin_unwind Jan 19 23:04:42 imperum-pml volumio[2212]: at src/libstd/panicking.rs:380 Jan 19 23:04:42 imperum-pml volumio[2212]: 12: 0xa8898c - core::panicking::panic_fmt::h2c56481ca2ee7c2f Jan 19 23:04:42 imperum-pml volumio[2212]: at src/libcore/panicking.rs:85 Jan 19 23:04:42 imperum-pml volumio[2212]: 13: 0xa887e8 - core::option::expect_none_failed::ha234f7f2802df4d8 Jan 19 23:04:42 imperum-pml volumio[2212]: at src/libcore/option.rs:1199 Jan 19 23:04:42 imperum-pml volumio[2212]: 14: 0x4aad88 - vollibrespot::main::h9f38da6aba824ea5 Jan 19 23:04:42 imperum-pml volumio[2212]: 15: 0x4ddec4 - std::rt::lang_start::{{closure}}::h15358b854b572f97 Jan 19 23:04:42 imperum-pml volumio[2212]: 16: 0xa71d6c - std::rt::lang_start_internal::{{closure}}::habef164db2ffdce8 Jan 19 23:04:42 imperum-pml volumio[2212]: at src/libstd/rt.rs:52 Jan 19 23:04:42 imperum-pml volumio[2212]: 17: 0xa71d6c - std::panicking::try::do_call::ha24ef807c5f4c553 Jan 19 23:04:42 imperum-pml volumio[2212]: at src/libstd/panicking.rs:305 Jan 19 23:04:42 imperum-pml volumio[2212]: 18: 0xa764dc - __rust_maybe_catch_panic Jan 19 23:04:42 imperum-pml volumio[2212]: at src/libpanic_unwind/lib.rs:86 Jan 19 23:04:42 imperum-pml volumio[2212]: 19: 0xa726ec - std::panicking::try::hbf32b3a5688b523f Jan 19 23:04:42 imperum-pml volumio[2212]: at src/libstd/panicking.rs:281 Jan 19 23:04:42 imperum-pml volumio[2212]: 20: 0xa726ec - std::panic::catch_unwind::haaedb267e2d3c6e6 Jan 19 23:04:42 imperum-pml volumio[2212]: at src/libstd/panic.rs:394 Jan 19 23:04:42 imperum-pml volumio[2212]: 21: 0xa726ec - std::rt::lang_start_internal::hdc1e9a94360026b9 Jan 19 23:04:42 imperum-pml volumio[2212]: at src/libstd/rt.rs:51 Jan 19 23:04:42 imperum-pml sudo[2328]: pam_unix(sudo:session): session closed for user root Jan 19 23:04:42 imperum-pml systemd[1]: volspotconnect2.service: main process exited, code=exited, status=101/n/a Jan 19 23:04:42 imperum-pml systemd[1]: Unit volspotconnect2.service entered failed state. Jan 19 23:04:42 imperum-pml volumio[2212]: 22: 0x4ab43c - main Jan 19 23:04:42 imperum-pml volumio[2212]: 23: 0x76c55294 - __libc_start_main Jan 19 23:04:42 imperum-pml wireless.js[1913]: trying... Jan 19 23:04:42 imperum-pml sudo[2385]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 19 23:04:42 imperum-pml sudo[2385]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 23:04:42 imperum-pml sudo[2385]: pam_unix(sudo:session): session closed for user root Jan 19 23:04:42 imperum-pml volumio[1688]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 19 23:04:42 imperum-pml volumio[1688]: { Error: addMembership ENODEV Jan 19 23:04:42 imperum-pml volumio[1688]: at _errnoException (util.js:1022:11) Jan 19 23:04:42 imperum-pml volumio[1688]: at Socket.addMembership (dgram.js:615:11) Jan 19 23:04:42 imperum-pml volumio[1688]: at Timeout.addMembership [as _onTimeout] (/volumio/node_modules/node-ssdp/lib/index.js:264:16) Jan 19 23:04:42 imperum-pml volumio[1688]: at ontimeout (timers.js:482:11) Jan 19 23:04:42 imperum-pml volumio[1688]: at tryOnTimeout (timers.js:317:5) Jan 19 23:04:42 imperum-pml volumio[1688]: at Timer.listOnTimeout (timers.js:277:5) code: 'ENODEV', errno: 'ENODEV', syscall: 'addMembership' } Jan 19 23:04:42 imperum-pml volumio[1688]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 19 23:04:43 imperum-pml wireless.js[1913]: trying... Jan 19 23:04:43 imperum-pml sudo[2414]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 19 23:04:43 imperum-pml sudo[2414]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 23:04:43 imperum-pml sudo[2414]: pam_unix(sudo:session): session closed for user root Jan 19 23:04:43 imperum-pml dhcpcd[2089]: eth0: leased 172.16.0.157 for 10800 seconds Jan 19 23:04:43 imperum-pml dhcpcd[2089]: eth0: adding host route to 172.16.0.157 via 127.0.0.1 Jan 19 23:04:43 imperum-pml dhcpcd[2089]: eth0: adding route to 172.16.0.0/24 Jan 19 23:04:43 imperum-pml dhcpcd[2089]: eth0: adding default route via 172.16.0.1 Jan 19 23:04:43 imperum-pml avahi-daemon[803]: Joining mDNS multicast group on interface eth0.IPv4 with address 172.16.0.157. Jan 19 23:04:43 imperum-pml avahi-daemon[803]: New relevant interface eth0.IPv4 for mDNS. Jan 19 23:04:43 imperum-pml avahi-daemon[803]: Registering new address record for 172.16.0.157 on eth0.IPv4. Jan 19 23:04:43 imperum-pml sudo[2487]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-19 23:03 Jan 19 23:04:43 imperum-pml sudo[2487]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" 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="ba22215fabbe766d668749499a1c25fcefd90f0a" VOLUMIO_FE_VERSION="" VOLUMIO_BE_VERSION="043dcaee624f27d121213b83af11a9f2feac0945" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Mar 18 01:32:43 CET 2020" VOLUMIO_VERSION="2.729" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="5a0b29326faa8f5d0f5fe95d9e58c069"