-- Logs begin at Sun 2024-04-21 16:39:44 UTC, end at Sun 2024-04-21 16:41:06 UTC. -- Apr 21 16:40:00 mdxs ntpd[730]: Listen normally on 3 eth0 192.168.0.87 UDP 123 Apr 21 16:40:00 mdxs ntpd[730]: peers refreshed Apr 21 16:40:00 mdxs wireless.js[756]: trying... Apr 21 16:40:00 mdxs sudo[1053]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 21 16:40:00 mdxs sudo[1053]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 16:40:00 mdxs sudo[1053]: pam_unix(sudo:session): session closed for user root Apr 21 16:40:01 mdxs wireless.js[756]: trying... Apr 21 16:40:01 mdxs sudo[1062]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 21 16:40:01 mdxs sudo[1062]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 16:40:01 mdxs sudo[1062]: pam_unix(sudo:session): session closed for user root Apr 21 16:40:01 mdxs dhcpcd[564]: eth0: soliciting an IPv6 router Apr 21 16:40:01 mdxs ifup[542]: dhcpcd[564]: eth0: soliciting an IPv6 router Apr 21 16:40:01 mdxs dhcpcd[564]: eth0: soliciting a DHCP lease Apr 21 16:40:01 mdxs ifup[542]: dhcpcd[564]: eth0: soliciting a DHCP lease Apr 21 16:40:02 mdxs ntpd_intres[800]: DNS 0.debian.pool.ntp.org -> 162.159.200.1 Apr 21 16:40:02 mdxs ntpd_intres[800]: DNS 1.debian.pool.ntp.org -> 144.76.197.108 Apr 21 16:40:02 mdxs nmbd[851]: [2024/04/21 16:40:02.264857, 0] ../lib/util/become_daemon.c:124(daemon_ready) Apr 21 16:40:02 mdxs nmbd[851]: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Apr 21 16:40:02 mdxs ntpd_intres[800]: DNS 2.debian.pool.ntp.org -> 217.196.145.42 Apr 21 16:40:02 mdxs wireless.js[756]: trying... Apr 21 16:40:02 mdxs ntpd_intres[800]: DNS 3.debian.pool.ntp.org -> 141.255.175.252 Apr 21 16:40:02 mdxs sudo[1072]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 21 16:40:02 mdxs sudo[1072]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 16:40:02 mdxs sudo[1072]: pam_unix(sudo:session): session closed for user root Apr 21 16:40:02 mdxs volumio-remote-updater[612]: [2024-04-21 16:40:02] [info] asio async_connect error: system:111 (Connection refused) Apr 21 16:40:02 mdxs volumio-remote-updater[612]: [2024-04-21 16:40:02] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Apr 21 16:40:02 mdxs volumio-remote-updater[612]: [2024-04-21 16:40:02] [error] handle_connect error: Underlying Transport Error Apr 21 16:40:03 mdxs dhcpcd[564]: eth0: offered 192.168.0.88 from 192.168.0.1 Apr 21 16:40:03 mdxs ifup[542]: dhcpcd[564]: eth0: offered 192.168.0.88 from 192.168.0.1 Apr 21 16:40:03 mdxs dhcpcd[1022]: wlan0: using IPv4LL address 169.254.14.162 Apr 21 16:40:03 mdxs avahi-daemon[668]: Joining mDNS multicast group on interface wlan0.IPv4 with address 169.254.14.162. Apr 21 16:40:03 mdxs dhcpcd[1022]: wlan0: adding host route to 169.254.14.162 via 127.0.0.1 Apr 21 16:40:03 mdxs avahi-daemon[668]: New relevant interface wlan0.IPv4 for mDNS. Apr 21 16:40:03 mdxs dhcpcd[1022]: wlan0: adding route to 169.254.0.0/16 Apr 21 16:40:03 mdxs avahi-daemon[668]: Registering new address record for 169.254.14.162 on wlan0.IPv4. Apr 21 16:40:03 mdxs wireless.js[756]: trying... Apr 21 16:40:03 mdxs sudo[1100]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 21 16:40:03 mdxs sudo[1100]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 16:40:03 mdxs sudo[1100]: pam_unix(sudo:session): session closed for user root Apr 21 16:40:03 mdxs wireless.js[756]: Connected to: ----trashy Apr 21 16:40:03 mdxs wireless.js[756]: ---- Apr 21 16:40:03 mdxs sudo[1109]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 21 16:40:03 mdxs sudo[1109]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 16:40:03 mdxs sudo[1109]: pam_unix(sudo:session): session closed for user root Apr 21 16:40:03 mdxs wireless.js[756]: ... joined AP, wlan0 IPv4 is 169.254.14.162, ipV6 is undefined Apr 21 16:40:03 mdxs wireless.js[756]: It's done! AP Apr 21 16:40:03 mdxs systemd[1]: Started Wireless Services. Apr 21 16:40:03 mdxs systemd[1]: Starting Volumio Backend Module... Apr 21 16:40:03 mdxs systemd[1]: Started Volumio Backend Module. Apr 21 16:40:03 mdxs systemd[1]: Starting Volumio Streaming Daemon... Apr 21 16:40:03 mdxs systemd[1]: Started Volumio Streaming Daemon. Apr 21 16:40:03 mdxs systemd[1]: Starting Multi-User System. Apr 21 16:40:03 mdxs systemd[1]: Reached target Multi-User System. Apr 21 16:40:03 mdxs systemd[1]: Starting Graphical Interface. Apr 21 16:40:03 mdxs systemd[1]: Reached target Graphical Interface. Apr 21 16:40:03 mdxs systemd[1]: Starting Update UTMP about System Runlevel Changes... Apr 21 16:40:03 mdxs systemd[1]: Started Update UTMP about System Runlevel Changes. Apr 21 16:40:03 mdxs systemd[1]: Startup finished in 8.383s (kernel) + 20.078s (userspace) = 28.462s. Apr 21 16:40:03 mdxs volumio-streaming-daemon[1119]: ############################ Apr 21 16:40:03 mdxs volumio-streaming-daemon[1119]: # Volumio Streaming Daemon # Apr 21 16:40:03 mdxs volumio-streaming-daemon[1119]: # Running on port 7777 # Apr 21 16:40:03 mdxs volumio-streaming-daemon[1119]: ############################ Apr 21 16:40:03 mdxs volumio-streaming-daemon[1119]: Environment: production Apr 21 16:40:05 mdxs volumio[1118]: info: ------------------------------------------- Apr 21 16:40:05 mdxs volumio[1118]: info: ----- Volumio2 ---- Apr 21 16:40:05 mdxs volumio[1118]: info: ------------------------------------------- Apr 21 16:40:05 mdxs volumio[1118]: info: ----- System startup ---- Apr 21 16:40:05 mdxs volumio[1118]: info: ------------------------------------------- Apr 21 16:40:05 mdxs ntpd[730]: Listen normally on 4 wlan0 169.254.14.162 UDP 123 Apr 21 16:40:05 mdxs ntpd[730]: peers refreshed Apr 21 16:40:05 mdxs volumio[1118]: info: MYVOLUMIO Environment detected Apr 21 16:40:05 mdxs volumio[1118]: info: Plugin folders cleanup Apr 21 16:40:05 mdxs volumio[1118]: info: Scanning into folder /volumio/app/plugins/ Apr 21 16:40:05 mdxs volumio[1118]: info: Scanning category audio_interface Apr 21 16:40:05 mdxs volumio[1118]: info: Scanning category miscellanea Apr 21 16:40:05 mdxs volumio[1118]: info: Scanning category music_service Apr 21 16:40:05 mdxs volumio[1118]: info: Scanning category plugins.json Apr 21 16:40:05 mdxs volumio[1118]: info: Scanning category system_controller Apr 21 16:40:05 mdxs volumio[1118]: info: Scanning category user_interface Apr 21 16:40:05 mdxs volumio[1118]: info: Scanning into folder /data/plugins/ Apr 21 16:40:05 mdxs volumio[1118]: info: Scanning category music_service Apr 21 16:40:05 mdxs volumio[1118]: info: Plugin folders cleanup completed Apr 21 16:40:05 mdxs volumio[1118]: info: ------------------------------------------- Apr 21 16:40:05 mdxs volumio[1118]: info: ----- Core plugins startup ---- Apr 21 16:40:05 mdxs volumio[1118]: info: ------------------------------------------- Apr 21 16:40:05 mdxs volumio[1118]: info: Loading plugins from folder /volumio/app/plugins/ Apr 21 16:40:05 mdxs volumio[1118]: info: Adding plugin upnp to MyMusic Plugins Apr 21 16:40:05 mdxs volumio[1118]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 21 16:40:05 mdxs volumio[1118]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 21 16:40:05 mdxs volumio[1118]: info: Loading plugins from folder /data/plugins/ Apr 21 16:40:05 mdxs volumio[1118]: info: Loading plugin "system"... Apr 21 16:40:05 mdxs volumio[1118]: info: Loading plugin "appearance"... Apr 21 16:40:06 mdxs volumio[1118]: info: Loading plugin "network"... Apr 21 16:40:06 mdxs volumio[1118]: info: Loading plugin "services"... Apr 21 16:40:06 mdxs volumio[1118]: info: Loading plugin "alsa_controller"... Apr 21 16:40:06 mdxs volumio[1118]: info: Loading plugin "wizard"... Apr 21 16:40:06 mdxs volumio[1118]: info: Loading plugin "volumio_command_line_client"... Apr 21 16:40:06 mdxs volumio[1118]: info: Loading plugin "upnp"... Apr 21 16:40:06 mdxs volumio[1118]: info: [1713717606680] Starting Upmpd Daemon Apr 21 16:40:06 mdxs volumio[1118]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 21 16:40:06 mdxs volumio[1118]: info: Loading plugin "my_music"... Apr 21 16:40:06 mdxs volumio[1118]: info: Loading plugin "mpd"... Apr 21 16:40:06 mdxs volumio[1118]: info: Loading plugin "upnp_browser"... Apr 21 16:40:07 mdxs volumio[1118]: info: Loading plugin "networkfs"... Apr 21 16:40:07 mdxs volumio[1118]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 21 16:40:07 mdxs volumio[1118]: info: Loading plugin "alarm-clock"... Apr 21 16:40:07 mdxs sudo[1150]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=music,password=xartxx,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.100/Multimedia/Music /mnt/NAS/Betty Apr 21 16:40:07 mdxs sudo[1150]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 16:40:07 mdxs kernel: FS-Cache: Netfs 'cifs' registered for caching Apr 21 16:40:07 mdxs kernel: Key type cifs.spnego registered Apr 21 16:40:07 mdxs kernel: Key type cifs.idmap registered Apr 21 16:40:07 mdxs kernel: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3 (or SMB2.1) specify vers=1.0 on mount. Apr 21 16:40:07 mdxs volumio[1118]: info: Loading plugin "airplay_emulation"... Apr 21 16:40:07 mdxs volumio[1118]: info: Starting Shairport Sync Apr 21 16:40:07 mdxs volumio[1118]: info: Loading plugin "last_100"... Apr 21 16:40:07 mdxs volumio[1118]: info: Loading plugin "webradio"... Apr 21 16:40:07 mdxs volumio[1118]: info: Loading plugin "i2s_dacs"... Apr 21 16:40:07 mdxs volumio[1118]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 21 16:40:07 mdxs volumio[1118]: info: Loading plugin "volumiodiscovery"... Apr 21 16:40:07 mdxs volumio[1118]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 21 16:40:07 mdxs node[1118]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 21 16:40:07 mdxs node[1118]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 21 16:40:07 mdxs node[1118]: *** WARNING *** For more information see Apr 21 16:40:07 mdxs node[1118]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 21 16:40:07 mdxs node[1118]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 21 16:40:07 mdxs node[1118]: *** WARNING *** For more information see Apr 21 16:40:07 mdxs volumio[1118]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 21 16:40:07 mdxs volumio[1118]: *** WARNING *** For more information see Apr 21 16:40:07 mdxs volumio[1118]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 21 16:40:07 mdxs volumio[1118]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 21 16:40:07 mdxs volumio[1118]: *** WARNING *** For more information see Apr 21 16:40:07 mdxs volumio[1118]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 21 16:40:07 mdxs volumio[1118]: Discovery: StartAdv! undefined Apr 21 16:40:07 mdxs volumio[1118]: Discovery: Started advertising... mdxs - undefined Apr 21 16:40:07 mdxs volumio[1118]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 21 16:40:07 mdxs volumio[1118]: info: Loading plugin "outputs"... Apr 21 16:40:07 mdxs volumio[1118]: info: Loading plugin "albumart"... Apr 21 16:40:07 mdxs volumio[1118]: info: Plugin example_plugin is not enabled Apr 21 16:40:07 mdxs volumio[1118]: info: Loading plugin "inputs"... Apr 21 16:40:07 mdxs volumio[1118]: info: Loading plugin "updater_comm"... Apr 21 16:40:07 mdxs volumio[1118]: info: Plugin mpdemulation is not enabled Apr 21 16:40:07 mdxs volumio[1118]: info: Loading plugin "rest_api"... Apr 21 16:40:08 mdxs volumio[1118]: info: Loading plugin "websocket"... Apr 21 16:40:08 mdxs volumio[1118]: info: Loading plugin "volspotconnect2"... Apr 21 16:40:08 mdxs volumio[1118]: Forking 3 albumart workers Apr 21 16:40:08 mdxs dhcpcd[1022]: wlan0: soliciting a DHCP lease Apr 21 16:40:08 mdxs volumio[1118]: Starting albumart workers Apr 21 16:40:08 mdxs volumio[1118]: Starting albumart workers Apr 21 16:40:08 mdxs volumio[1118]: Starting albumart workers Apr 21 16:40:08 mdxs dhcpcd[564]: eth0: leased 192.168.0.88 for 86400 seconds Apr 21 16:40:08 mdxs dhcpcd[564]: eth0: adding host route to 192.168.0.88 via 127.0.0.1 Apr 21 16:40:08 mdxs avahi-daemon[668]: Registering new address record for 192.168.0.88 on eth0.IPv4. Apr 21 16:40:08 mdxs dhcpcd[1022]: eth0: removing route to 192.168.0.0/24 Apr 21 16:40:08 mdxs dhcpcd[564]: eth0: adding route to 192.168.0.0/24 Apr 21 16:40:08 mdxs dhcpcd[564]: eth0: adding default route via 192.168.0.1 Apr 21 16:40:08 mdxs dhcpcd[1022]: eth0: removing default route via 192.168.0.1 Apr 21 16:40:08 mdxs ifup[542]: dhcpcd[564]: eth0: leased 192.168.0.88 for 86400 seconds Apr 21 16:40:08 mdxs ifup[542]: dhcpcd[564]: eth0: adding host route to 192.168.0.88 via 127.0.0.1 Apr 21 16:40:08 mdxs ifup[542]: dhcpcd[564]: eth0: adding route to 192.168.0.0/24 Apr 21 16:40:08 mdxs ifup[542]: dhcpcd[564]: eth0: adding default route via 192.168.0.1 Apr 21 16:40:08 mdxs dhcpcd[564]: forked to background, child pid 1226 Apr 21 16:40:08 mdxs ifup[542]: dhcpcd[564]: forked to background, child pid 1226 Apr 21 16:40:08 mdxs dhcpcd[1226]: eth0: removing route to 192.168.0.0/24 Apr 21 16:40:08 mdxs dhcpcd[1226]: eth0: removing default route via 192.168.0.1 Apr 21 16:40:08 mdxs volumio[1118]: info: ___________ START PLUGINS ___________ Apr 21 16:40:08 mdxs volumio[1118]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 21 16:40:08 mdxs volumio[1118]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 21 16:40:08 mdxs volumio[1118]: info: [1713717608964] CoreMusicLibrary::Adding element Media Servers Apr 21 16:40:08 mdxs volumio[1118]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 21 16:40:09 mdxs volumio[1118]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 21 16:40:09 mdxs volumio[1118]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 21 16:40:09 mdxs volumio[1118]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 21 16:40:09 mdxs volumio[1118]: info: [1713717609066] CoreMusicLibrary::Adding element Last_100 Apr 21 16:40:09 mdxs volumio[1118]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 21 16:40:55 mdxs volumio[1118]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 21 16:40:55 mdxs systemd[1]: Time has been changed Apr 21 16:40:55 mdxs volumio[1118]: info: [1713717609068] CoreMusicLibrary::Adding element Webradio Apr 21 16:40:55 mdxs volumio[1118]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 21 16:40:55 mdxs volumio[1118]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 21 16:40:55 mdxs volumio[1118]: [SpotifyConnect] Creating VLS config file Apr 21 16:40:55 mdxs volumio[1118]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 16:40:55 mdxs volumio[1118]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 16:40:55 mdxs volumio[1118]: [SpotifyConnect] <> or Mixer found, using softvol Apr 21 16:40:55 mdxs volumio[1118]: [SpotifyConnect] Starting metadata listener Apr 21 16:40:55 mdxs volumio[1118]: info: ------------------------------------------- Apr 21 16:40:55 mdxs volumio[1118]: info: ----- MyVolumio plugins startup ---- Apr 21 16:40:55 mdxs volumio[1118]: info: ------------------------------------------- Apr 21 16:40:55 mdxs volumio[1118]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 21 16:40:55 mdxs sudo[1296]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect2.service Apr 21 16:40:55 mdxs volumio[1118]: info: Loading i18n strings for locale de Apr 21 16:40:55 mdxs volumio[1118]: Updating browse sources language Apr 21 16:40:55 mdxs volumio[1118]: Cannot find translation for sourceMedia Servers Apr 21 16:40:55 mdxs volumio[1118]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 21 16:40:55 mdxs sudo[1296]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 16:40:55 mdxs volumio[1118]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 21 16:40:55 mdxs volumio[1118]: info: CoreCommandRouter::initPlayerControls Apr 21 16:40:55 mdxs systemd[1]: Starting Volspotconnect2 Daemon... Apr 21 16:40:55 mdxs systemd[1]: Started Volspotconnect2 Daemon. Apr 21 16:40:55 mdxs volumio[1118]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 16:40:55 mdxs volumio[1118]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Apr 21 16:40:55 mdxs sudo[1296]: pam_unix(sudo:session): session closed for user root Apr 21 16:40:55 mdxs volumio[1118]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 16:40:55 mdxs volumio[1118]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 16:40:55 mdxs volumio[1118]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 16:40:55 mdxs volumio[1118]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 16:40:55 mdxs volumio[1118]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 16:40:55 mdxs volumio[1118]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 21 16:40:55 mdxs volumio[1118]: info: BOOT COMPLETED Apr 21 16:40:55 mdxs volumio[1118]: [Metrics] CommandRouter: 4s 165.43ms Apr 21 16:40:55 mdxs volumio[1118]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 21 16:40:55 mdxs volumio[1301]: vollibrespot v0.2.2 cd96399 2020-10-07 (librespot 08d8bcc 2020-10-07) -- Built On 2020-10-07 Apr 21 16:40:55 mdxs volumio[1301]: Reading Config from "volspotify.toml" Apr 21 16:40:55 mdxs volumio[1301]: [Vollibrespot] : Using Alsa backend with device: plughw:1 Apr 21 16:40:55 mdxs dhcpcd[1022]: wlan0: offered 192.168.0.220 from 192.168.0.1 Apr 21 16:40:55 mdxs volumio[1301]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Other, message: "No such device" } Apr 21 16:40:56 mdxs ntpd[730]: Listen normally on 5 eth0 192.168.0.88 UDP 123 Apr 21 16:40:56 mdxs ntpd[730]: peers refreshed Apr 21 16:40:59 mdxs volumio-remote-updater[612]: [2024-04-21 16:40:59] [connect] Successful connection Apr 21 16:41:00 mdxs sudo[1150]: pam_unix(sudo:session): session closed for user root Apr 21 16:41:00 mdxs dhcpcd[1022]: wlan0: leased 192.168.0.220 for 86400 seconds Apr 21 16:41:00 mdxs avahi-daemon[668]: Registering new address record for 192.168.0.220 on wlan0.IPv4. Apr 21 16:41:00 mdxs avahi-daemon[668]: Withdrawing address record for 169.254.14.162 on wlan0. Apr 21 16:41:00 mdxs avahi-daemon[668]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 169.254.14.162. Apr 21 16:41:00 mdxs dhcpcd[1022]: eth0: adding route to 192.168.0.0/24 Apr 21 16:41:00 mdxs dhcpcd[1022]: eth0: adding default route via 192.168.0.1 Apr 21 16:41:00 mdxs dhcpcd[1022]: wlan0: adding host route to 192.168.0.220 via 127.0.0.1 Apr 21 16:41:00 mdxs dhcpcd[1022]: wlan0: adding route to 192.168.0.0/24 Apr 21 16:41:00 mdxs dhcpcd[1022]: wlan0: adding default route via 192.168.0.1 Apr 21 16:41:00 mdxs dhcpcd[1022]: wlan0: deleting host route to 169.254.14.162 via 127.0.0.1 Apr 21 16:41:00 mdxs dhcpcd[1022]: wlan0: deleting route to 169.254.0.0/16 Apr 21 16:41:00 mdxs avahi-daemon[668]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.220. Apr 21 16:41:00 mdxs dhcpcd[1022]: eth0: removing route to 192.168.0.0/24 Apr 21 16:41:00 mdxs dhcpcd[1022]: eth0: removing default route via 192.168.0.1 Apr 21 16:41:01 mdxs volumio[1118]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Apr 21 16:41:01 mdxs volumio[1118]: info: CoreCommandRouter::Close All Modals sent Apr 21 16:41:01 mdxs volumio[1118]: info: CoreCommandRouter::Close All Modals sent Apr 21 16:41:01 mdxs volumio[1118]: Express server listening on port 3000 Apr 21 16:41:01 mdxs volumio[1118]: [Metrics] WebUI: 9s 798.07ms Apr 21 16:41:01 mdxs volumio[1118]: Volumio Calling Home Apr 21 16:41:01 mdxs volumio[1118]: info: CoreStateMachine::resetVolumioState Apr 21 16:41:01 mdxs volumio[1118]: info: CoreStateMachine::getcurrentVolume Apr 21 16:41:01 mdxs volumio[1118]: info: CoreCommandRouter::volumioRetrievevolume Apr 21 16:41:01 mdxs volumio[1118]: info: CoreStateMachine::pushState Apr 21 16:41:01 mdxs volumio[1118]: info: CoreStateMachine::getState Apr 21 16:41:01 mdxs volumio[1118]: info: CorePlayQueue::getTrack 0 Apr 21 16:41:01 mdxs volumio[1118]: info: CoreCommandRouter::volumioPushState Apr 21 16:41:01 mdxs volumio[1118]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 21 16:41:01 mdxs volumio[1118]: info: Setting Device type: Raspberry PI Apr 21 16:41:01 mdxs volumio[1118]: [SpotifyConnect] Vollibrespot Daemon service started! Apr 21 16:41:01 mdxs volumio[1118]: [Metrics] SpotifyConnect: 5s 578.50ms Apr 21 16:41:01 mdxs volumio[1118]: info: MPD running with PID671 ,establishing connection Apr 21 16:41:01 mdxs volumio-remote-updater[612]: [2024-04-21 16:41:01] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1713717659 101 Apr 21 16:41:01 mdxs volumio[1118]: info: Reloading queue from file Apr 21 16:41:01 mdxs volumio[1118]: info: Apr 21 16:41:01 mdxs volumio[1118]: info: message= [50@0] {} No such directory, stack=Error: [50@0] {} No such directory Apr 21 16:41:01 mdxs volumio[1118]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:63:14) Apr 21 16:41:01 mdxs volumio[1118]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:10) Apr 21 16:41:01 mdxs volumio[1118]: at emitOne (events.js:116:13) Apr 21 16:41:01 mdxs volumio[1118]: at Socket.emit (events.js:211:7) Apr 21 16:41:01 mdxs volumio[1118]: at addChunk (_stream_readable.js:263:12) Apr 21 16:41:01 mdxs volumio[1118]: at readableAddChunk (_stream_readable.js:246:13) Apr 21 16:41:01 mdxs volumio[1118]: at Socket.Readable.push (_stream_readable.js:208:10) Apr 21 16:41:01 mdxs volumio[1118]: at Pipe.onread (net.js:607:20) Apr 21 16:41:01 mdxs volumio[1118]: info: CoreStateMachine::setRepeat null single undefined Apr 21 16:41:01 mdxs volumio[1118]: info: CoreStateMachine::pushState Apr 21 16:41:01 mdxs volumio[1118]: info: CoreStateMachine::getState Apr 21 16:41:01 mdxs volumio[1118]: info: CorePlayQueue::getTrack 0 Apr 21 16:41:01 mdxs volumio[1118]: info: CoreCommandRouter::volumioPushState Apr 21 16:41:01 mdxs volumio[1118]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 21 16:41:01 mdxs volumio[1118]: info: CoreStateMachine::setRandom true Apr 21 16:41:01 mdxs volumio[1118]: info: CoreStateMachine::pushState Apr 21 16:41:01 mdxs volumio[1118]: info: CoreStateMachine::getState Apr 21 16:41:01 mdxs volumio[1118]: info: CorePlayQueue::getTrack 0 Apr 21 16:41:01 mdxs volumio[1118]: info: CoreCommandRouter::volumioPushState Apr 21 16:41:01 mdxs volumio[1118]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 21 16:41:01 mdxs volumio[1118]: info: Starting Shairport Sync Apr 21 16:41:01 mdxs volumio[1118]: info: Starting Shairport Sync Apr 21 16:41:01 mdxs sudo[1364]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 21 16:41:01 mdxs sudo[1364]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 16:41:01 mdxs sudo[1366]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 21 16:41:01 mdxs systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Apr 21 16:41:01 mdxs systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 21 16:41:01 mdxs sudo[1364]: pam_unix(sudo:session): session closed for user root Apr 21 16:41:01 mdxs sudo[1366]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 16:41:01 mdxs systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 21 16:41:01 mdxs systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Apr 21 16:41:01 mdxs systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 21 16:41:01 mdxs sudo[1366]: pam_unix(sudo:session): session closed for user root Apr 21 16:41:01 mdxs volumio[1118]: info: Shairport-Sync Started Apr 21 16:41:01 mdxs volumio[1118]: Error adding Membership: Error: addMembership EINVAL Apr 21 16:41:01 mdxs volumio[1118]: info: Shairport-Sync Started Apr 21 16:41:01 mdxs volumio[1118]: info: CoreCommandRouter::volumioGetState Apr 21 16:41:01 mdxs volumio[1118]: info: CoreStateMachine::getState Apr 21 16:41:01 mdxs volumio[1118]: info: CorePlayQueue::getTrack 0 Apr 21 16:41:02 mdxs volumio[1118]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Apr 21 16:41:02 mdxs volumio[1118]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Apr 21 16:41:02 mdxs ntpd[730]: Listen normally on 6 wlan0 192.168.0.220 UDP 123 Apr 21 16:41:02 mdxs ntpd[730]: Deleting interface #4 wlan0, 169.254.14.162#123, interface stats: received=0, sent=0, dropped=0, active_time=11 secs Apr 21 16:41:02 mdxs ntpd[730]: peers refreshed Apr 21 16:41:03 mdxs sudo[1396]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 21 16:41:03 mdxs sudo[1396]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 16:41:03 mdxs sudo[1396]: pam_unix(sudo:session): session closed for user root Apr 21 16:41:05 mdxs sudo[1405]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Apr 21 16:41:05 mdxs sudo[1405]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 16:41:05 mdxs systemd[1]: Starting UPnP Renderer front-end to MPD... Apr 21 16:41:05 mdxs systemd[1]: Started UPnP Renderer front-end to MPD. Apr 21 16:41:05 mdxs sudo[1405]: pam_unix(sudo:session): session closed for user root Apr 21 16:41:06 mdxs volumio[1118]: info: Upmpdcli Daemon Started Apr 21 16:41:06 mdxs volumio[1118]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 21 16:41:06 mdxs volumio[1118]: { Error: addMembership ENODEV Apr 21 16:41:06 mdxs volumio[1118]: at _errnoException (util.js:1022:11) Apr 21 16:41:06 mdxs volumio[1118]: at Socket.addMembership (dgram.js:615:11) Apr 21 16:41:06 mdxs volumio[1118]: at Timeout.addMembership [as _onTimeout] (/volumio/node_modules/node-ssdp/lib/index.js:264:16) Apr 21 16:41:06 mdxs volumio[1118]: at ontimeout (timers.js:482:11) Apr 21 16:41:06 mdxs volumio[1118]: at tryOnTimeout (timers.js:317:5) Apr 21 16:41:06 mdxs volumio[1118]: at Timer.listOnTimeout (timers.js:277:5) code: 'ENODEV', errno: 'ENODEV', syscall: 'addMembership' } Apr 21 16:41:06 mdxs volumio[1118]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 21 16:41:06 mdxs sudo[1422]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-21 16:40 Apr 21 16:41:06 mdxs sudo[1422]: 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"