-- Logs begin at Mon 2020-05-25 16:53:02 UTC, end at Tue 2025-04-22 11:02:31 UTC. -- Apr 22 11:02:00 volumio systemd[1]: Time has been changed Apr 22 11:02:00 volumio wpa_supplicant[1039]: wlan0: Trying to associate with SSID 'Vodafone-AF92' Apr 22 11:02:00 volumio wireless.js[841]: trying... Apr 22 11:02:00 volumio sudo[1125]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 22 11:02:00 volumio sudo[1125]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 11:02:00 volumio sudo[1125]: pam_unix(sudo:session): session closed for user root Apr 22 11:02:01 volumio wireless.js[841]: trying... Apr 22 11:02:01 volumio sudo[1134]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 22 11:02:01 volumio sudo[1134]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 11:02:01 volumio sudo[1134]: pam_unix(sudo:session): session closed for user root Apr 22 11:02:02 volumio wireless.js[841]: trying... Apr 22 11:02:02 volumio sudo[1143]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 22 11:02:02 volumio sudo[1143]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 11:02:02 volumio sudo[1143]: pam_unix(sudo:session): session closed for user root Apr 22 11:02:03 volumio wpa_supplicant[1039]: wlan0: Associated with a4:43:8c:0a:dd:b6 Apr 22 11:02:03 volumio wpa_supplicant[1039]: wlan0: WPA: Key negotiation completed with a4:43:8c:0a:dd:b6 [PTK=CCMP GTK=CCMP] Apr 22 11:02:03 volumio wpa_supplicant[1039]: wlan0: CTRL-EVENT-CONNECTED - Connection to a4:43:8c:0a:dd:b6 completed [id=0 id_str=] Apr 22 11:02:03 volumio dhcpcd[1091]: wlan0: carrier acquired Apr 22 11:02:03 volumio dhcpcd[1091]: wlan0: soliciting an IPv6 router Apr 22 11:02:03 volumio wireless.js[841]: trying... Apr 22 11:02:03 volumio sudo[1175]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 22 11:02:03 volumio sudo[1175]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 11:02:03 volumio sudo[1175]: pam_unix(sudo:session): session closed for user root Apr 22 11:02:04 volumio wireless.js[841]: trying... Apr 22 11:02:04 volumio sudo[1184]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 22 11:02:04 volumio sudo[1184]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 11:02:04 volumio sudo[1184]: pam_unix(sudo:session): session closed for user root Apr 22 11:02:05 volumio wireless.js[841]: trying... Apr 22 11:02:05 volumio sudo[1193]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 22 11:02:05 volumio volumio-remote-updater[689]: [2025-04-22 11:02:05] [info] asio async_connect error: system:111 (Connection refused) Apr 22 11:02:05 volumio volumio-remote-updater[689]: [2025-04-22 11:02:05] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Apr 22 11:02:05 volumio volumio-remote-updater[689]: [2025-04-22 11:02:05] [error] handle_connect error: Underlying Transport Error Apr 22 11:02:05 volumio sudo[1193]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 11:02:05 volumio sudo[1193]: pam_unix(sudo:session): session closed for user root Apr 22 11:02:06 volumio wpa_supplicant[1039]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Apr 22 11:02:06 volumio wireless.js[841]: trying... Apr 22 11:02:06 volumio sudo[1204]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 22 11:02:06 volumio sudo[1204]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 11:02:06 volumio sudo[1204]: pam_unix(sudo:session): session closed for user root Apr 22 11:02:07 volumio wireless.js[841]: trying... Apr 22 11:02:07 volumio sudo[1213]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 22 11:02:07 volumio sudo[1213]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 11:02:07 volumio sudo[1213]: pam_unix(sudo:session): session closed for user root Apr 22 11:02:08 volumio wireless.js[841]: trying... Apr 22 11:02:08 volumio sudo[1222]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 22 11:02:08 volumio sudo[1222]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 11:02:08 volumio sudo[1222]: pam_unix(sudo:session): session closed for user root Apr 22 11:02:08 volumio dhcpcd[1091]: wlan0: using IPv4LL address 169.254.192.120 Apr 22 11:02:08 volumio avahi-daemon[747]: Joining mDNS multicast group on interface wlan0.IPv4 with address 169.254.192.120. Apr 22 11:02:08 volumio avahi-daemon[747]: New relevant interface wlan0.IPv4 for mDNS. Apr 22 11:02:08 volumio dhcpcd[1091]: eth0: adding route to 192.168.0.0/24 Apr 22 11:02:08 volumio avahi-daemon[747]: Registering new address record for 169.254.192.120 on wlan0.IPv4. Apr 22 11:02:08 volumio dhcpcd[1091]: eth0: adding default route via 192.168.0.1 Apr 22 11:02:08 volumio dhcpcd[1091]: wlan0: adding host route to 169.254.192.120 via 127.0.0.1 Apr 22 11:02:08 volumio dhcpcd[1091]: wlan0: adding route to 169.254.0.0/16 Apr 22 11:02:09 volumio dhcpcd[1091]: eth0: removing route to 192.168.0.0/24 Apr 22 11:02:09 volumio dhcpcd[1091]: eth0: removing default route via 192.168.0.1 Apr 22 11:02:09 volumio wireless.js[841]: trying... Apr 22 11:02:09 volumio sudo[1255]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 22 11:02:09 volumio sudo[1255]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 11:02:09 volumio sudo[1255]: pam_unix(sudo:session): session closed for user root Apr 22 11:02:09 volumio wireless.js[841]: Connected to: ----Vodafone-AF92 Apr 22 11:02:09 volumio wireless.js[841]: ---- Apr 22 11:02:09 volumio sudo[1264]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 22 11:02:09 volumio sudo[1264]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 11:02:09 volumio sudo[1264]: pam_unix(sudo:session): session closed for user root Apr 22 11:02:09 volumio wireless.js[841]: ... joined AP, wlan0 IPv4 is 169.254.192.120, ipV6 is undefined Apr 22 11:02:09 volumio wireless.js[841]: It's done! AP Apr 22 11:02:09 volumio systemd[1]: Started Wireless Services. Apr 22 11:02:09 volumio systemd[1]: Starting Volumio Backend Module... Apr 22 11:02:09 volumio systemd[1]: Started Volumio Backend Module. Apr 22 11:02:09 volumio systemd[1]: Starting Volumio Streaming Daemon... Apr 22 11:02:09 volumio systemd[1]: Started Volumio Streaming Daemon. Apr 22 11:02:09 volumio systemd[1]: Starting Multi-User System. Apr 22 11:02:09 volumio systemd[1]: Reached target Multi-User System. Apr 22 11:02:09 volumio systemd[1]: Starting Graphical Interface. Apr 22 11:02:09 volumio systemd[1]: Reached target Graphical Interface. Apr 22 11:02:09 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Apr 22 11:02:09 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Apr 22 11:02:09 volumio systemd[1]: Startup finished in 8.936s (kernel) + 33.302s (userspace) = 42.239s. Apr 22 11:02:10 volumio ntpd[826]: Listen normally on 4 wlan0 169.254.192.120 UDP 123 Apr 22 11:02:10 volumio ntpd[826]: peers refreshed Apr 22 11:02:10 volumio volumio-streaming-daemon[1274]: ############################ Apr 22 11:02:10 volumio volumio-streaming-daemon[1274]: # Volumio Streaming Daemon # Apr 22 11:02:10 volumio volumio-streaming-daemon[1274]: # Running on port 7777 # Apr 22 11:02:10 volumio volumio-streaming-daemon[1274]: ############################ Apr 22 11:02:10 volumio volumio-streaming-daemon[1274]: Environment: production Apr 22 11:02:12 volumio volumio[1273]: info: ------------------------------------------- Apr 22 11:02:12 volumio volumio[1273]: info: ----- Volumio2 ---- Apr 22 11:02:12 volumio volumio[1273]: info: ------------------------------------------- Apr 22 11:02:12 volumio volumio[1273]: info: ----- System startup ---- Apr 22 11:02:12 volumio volumio[1273]: info: ------------------------------------------- Apr 22 11:02:12 volumio volumio[1273]: info: MYVOLUMIO Environment detected Apr 22 11:02:13 volumio dhcpcd[1091]: wlan0: soliciting a DHCP lease Apr 22 11:02:13 volumio volumio[1273]: info: Plugin folders cleanup Apr 22 11:02:13 volumio volumio[1273]: info: Scanning into folder /volumio/app/plugins/ Apr 22 11:02:13 volumio volumio[1273]: info: Scanning category audio_interface Apr 22 11:02:13 volumio volumio[1273]: info: Scanning category miscellanea Apr 22 11:02:13 volumio volumio[1273]: info: Scanning category music_service Apr 22 11:02:13 volumio volumio[1273]: info: Scanning category plugins.json Apr 22 11:02:13 volumio volumio[1273]: info: Scanning category system_controller Apr 22 11:02:13 volumio volumio[1273]: info: Scanning category user_interface Apr 22 11:02:13 volumio volumio[1273]: info: Scanning into folder /data/plugins/ Apr 22 11:02:13 volumio volumio[1273]: info: Plugin folders cleanup completed Apr 22 11:02:13 volumio volumio[1273]: info: ------------------------------------------- Apr 22 11:02:13 volumio volumio[1273]: info: ----- Core plugins startup ---- Apr 22 11:02:13 volumio volumio[1273]: info: ------------------------------------------- Apr 22 11:02:13 volumio volumio[1273]: info: Loading plugins from folder /volumio/app/plugins/ Apr 22 11:02:13 volumio volumio[1273]: info: Adding plugin upnp to MyMusic Plugins Apr 22 11:02:13 volumio volumio[1273]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 22 11:02:13 volumio volumio[1273]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 22 11:02:13 volumio volumio[1273]: info: Loading plugins from folder /data/plugins/ Apr 22 11:02:13 volumio volumio[1273]: info: Loading plugin "system"... Apr 22 11:02:13 volumio volumio[1273]: info: Loading plugin "appearance"... Apr 22 11:02:15 volumio volumio[1273]: info: Loading plugin "network"... Apr 22 11:02:15 volumio volumio[1273]: info: Refreshing Cached IP Addresses Apr 22 11:02:15 volumio sudo[1299]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 22 11:02:15 volumio volumio[1273]: info: Loading plugin "services"... Apr 22 11:02:15 volumio volumio[1273]: info: Loading plugin "alsa_controller"... Apr 22 11:02:15 volumio sudo[1299]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 11:02:15 volumio sudo[1299]: pam_unix(sudo:session): session closed for user root Apr 22 11:02:15 volumio sudo[1302]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 22 11:02:15 volumio sudo[1302]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 11:02:15 volumio sudo[1302]: pam_unix(sudo:session): session closed for user root Apr 22 11:02:15 volumio volumio[1273]: info: Loading plugin "wizard"... Apr 22 11:02:15 volumio volumio[1273]: info: Loading plugin "volumio_command_line_client"... Apr 22 11:02:15 volumio volumio[1273]: info: Loading plugin "upnp"... Apr 22 11:02:15 volumio volumio[1273]: info: [1745319735531] Starting Upmpd Daemon Apr 22 11:02:15 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 22 11:02:15 volumio volumio[1273]: info: Loading plugin "my_music"... Apr 22 11:02:15 volumio volumio[1273]: info: Loading plugin "mpd"... Apr 22 11:02:15 volumio volumio-remote-updater[689]: [2025-04-22 11:02:15] [connect] Successful connection Apr 22 11:02:16 volumio volumio[1273]: info: Loading plugin "upnp_browser"... Apr 22 11:02:16 volumio dhcpcd[1091]: wlan0: offered 192.168.0.175 from 192.168.0.1 Apr 22 11:02:16 volumio volumio[1273]: info: Loading plugin "networkfs"... Apr 22 11:02:16 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 22 11:02:16 volumio volumio[1273]: info: Loading plugin "alarm-clock"... Apr 22 11:02:17 volumio volumio[1273]: info: Loading plugin "airplay_emulation"... Apr 22 11:02:17 volumio volumio[1273]: info: Starting Shairport Sync Apr 22 11:02:17 volumio volumio[1273]: info: Loading plugin "last_100"... Apr 22 11:02:17 volumio volumio[1273]: info: Loading plugin "webradio"... Apr 22 11:02:17 volumio volumio[1273]: info: Loading plugin "i2s_dacs"... Apr 22 11:02:17 volumio volumio[1273]: info: I2S DAC not set, start Auto-detection Apr 22 11:02:17 volumio volumio[1273]: info: Loading plugin "volumiodiscovery"... Apr 22 11:02:17 volumio volumio[1273]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 22 11:02:17 volumio node[1273]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 22 11:02:17 volumio node[1273]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 22 11:02:17 volumio node[1273]: *** WARNING *** For more information see Apr 22 11:02:17 volumio node[1273]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 22 11:02:17 volumio node[1273]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 22 11:02:17 volumio node[1273]: *** WARNING *** For more information see Apr 22 11:02:17 volumio volumio[1273]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 22 11:02:17 volumio volumio[1273]: *** WARNING *** For more information see Apr 22 11:02:17 volumio volumio[1273]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 22 11:02:17 volumio volumio[1273]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 22 11:02:17 volumio volumio[1273]: *** WARNING *** For more information see Apr 22 11:02:17 volumio volumio[1273]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 22 11:02:17 volumio volumio[1273]: Discovery: StartAdv! undefined Apr 22 11:02:17 volumio volumio[1273]: Discovery: Started advertising... Volumio - undefined Apr 22 11:02:17 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 22 11:02:17 volumio volumio[1273]: info: Loading plugin "outputs"... Apr 22 11:02:17 volumio volumio[1273]: info: Loading plugin "albumart"... Apr 22 11:02:17 volumio volumio[1273]: info: Plugin example_plugin is not enabled Apr 22 11:02:17 volumio volumio[1273]: info: Loading plugin "inputs"... Apr 22 11:02:17 volumio volumio[1273]: info: Loading plugin "updater_comm"... Apr 22 11:02:17 volumio volumio[1273]: info: Plugin mpdemulation is not enabled Apr 22 11:02:17 volumio volumio[1273]: info: Loading plugin "rest_api"... Apr 22 11:02:17 volumio volumio[1273]: info: Loading plugin "websocket"... Apr 22 11:02:17 volumio volumio[1273]: info: ___________ START PLUGINS ___________ Apr 22 11:02:17 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 22 11:02:17 volumio volumio[1273]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 22 11:02:17 volumio volumio[1273]: info: [1745319737677] CoreMusicLibrary::Adding element Media Servers Apr 22 11:02:17 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 22 11:02:17 volumio volumio[1273]: Forking 3 albumart workers Apr 22 11:02:17 volumio volumio[1273]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 22 11:02:17 volumio volumio[1273]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 22 11:02:17 volumio volumio[1273]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 22 11:02:17 volumio volumio[1273]: info: [1745319737829] CoreMusicLibrary::Adding element Last_100 Apr 22 11:02:17 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 22 11:02:17 volumio volumio[1273]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 22 11:02:17 volumio volumio[1273]: info: [1745319737834] CoreMusicLibrary::Adding element Webradio Apr 22 11:02:17 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 22 11:02:17 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 22 11:02:17 volumio volumio[1273]: info: ------------------------------------------- Apr 22 11:02:17 volumio volumio[1273]: info: ----- MyVolumio plugins startup ---- Apr 22 11:02:17 volumio volumio[1273]: info: ------------------------------------------- Apr 22 11:02:17 volumio volumio[1273]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 22 11:02:17 volumio volumio[1273]: info: Loading i18n strings for locale de Apr 22 11:02:17 volumio volumio[1273]: Updating browse sources language Apr 22 11:02:17 volumio volumio[1273]: Cannot find translation for sourceMedia Servers Apr 22 11:02:17 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 22 11:02:17 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 22 11:02:17 volumio volumio[1273]: info: CoreCommandRouter::initPlayerControls Apr 22 11:02:17 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 11:02:17 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Apr 22 11:02:17 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 11:02:17 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 11:02:17 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 11:02:17 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 11:02:17 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 11:02:17 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 22 11:02:17 volumio volumio[1273]: info: BOOT COMPLETED Apr 22 11:02:17 volumio volumio[1273]: [Metrics] CommandRouter: 5s 305.47ms Apr 22 11:02:17 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 22 11:02:18 volumio volumio[1273]: Starting albumart workers Apr 22 11:02:18 volumio volumio[1273]: Starting albumart workers Apr 22 11:02:18 volumio volumio[1273]: Starting albumart workers Apr 22 11:02:20 volumio dhcpcd[1091]: wlan0: leased 192.168.0.175 for 604800 seconds Apr 22 11:02:20 volumio avahi-daemon[747]: Registering new address record for 192.168.0.175 on wlan0.IPv4. Apr 22 11:02:20 volumio avahi-daemon[747]: Withdrawing address record for 169.254.192.120 on wlan0. Apr 22 11:02:20 volumio avahi-daemon[747]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 169.254.192.120. Apr 22 11:02:20 volumio avahi-daemon[747]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.175. Apr 22 11:02:20 volumio dhcpcd[1091]: eth0: adding route to 192.168.0.0/24 Apr 22 11:02:20 volumio dhcpcd[1091]: eth0: adding default route via 192.168.0.1 Apr 22 11:02:20 volumio dhcpcd[1091]: wlan0: adding host route to 192.168.0.175 via 127.0.0.1 Apr 22 11:02:20 volumio dhcpcd[1091]: wlan0: adding route to 192.168.0.0/24 Apr 22 11:02:20 volumio dhcpcd[1091]: wlan0: adding default route via 192.168.0.1 Apr 22 11:02:20 volumio dhcpcd[1091]: wlan0: deleting host route to 169.254.192.120 via 127.0.0.1 Apr 22 11:02:20 volumio dhcpcd[1091]: wlan0: deleting route to 169.254.0.0/16 Apr 22 11:02:21 volumio systemd[1]: Stopping LSB: Start NTP daemon... Apr 22 11:02:21 volumio ntpd[826]: ntpd exiting on signal 15 Apr 22 11:02:21 volumio ntp[1486]: Stopping NTP server: ntpd. Apr 22 11:02:21 volumio systemd[1]: Starting LSB: Start NTP daemon... Apr 22 11:02:21 volumio ntpd[1504]: ntpd 4.2.6p5@1.2349-o Mon Jul 25 22:35:28 UTC 2016 (1) Apr 22 11:02:21 volumio ntp[1497]: Starting NTP server: ntpd. Apr 22 11:02:21 volumio ntpd[1505]: proto: precision = 0.468 usec Apr 22 11:02:21 volumio ntpd[1505]: Listen and drop on 0 v4wildcard 0.0.0.0 UDP 123 Apr 22 11:02:21 volumio systemd[1]: Started LSB: Start NTP daemon. Apr 22 11:02:21 volumio ntpd[1505]: Listen and drop on 1 v6wildcard :: UDP 123 Apr 22 11:02:21 volumio ntpd[1505]: Listen normally on 2 lo 127.0.0.1 UDP 123 Apr 22 11:02:21 volumio ntpd[1505]: Listen normally on 3 eth0 192.168.0.190 UDP 123 Apr 22 11:02:21 volumio ntpd[1505]: Listen normally on 4 wlan0 192.168.0.175 UDP 123 Apr 22 11:02:21 volumio ntpd[1505]: peers refreshed Apr 22 11:02:21 volumio ntpd[1505]: Listening on routing socket on fd #21 for interface updates Apr 22 11:02:21 volumio dhcpcd[1091]: eth0: removing route to 192.168.0.0/24 Apr 22 11:02:21 volumio dhcpcd[1091]: eth0: removing default route via 192.168.0.1 Apr 22 11:02:23 volumio volumio[1273]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Apr 22 11:02:23 volumio volumio[1273]: info: CoreCommandRouter::Close All Modals sent Apr 22 11:02:23 volumio volumio[1273]: info: CoreCommandRouter::Close All Modals sent Apr 22 11:02:23 volumio volumio[1273]: Express server listening on port 3000 Apr 22 11:02:23 volumio volumio[1273]: [Metrics] WebUI: 11s 531.68ms Apr 22 11:02:23 volumio volumio[1273]: Volumio Calling Home Apr 22 11:02:23 volumio volumio[1273]: info: CoreStateMachine::resetVolumioState Apr 22 11:02:23 volumio volumio[1273]: info: CoreStateMachine::getcurrentVolume Apr 22 11:02:23 volumio volumio[1273]: info: CoreCommandRouter::volumioRetrievevolume Apr 22 11:02:23 volumio volumio[1273]: info: Setting Device type: Raspberry PI Apr 22 11:02:23 volumio volumio[1273]: info: MPD running with PID755 Apr 22 11:02:23 volumio volumio[1273]: ,establishing connection Apr 22 11:02:23 volumio sudo[1515]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cdetect -y 1 Apr 22 11:02:23 volumio sudo[1515]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 11:02:23 volumio sudo[1515]: pam_unix(sudo:session): session closed for user root Apr 22 11:02:23 volumio volumio[1273]: info: VolumeController:: Volume=46 Mute =false Apr 22 11:02:23 volumio volumio[1273]: info: CoreStateMachine::pushState Apr 22 11:02:23 volumio volumio[1273]: info: CorePlayQueue::getTrack 0 Apr 22 11:02:23 volumio volumio[1273]: info: CoreCommandRouter::volumioPushState Apr 22 11:02:23 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 11:02:23 volumio volumio[1273]: info: CoreStateMachine::updateTrackBlock Apr 22 11:02:23 volumio volumio[1273]: info: CorePlayQueue::getTrackBlock Apr 22 11:02:23 volumio volumio[1273]: info: CoreCommandRouter::volumioRetrievevolume Apr 22 11:02:23 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 22 11:02:23 volumio volumio[1273]: info: Reloading queue from file Apr 22 11:02:23 volumio volumio[1273]: error: updateQueue error: null Apr 22 11:02:23 volumio volumio[1273]: error: Failed LSINFO: Error: [50@0] {} No such directory Apr 22 11:02:23 volumio volumio[1273]: info: CoreStateMachine::setRepeat null single undefined Apr 22 11:02:23 volumio volumio[1273]: info: CoreStateMachine::pushState Apr 22 11:02:23 volumio volumio[1273]: info: CorePlayQueue::getTrack 0 Apr 22 11:02:23 volumio volumio[1273]: info: CoreCommandRouter::volumioPushState Apr 22 11:02:23 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 11:02:23 volumio volumio[1273]: info: CoreStateMachine::setRandom null Apr 22 11:02:23 volumio volumio[1273]: info: CoreStateMachine::pushState Apr 22 11:02:23 volumio volumio[1273]: info: CorePlayQueue::getTrack 0 Apr 22 11:02:23 volumio volumio[1273]: info: CoreCommandRouter::volumioPushState Apr 22 11:02:23 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 11:02:23 volumio volumio[1273]: info: VolumeController:: Volume=46 Mute =false Apr 22 11:02:23 volumio volumio[1273]: info: CoreStateMachine::pushState Apr 22 11:02:23 volumio volumio[1273]: info: CorePlayQueue::getTrack 0 Apr 22 11:02:23 volumio volumio[1273]: info: CoreCommandRouter::volumioPushState Apr 22 11:02:23 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 11:02:23 volumio volumio[1273]: info: Starting Shairport Sync Apr 22 11:02:24 volumio volumio[1273]: info: Starting Shairport Sync Apr 22 11:02:24 volumio sudo[1528]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 22 11:02:24 volumio sudo[1528]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 11:02:24 volumio sudo[1530]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 22 11:02:24 volumio sudo[1530]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 11:02:24 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Apr 22 11:02:24 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 22 11:02:24 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 22 11:02:24 volumio sudo[1528]: pam_unix(sudo:session): session closed for user root Apr 22 11:02:24 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Apr 22 11:02:24 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 22 11:02:24 volumio sudo[1530]: pam_unix(sudo:session): session closed for user root Apr 22 11:02:24 volumio volumio[1273]: Discovery: adding 7d334587-4773-4203-9841-14310d6baf87 Apr 22 11:02:24 volumio volumio[1273]: info: mDNS: Found device Volumio Apr 22 11:02:24 volumio volumio[1273]: info: CoreCommandRouter::volumioGetState Apr 22 11:02:24 volumio volumio[1273]: info: CorePlayQueue::getTrack 0 Apr 22 11:02:24 volumio volumio[1273]: Discovery: this is already registered, 7d334587-4773-4203-9841-14310d6baf87 Apr 22 11:02:24 volumio volumio[1273]: info: mDNS: Found device Volumio Apr 22 11:02:24 volumio volumio[1273]: info: CoreCommandRouter::volumioGetState Apr 22 11:02:24 volumio volumio[1273]: info: CorePlayQueue::getTrack 0 Apr 22 11:02:24 volumio volumio[1273]: info: Shairport-Sync Started Apr 22 11:02:24 volumio volumio[1273]: Error adding Membership: Error: addMembership EINVAL Apr 22 11:02:24 volumio volumio[1273]: info: Shairport-Sync Started Apr 22 11:02:24 volumio volumio[1273]: info: CoreCommandRouter::volumioGetState Apr 22 11:02:24 volumio volumio[1273]: info: CorePlayQueue::getTrack 0 Apr 22 11:02:24 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Apr 22 11:02:24 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Apr 22 11:02:25 volumio sudo[1563]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 22 11:02:25 volumio sudo[1563]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 11:02:25 volumio sudo[1563]: pam_unix(sudo:session): session closed for user root Apr 22 11:02:25 volumio sudo[1567]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 22 11:02:25 volumio sudo[1573]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Apr 22 11:02:25 volumio sudo[1567]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 11:02:25 volumio sudo[1567]: pam_unix(sudo:session): session closed for user root Apr 22 11:02:25 volumio sudo[1573]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 11:02:25 volumio systemd[1]: Starting UPnP Renderer front-end to MPD... Apr 22 11:02:25 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Apr 22 11:02:25 volumio sudo[1573]: pam_unix(sudo:session): session closed for user root Apr 22 11:02:25 volumio volumio[1273]: info: Upmpdcli Daemon Started Apr 22 11:02:26 volumio volumio[1273]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Apr 22 11:02:26 volumio volumio[1273]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Apr 22 11:02:26 volumio volumio[1273]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Apr 22 11:02:26 volumio volumio[1273]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Apr 22 11:02:26 volumio volumio[1273]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Apr 22 11:02:26 volumio volumio[1273]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Apr 22 11:02:26 volumio volumio[1273]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Apr 22 11:02:26 volumio volumio[1273]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Apr 22 11:02:26 volumio volumio[1273]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Apr 22 11:02:26 volumio volumio[1273]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Apr 22 11:02:26 volumio volumio[1273]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Apr 22 11:02:26 volumio volumio[1273]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Apr 22 11:02:26 volumio volumio[1273]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Apr 22 11:02:26 volumio volumio[1273]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Apr 22 11:02:26 volumio volumio[1273]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Apr 22 11:02:26 volumio volumio[1273]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Apr 22 11:02:26 volumio volumio[1273]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Apr 22 11:02:26 volumio volumio[1273]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Apr 22 11:02:26 volumio volumio[1273]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Apr 22 11:02:26 volumio volumio[1273]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Apr 22 11:02:26 volumio volumio[1273]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Apr 22 11:02:26 volumio volumio[1273]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Apr 22 11:02:26 volumio volumio[1273]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Apr 22 11:02:26 volumio volumio[1273]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Apr 22 11:02:26 volumio volumio[1273]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Apr 22 11:02:26 volumio volumio[1273]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Apr 22 11:02:26 volumio volumio[1273]: info: Adding plugin bluetooth to MyMusic Plugins Apr 22 11:02:26 volumio volumio[1273]: info: Adding plugin cd_controller to MyMusic Plugins Apr 22 11:02:26 volumio volumio[1273]: info: Adding plugin smart_inputs to MyMusic Plugins Apr 22 11:02:26 volumio volumio[1273]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Apr 22 11:02:28 volumio volumio[1273]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Apr 22 11:02:30 volumio volumio[1273]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Apr 22 11:02:30 volumio volumio[1273]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Apr 22 11:02:30 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 22 11:02:30 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 22 11:02:30 volumio volumio[1273]: info: Starting MyVolumio Remote Streaming Endpoints Apr 22 11:02:30 volumio volumio[1273]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Apr 22 11:02:30 volumio volumio[1273]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Apr 22 11:02:30 volumio volumio[1273]: info: Streaming services startup Apr 22 11:02:30 volumio volumio[1273]: info: Starting Streaming Daemon Apr 22 11:02:30 volumio sudo[1604]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 22 11:02:30 volumio volumio[1273]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Apr 22 11:02:30 volumio sudo[1604]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 11:02:30 volumio systemd[1]: Stopping Volumio Streaming Daemon... Apr 22 11:02:30 volumio systemd[1]: Starting Volumio Streaming Daemon... Apr 22 11:02:30 volumio systemd[1]: Started Volumio Streaming Daemon. Apr 22 11:02:30 volumio sudo[1604]: pam_unix(sudo:session): session closed for user root Apr 22 11:02:30 volumio volumio-streaming-daemon[1610]: ############################ Apr 22 11:02:30 volumio volumio-streaming-daemon[1610]: # Volumio Streaming Daemon # Apr 22 11:02:30 volumio volumio-streaming-daemon[1610]: # Running on port 7777 # Apr 22 11:02:30 volumio volumio-streaming-daemon[1610]: ############################ Apr 22 11:02:30 volumio volumio-streaming-daemon[1610]: Environment: production Apr 22 11:02:30 volumio volumio[1273]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 22 11:02:30 volumio volumio[1273]: { Error: addMembership ENODEV Apr 22 11:02:30 volumio volumio[1273]: at _errnoException (util.js:1022:11) Apr 22 11:02:30 volumio volumio[1273]: at Socket.addMembership (dgram.js:615:11) Apr 22 11:02:30 volumio volumio[1273]: at Timeout.addMembership [as _onTimeout] (/volumio/node_modules/node-ssdp/lib/index.js:264:16) Apr 22 11:02:30 volumio volumio[1273]: at ontimeout (timers.js:482:11) Apr 22 11:02:30 volumio volumio[1273]: at tryOnTimeout (timers.js:317:5) Apr 22 11:02:30 volumio volumio[1273]: at Timer.listOnTimeout (timers.js:277:5) code: 'ENODEV', errno: 'ENODEV', syscall: 'addMembership' } Apr 22 11:02:30 volumio volumio[1273]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 22 11:02:30 volumio volumio-remote-updater[689]: [2025-04-22 11:02:30] [connect] Successful connection Apr 22 11:02:31 volumio sudo[1628]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-22 11:01 Apr 22 11:02:31 volumio sudo[1628]: 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="b9cd485c2d7861a79d18f42ff0fdb052338b2239" VOLUMIO_FE_VERSION="2932a2aa65665ab9ec21cd0bdf3d21669bbbf643" VOLUMIO_BE_VERSION="8583c814bdc88e8c28c10464bec8226bce2b0634" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon May 25 18:53:02 CEST 2020" VOLUMIO_VERSION="2.777" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="c75975235b22f56393d7c4516aa10590"