-- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Sun 2025-05-04 06:47:55 UTC. -- May 04 06:46:42 volumio dhcpcd[755]: wlan0: carrier lost May 04 06:46:42 volumio wpa_supplicant[741]: wlan0: CTRL-EVENT-DISCONNECTED bssid=f0:f8:4a:b3:c6:25 reason=0 locally_generated=1 May 04 06:46:42 volumio wpa_supplicant[741]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD May 04 06:46:42 volumio wpa_supplicant[741]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=JP May 04 06:46:42 volumio avahi-daemon[475]: Withdrawing address record for 192.168.1.18 on wlan0. May 04 06:46:42 volumio avahi-daemon[475]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.18. May 04 06:46:42 volumio avahi-daemon[475]: Interface wlan0.IPv4 no longer relevant for mDNS. May 04 06:46:42 volumio dhcpcd[755]: wlan0: deleting route to 192.168.1.0/24 May 04 06:46:42 volumio dhcpcd[755]: wlan0: deleting default route via 192.168.1.1 May 04 06:46:42 volumio volumio[16542]: info: Discovery: A device disappeared from network May 04 06:46:42 volumio volumio[16542]: info: Discovery: Device volumio disappeared from network May 04 06:46:43 volumio wpa_supplicant[741]: wlan0: Trying to associate with SSID 'Buffalo-A-5A90' May 04 06:46:45 volumio ntpd[646]: Deleting interface #27 wlan0, 192.168.1.18#123, interface stats: received=459, sent=464, dropped=0, active_time=22023 secs May 04 06:46:45 volumio ntpd[646]: 139.162.81.45 local addr 192.168.1.18 -> May 04 06:46:45 volumio ntpd[646]: 194.0.5.123 local addr 192.168.1.18 -> May 04 06:46:45 volumio ntpd[646]: 160.16.113.133 local addr 192.168.1.18 -> May 04 06:46:45 volumio ntpd[646]: 172.237.12.44 local addr 192.168.1.18 -> May 04 06:46:45 volumio ntpd[646]: 23.81.40.154 local addr 192.168.1.18 -> May 04 06:46:45 volumio ntpd[646]: 45.76.211.39 local addr 192.168.1.18 -> May 04 06:46:45 volumio ntpd[646]: 133.130.121.141 local addr 192.168.1.18 -> May 04 06:46:45 volumio ntpd[646]: 103.131.151.20 local addr 192.168.1.18 -> May 04 06:46:45 volumio ntpd[646]: 202.181.103.212 local addr 192.168.1.18 -> May 04 06:46:45 volumio ntpd[646]: 47.79.37.210 local addr 192.168.1.18 -> May 04 06:46:45 volumio ntpd[646]: 18.180.64.47 local addr 192.168.1.18 -> May 04 06:46:45 volumio ntpd[646]: 167.179.119.205 local addr 192.168.1.18 -> May 04 06:46:45 volumio ntpd[646]: 162.159.200.123 local addr 192.168.1.18 -> May 04 06:46:45 volumio ntpd[646]: 162.159.200.1 local addr 192.168.1.18 -> May 04 06:46:45 volumio ntpd[646]: 45.77.20.103 local addr 192.168.1.18 -> May 04 06:46:45 volumio ntpd[646]: 103.131.151.30 local addr 192.168.1.18 -> May 04 06:46:47 volumio wpa_supplicant[741]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 May 04 06:46:49 volumio wpa_supplicant[741]: wlan0: Trying to associate with SSID 'Buffalo-A-5A90' May 04 06:46:50 volumio wpa_supplicant[741]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 May 04 06:46:53 volumio wpa_supplicant[741]: wlan0: Trying to associate with SSID 'Buffalo-A-5A90' May 04 06:46:54 volumio volumio[16542]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 04 06:46:54 volumio volumio[16542]: Error: send ENETUNREACH 255.255.255.255:3483 May 04 06:46:54 volumio volumio[16542]: at doSend (dgram.js:692:16) May 04 06:46:54 volumio volumio[16542]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) May 04 06:46:54 volumio volumio[16542]: at afterDns (dgram.js:638:5) May 04 06:46:54 volumio volumio[16542]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { May 04 06:46:54 volumio volumio[16542]: errno: -101, May 04 06:46:54 volumio volumio[16542]: code: 'ENETUNREACH', May 04 06:46:54 volumio volumio[16542]: syscall: 'send', May 04 06:46:54 volumio volumio[16542]: address: '255.255.255.255', May 04 06:46:54 volumio volumio[16542]: port: 3483 May 04 06:46:54 volumio volumio[16542]: } May 04 06:46:54 volumio volumio[16542]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 04 06:46:55 volumio wpa_supplicant[741]: wlan0: Associated with f0:f8:4a:b3:c6:25 May 04 06:46:55 volumio wpa_supplicant[741]: wlan0: CTRL-EVENT-CONNECTED - Connection to f0:f8:4a:b3:c6:25 completed [id=0 id_str=] May 04 06:46:55 volumio dhcpcd[755]: wlan0: carrier acquired May 04 06:46:55 volumio wpa_supplicant[741]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 May 04 06:46:55 volumio wpa_supplicant[741]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=JP May 04 06:46:55 volumio dhcpcd[755]: wlan0: IAID eb:0d:71:2a May 04 06:46:55 volumio sudo[20077]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-04 06:45 May 04 06:46:55 volumio sudo[20077]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 06:46:55 volumio sudo[20077]: pam_unix(sudo:session): session closed for user root May 04 06:46:56 volumio dhcpcd[755]: wlan0: soliciting an IPv6 router May 04 06:46:56 volumio volumio-remote-updater[509]: [2025-05-04 06:46:56] [error] handle_read_frame error: websocketpp.transport:7 (End of File) May 04 06:46:56 volumio volumio-remote-updater[509]: [2025-05-04 06:46:56] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] May 04 06:46:56 volumio dhcpcd[755]: wlan0: rebinding lease of 192.168.1.18 May 04 06:46:57 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE May 04 06:46:57 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. May 04 06:46:57 volumio systemd[1]: Started dynamicswap service. May 04 06:46:57 volumio systemd[1]: dynamicswap.service: Succeeded. May 04 06:46:57 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. May 04 06:46:57 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 9. May 04 06:46:57 volumio systemd[1]: Stopped Volumio Backend Module. May 04 06:46:57 volumio systemd[1]: Started Volumio Backend Module. May 04 06:46:57 volumio systemd[1]: Started dynamicswap service. May 04 06:46:57 volumio systemd[1]: dynamicswap.service: Succeeded. May 04 06:47:01 volumio dhcpcd[755]: wlan0: probing address 192.168.1.18/24 May 04 06:47:01 volumio volumio[20090]: info: ------------------------------------------- May 04 06:47:01 volumio volumio[20090]: info: ----- Volumio3 ---- May 04 06:47:01 volumio volumio[20090]: info: ------------------------------------------- May 04 06:47:01 volumio volumio[20090]: info: ----- System startup ---- May 04 06:47:01 volumio volumio[20090]: info: ------------------------------------------- May 04 06:47:02 volumio volumio-remote-updater[509]: [2025-05-04 06:47:02] [connect] Successful connection May 04 06:47:03 volumio volumio[20090]: info: MYVOLUMIO Environment detected May 04 06:47:03 volumio volumio[20090]: info: Plugin folders cleanup May 04 06:47:03 volumio volumio[20090]: info: Scanning into folder /volumio/app/plugins/ May 04 06:47:03 volumio volumio[20090]: info: Scanning category audio_interface May 04 06:47:03 volumio volumio[20090]: info: Scanning category miscellanea May 04 06:47:03 volumio volumio[20090]: info: Scanning category music_service May 04 06:47:03 volumio volumio[20090]: info: Scanning category plugins.json May 04 06:47:03 volumio volumio[20090]: info: Scanning category system_controller May 04 06:47:03 volumio volumio[20090]: info: Scanning category user_interface May 04 06:47:03 volumio volumio[20090]: info: Scanning into folder /data/plugins/ May 04 06:47:03 volumio volumio[20090]: info: Scanning category audio_interface May 04 06:47:03 volumio volumio[20090]: info: Scanning category music_service May 04 06:47:03 volumio volumio[20090]: info: Scanning category user_interface May 04 06:47:03 volumio volumio[20090]: info: Plugin folders cleanup completed May 04 06:47:03 volumio volumio[20090]: info: ------------------------------------------- May 04 06:47:03 volumio volumio[20090]: info: ----- Core plugins startup ---- May 04 06:47:03 volumio volumio[20090]: info: ------------------------------------------- May 04 06:47:03 volumio volumio[20090]: info: Loading plugins from folder /volumio/app/plugins/ May 04 06:47:03 volumio volumio[20090]: info: Adding plugin upnp to MyMusic Plugins May 04 06:47:03 volumio volumio[20090]: info: Adding plugin airplay_emulation to MyMusic Plugins May 04 06:47:03 volumio volumio[20090]: info: Adding plugin upnp_browser to MyMusic Plugins May 04 06:47:03 volumio volumio[20090]: info: Loading plugins from folder /data/plugins/ May 04 06:47:03 volumio volumio[20090]: info: Loading plugin "system"... May 04 06:47:03 volumio volumio[20090]: info: Loading plugin "appearance"... May 04 06:47:05 volumio volumio[20090]: info: Loading plugin "network"... May 04 06:47:05 volumio volumio[20090]: info: Refreshing Cached IP Addresses May 04 06:47:05 volumio sudo[20116]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 04 06:47:05 volumio sudo[20116]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 06:47:05 volumio sudo[20116]: pam_unix(sudo:session): session closed for user root May 04 06:47:05 volumio sudo[20118]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 04 06:47:05 volumio sudo[20118]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 06:47:05 volumio volumio[20090]: info: Loading plugin "services"... May 04 06:47:05 volumio volumio[20090]: info: Loading plugin "alsa_controller"... May 04 06:47:05 volumio sudo[20118]: pam_unix(sudo:session): session closed for user root May 04 06:47:05 volumio sudo[20121]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 04 06:47:05 volumio sudo[20121]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 06:47:05 volumio nmbd[629]: [2025/05/04 06:47:05.768394, 0] ../source3/nmbd/nmbd.c:361(reload_interfaces) May 04 06:47:05 volumio nmbd[629]: reload_interfaces: No subnets to listen to. Waiting.. May 04 06:47:05 volumio volumio[20090]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 04 06:47:05 volumio volumio[20090]: info: Loading plugin "wizard"... May 04 06:47:05 volumio volumio[20090]: info: Loading plugin "volumio_command_line_client"... May 04 06:47:05 volumio volumio[20090]: info: Loading plugin "upnp"... May 04 06:47:05 volumio volumio[20090]: info: [1746341225853] Starting Upmpd Daemon May 04 06:47:05 volumio volumio[20090]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 04 06:47:05 volumio volumio[20090]: info: Loading plugin "my_music"... May 04 06:47:05 volumio volumio[20090]: info: Loading plugin "mpd"... May 04 06:47:06 volumio volumio[20090]: info: Creating MPD Configuration file May 04 06:47:06 volumio volumio[20090]: info: Loading plugin "upnp_browser"... May 04 06:47:07 volumio dhcpcd[755]: wlan0: leased 192.168.1.18 for 86400 seconds May 04 06:47:07 volumio volumio[20090]: info: Loading plugin "networkfs"... May 04 06:47:07 volumio avahi-daemon[475]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.18. May 04 06:47:07 volumio dhcpcd[755]: wlan0: adding route to 192.168.1.0/24 May 04 06:47:07 volumio dhcpcd[755]: wlan0: adding default route via 192.168.1.1 May 04 06:47:07 volumio avahi-daemon[475]: New relevant interface wlan0.IPv4 for mDNS. May 04 06:47:07 volumio avahi-daemon[475]: Registering new address record for 192.168.1.18 on wlan0.IPv4. May 04 06:47:07 volumio sudo[20129]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 04 06:47:07 volumio sudo[20129]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 06:47:07 volumio sudo[20129]: pam_unix(sudo:session): session closed for user root May 04 06:47:07 volumio sudo[20131]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 04 06:47:07 volumio sudo[20131]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 06:47:07 volumio sudo[20121]: pam_unix(sudo:session): session closed for user root May 04 06:47:07 volumio systemd[1]: Stopping Music Player Daemon... May 04 06:47:07 volumio volumio[20090]: info: Cannot mount NAS LS210D11 at system boot, trial number 1 ,retrying in 5 seconds May 04 06:47:07 volumio volumio[20090]: info: Starting Udev Watcher for removable devices May 04 06:47:07 volumio systemd[1]: mpd.service: Succeeded. May 04 06:47:07 volumio systemd[1]: Stopped Music Player Daemon. May 04 06:47:07 volumio systemd[1]: Starting Music Player Daemon... May 04 06:47:08 volumio volumio[20090]: info: Ignoring mount for partition: boot May 04 06:47:08 volumio volumio[20090]: info: Ignoring mount for partition: volumio May 04 06:47:08 volumio volumio[20090]: info: Ignoring mount for partition: volumio_data May 04 06:47:08 volumio volumio[20090]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 04 06:47:08 volumio volumio[20090]: info: Loading plugin "alarm-clock"... May 04 06:47:08 volumio volumio[20090]: info: Loading plugin "airplay_emulation"... May 04 06:47:08 volumio volumio[20090]: info: Starting Shairport Sync May 04 06:47:08 volumio volumio[20090]: info: Loading plugin "last_100"... May 04 06:47:08 volumio volumio[20090]: info: Loading plugin "webradio"... May 04 06:47:09 volumio volumio[20090]: info: Loading plugin "i2s_dacs"... May 04 06:47:09 volumio volumio[20090]: info: I2S DAC not set, start Auto-detection May 04 06:47:09 volumio volumio[20090]: info: Loading plugin "volumiodiscovery"... May 04 06:47:09 volumio volumio[20090]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 04 06:47:09 volumio volumio[20090]: *** WARNING *** Please fix your application to use the native API of Avahi! May 04 06:47:09 volumio volumio[20090]: *** WARNING *** For more information see May 04 06:47:09 volumio node[20090]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 04 06:47:09 volumio volumio[20090]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 04 06:47:09 volumio volumio[20090]: *** WARNING *** Please fix your application to use the native API of Avahi! May 04 06:47:09 volumio volumio[20090]: *** WARNING *** For more information see May 04 06:47:09 volumio node[20090]: *** WARNING *** Please fix your application to use the native API of Avahi! May 04 06:47:09 volumio node[20090]: *** WARNING *** For more information see May 04 06:47:09 volumio node[20090]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 04 06:47:09 volumio node[20090]: *** WARNING *** Please fix your application to use the native API of Avahi! May 04 06:47:09 volumio node[20090]: *** WARNING *** For more information see May 04 06:47:09 volumio volumio[20090]: info: Applying required configuration parameters for plugin volumiodiscovery May 04 06:47:09 volumio volumio[20090]: info: Discovery: Started advertising with name: Volumio May 04 06:47:09 volumio volumio[20090]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 04 06:47:09 volumio volumio[20090]: info: Loading plugin "squeezelite_mc"... May 04 06:47:10 volumio ntpd[646]: Listen normally on 28 wlan0 192.168.1.18:123 May 04 06:47:10 volumio ntpd[646]: new interface(s) found: waking up resolver May 04 06:47:10 volumio mpd[20158]: May 04 06:47 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 04 06:47:11 volumio volumio[20090]: info: Loading plugin "now_playing"... May 04 06:47:11 volumio mpd[20158]: May 04 06:47 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured May 04 06:47:11 volumio mpd[20158]: May 04 06:47 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured May 04 06:47:12 volumio systemd[1]: Started Music Player Daemon. May 04 06:47:12 volumio sudo[20131]: pam_unix(sudo:session): session closed for user root May 04 06:47:13 volumio volumio[20090]: info: Loading plugin "outputs"... May 04 06:47:13 volumio volumio[20090]: info: Loading plugin "albumart"... May 04 06:47:13 volumio volumio[20090]: info: Plugin example_plugin is not enabled May 04 06:47:13 volumio volumio[20090]: info: Loading plugin "inputs"... May 04 06:47:13 volumio volumio[20090]: info: Loading plugin "updater_comm"... May 04 06:47:13 volumio volumio[20090]: info: Plugin mpdemulation is not enabled May 04 06:47:13 volumio volumio[20090]: info: Loading plugin "rest_api"... May 04 06:47:13 volumio volumio[20090]: info: Loading plugin "websocket"... May 04 06:47:13 volumio volumio[20090]: info: Plugin fusiondsp is not enabled May 04 06:47:13 volumio volumio[20090]: info: Loading plugin "lms"... May 04 06:47:14 volumio volumio[20090]: info: Loading plugin "touch_display"... May 04 06:47:14 volumio volumio[20090]: Forking 3 albumart workers May 04 06:47:14 volumio volumio[20090]: info: Applying required configuration parameters for plugin touch_display May 04 06:47:15 volumio volumio-remote-updater[509]: [2025-05-04 06:47:15] [connect] Successful connection May 04 06:47:15 volumio volumio[20090]: info: Loading i18n strings for locale ja May 04 06:47:15 volumio volumio[20090]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. May 04 06:47:15 volumio volumio[20090]: Updating browse sources language May 04 06:47:15 volumio volumio[20090]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 04 06:47:15 volumio volumio[20090]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 04 06:47:15 volumio volumio[20090]: info: CoreCommandRouter::initPlayerControls May 04 06:47:15 volumio volumio[20090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 06:47:15 volumio volumio[20090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 06:47:15 volumio volumio[20090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 06:47:15 volumio volumio[20090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 06:47:15 volumio volumio[20090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 06:47:15 volumio volumio[20090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 06:47:15 volumio volumio[20090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 06:47:15 volumio volumio[20090]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 04 06:47:15 volumio volumio[20090]: Express server listening on port 3000 May 04 06:47:15 volumio volumio[20090]: [Metrics] WebUI: 14s 999.36ms May 04 06:47:15 volumio volumio[20090]: info: CoreStateMachine::resetVolumioState May 04 06:47:15 volumio volumio[20090]: info: CoreStateMachine::getcurrentVolume May 04 06:47:15 volumio volumio[20090]: info: CoreCommandRouter::volumioRetrievevolume May 04 06:47:15 volumio volumio[20090]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 04 06:47:15 volumio volumio[20090]: info: Cannot mount NAS LS210D11 at system boot, trial number 2 ,retrying in 5 seconds May 04 06:47:15 volumio volumio[20090]: info: MPD Permissions set May 04 06:47:16 volumio sudo[20207]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 04 06:47:16 volumio sudo[20207]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 06:47:16 volumio volumio[20090]: Starting albumart workers May 04 06:47:16 volumio volumio[20090]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Total Clients: 1 May 04 06:47:16 volumio volumio[20090]: Starting albumart workers May 04 06:47:16 volumio volumio[20090]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Total Clients: 2 May 04 06:47:16 volumio volumio[20090]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Total Clients: 3 May 04 06:47:16 volumio volumio-remote-updater[509]: [2025-05-04 06:47:16] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1746341235 101 May 04 06:47:16 volumio volumio[20090]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 4 May 04 06:47:16 volumio volumio[20090]: info: MPD running with PID20158 May 04 06:47:16 volumio volumio[20090]: ,establishing connection May 04 06:47:16 volumio volumio[20090]: Unable to parse: May 04 06:47:16 volumio volumio[20090]: Simple mixer control 'PCM',0 May 04 06:47:16 volumio volumio[20090]: Capabilities: pswitch pswitch-joined May 04 06:47:16 volumio volumio[20090]: Playback channels: Mono May 04 06:47:16 volumio volumio[20090]: Mono: Playback [on] May 04 06:47:16 volumio volumio[20090]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output May 04 06:47:16 volumio volumio[20090]: Starting albumart workers May 04 06:47:16 volumio volumio[20090]: error: MPD error: The expression evaluated to a falsy value: May 04 06:47:16 volumio volumio[20090]: assert.ok(self.idling) May 04 06:47:16 volumio volumio[20090]: error: MPD error: The expression evaluated to a falsy value: May 04 06:47:16 volumio volumio[20090]: assert.ok(self.idling) May 04 06:47:16 volumio volumio[20090]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 5 May 04 06:47:16 volumio volumio[20090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 06:47:16 volumio volumio[20090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 06:47:16 volumio volumio[20090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 06:47:16 volumio volumio[20090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 06:47:16 volumio volumio[20090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 06:47:16 volumio volumio[20090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 06:47:16 volumio volumio[20090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 06:47:16 volumio volumio[20090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 06:47:17 volumio volumio[20090]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 04 06:47:17 volumio volumio[20090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 06:47:17 volumio volumio[20090]: info: Reloading queue from file May 04 06:47:17 volumio volumio[20090]: Unable to parse: May 04 06:47:17 volumio volumio[20090]: Simple mixer control 'PCM',0 May 04 06:47:17 volumio volumio[20090]: Capabilities: pswitch pswitch-joined May 04 06:47:17 volumio volumio[20090]: Playback channels: Mono May 04 06:47:17 volumio volumio[20090]: Mono: Playback [on] May 04 06:47:17 volumio volumio[20090]: info: VolumeController:: Volume=undefined Mute =false May 04 06:47:17 volumio volumio[20090]: info: CoreStateMachine::pushState May 04 06:47:17 volumio volumio[20090]: info: CorePlayQueue::getTrack 0 May 04 06:47:17 volumio volumio[20090]: info: CoreCommandRouter::volumioPushState May 04 06:47:17 volumio volumio[20090]: info: CoreStateMachine::updateTrackBlock May 04 06:47:17 volumio volumio[20090]: info: CorePlayQueue::getTrackBlock May 04 06:47:17 volumio volumio[20090]: info: CoreCommandRouter::volumioRetrievevolume May 04 06:47:17 volumio volumio[20090]: info: CoreStateMachine::setRepeat null single undefined May 04 06:47:17 volumio volumio[20090]: info: CoreStateMachine::pushState May 04 06:47:17 volumio volumio[20090]: info: CorePlayQueue::getTrack 0 May 04 06:47:17 volumio volumio[20090]: info: CoreCommandRouter::volumioPushState May 04 06:47:17 volumio volumio[20090]: info: CoreStateMachine::setRandom null May 04 06:47:17 volumio volumio[20090]: info: CoreStateMachine::pushState May 04 06:47:17 volumio volumio[20090]: info: CorePlayQueue::getTrack 0 May 04 06:47:17 volumio volumio[20090]: info: CoreCommandRouter::volumioPushState May 04 06:47:17 volumio volumio[20090]: info: Setting Device type: Raspberry PI May 04 06:47:17 volumio volumio[20090]: error: updateQueue error: null May 04 06:47:17 volumio volumio[20090]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory May 04 06:47:17 volumio volumio[20090]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Total Clients: 6 May 04 06:47:17 volumio volumio[20090]: Unable to parse: May 04 06:47:17 volumio volumio[20090]: Simple mixer control 'PCM',0 May 04 06:47:17 volumio volumio[20090]: Capabilities: pswitch pswitch-joined May 04 06:47:17 volumio volumio[20090]: Playback channels: Mono May 04 06:47:17 volumio volumio[20090]: Mono: Playback [on] May 04 06:47:17 volumio volumio[20090]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output May 04 06:47:17 volumio volumio[20090]: info: Completed loading Core Plugins May 04 06:47:17 volumio volumio[20090]: info: Preparing to generate the ALSA configuration file May 04 06:47:17 volumio sudo[20207]: pam_unix(sudo:session): session closed for user root May 04 06:47:17 volumio sudo[20209]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 04 06:47:17 volumio sudo[20209]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 06:47:17 volumio sudo[20209]: pam_unix(sudo:session): session closed for user root May 04 06:47:17 volumio volumio[20090]: info: Discovery: adding 20a592b7-9640-4569-a7b4-6cc8ab55afc9 May 04 06:47:17 volumio volumio[20090]: info: Discovery: Found device Volumio May 04 06:47:17 volumio volumio[20090]: info: CoreCommandRouter::volumioGetState May 04 06:47:17 volumio volumio[20090]: info: CorePlayQueue::getTrack 0 May 04 06:47:17 volumio sudo[20225]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 04 06:47:17 volumio sudo[20225]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 06:47:17 volumio volumio[20090]: Unable to parse: May 04 06:47:17 volumio volumio[20090]: Simple mixer control 'PCM',0 May 04 06:47:17 volumio volumio[20090]: Capabilities: pswitch pswitch-joined May 04 06:47:17 volumio volumio[20090]: Playback channels: Mono May 04 06:47:17 volumio volumio[20090]: Mono: Playback [on] May 04 06:47:17 volumio volumio[20090]: info: VolumeController:: Volume=undefined Mute =false May 04 06:47:17 volumio volumio[20090]: info: CoreStateMachine::pushState May 04 06:47:17 volumio volumio[20090]: info: CorePlayQueue::getTrack 0 May 04 06:47:17 volumio volumio[20090]: info: CoreCommandRouter::volumioPushState May 04 06:47:17 volumio sudo[20225]: pam_unix(sudo:session): session closed for user root May 04 06:47:17 volumio volumio[20090]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 04 06:47:17 volumio volumio[20090]: info: CoreCommandRouter::volumioGetVisibleSources May 04 06:47:17 volumio volumio[20090]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 04 06:47:17 volumio volumio[20090]: info: CoreCommandRouter::volumioGetState May 04 06:47:17 volumio volumio[20090]: info: CorePlayQueue::getTrack 0 May 04 06:47:18 volumio volumio[20090]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 04 06:47:18 volumio volumio[20090]: info: Received Get System Info May 04 06:47:18 volumio volumio[20090]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 04 06:47:18 volumio volumio[20090]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 04 06:47:18 volumio volumio[20090]: info: Discovery: Getting this device information May 04 06:47:18 volumio volumio[20090]: info: CoreCommandRouter::volumioGetState May 04 06:47:18 volumio volumio[20090]: info: CorePlayQueue::getTrack 0 May 04 06:47:18 volumio volumio[20090]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 04 06:47:18 volumio volumio[20090]: info: CoreCommandRouter::volumioGetState May 04 06:47:18 volumio volumio[20090]: info: CorePlayQueue::getTrack 0 May 04 06:47:18 volumio volumio[20090]: info: Listing playlists May 04 06:47:18 volumio volumio[20090]: info: Upmpdcli Daemon Started May 04 06:47:18 volumio volumio[20090]: info: Asound.conf file unchanged, so no further update is needed May 04 06:47:18 volumio volumio[20090]: info: Output device has changed, restarting MPD May 04 06:47:18 volumio volumio[20090]: info: Output device has changed, restarting Shairport Sync May 04 06:47:18 volumio sudo[20245]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 04 06:47:18 volumio volumio[20090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 06:47:18 volumio volumio[20090]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 04 06:47:18 volumio sudo[20245]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 06:47:18 volumio sudo[20245]: pam_unix(sudo:session): session closed for user root May 04 06:47:18 volumio sudo[20254]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 04 06:47:18 volumio sudo[20254]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 06:47:18 volumio volumio[20090]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 04 06:47:18 volumio systemd[1]: Stopping Music Player Daemon... May 04 06:47:18 volumio volumio[20090]: info: ___________ START PLUGINS ___________ May 04 06:47:18 volumio volumio[20090]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 04 06:47:18 volumio volumio[20090]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 04 06:47:18 volumio volumio[20090]: info: [1746341238668] CoreMusicLibrary::Adding element Media Servers May 04 06:47:18 volumio volumio[20090]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 04 06:47:18 volumio volumio[20090]: Cannot find translation for sourceMedia Servers May 04 06:47:18 volumio systemd[1]: mpd.service: Succeeded. May 04 06:47:18 volumio systemd[1]: Stopped Music Player Daemon. May 04 06:47:18 volumio systemd[1]: Starting Music Player Daemon... May 04 06:47:20 volumio volumio[20090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 06:47:20 volumio volumio[20090]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 04 06:47:20 volumio volumio[20090]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 04 06:47:20 volumio volumio[20090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 06:47:20 volumio volumio[20090]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 04 06:47:20 volumio volumio[20090]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 04 06:47:20 volumio volumio[20090]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 04 06:47:20 volumio volumio[20090]: info: [1746341240376] CoreMusicLibrary::Adding element Last_100 May 04 06:47:20 volumio volumio[20090]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 04 06:47:20 volumio volumio[20090]: Cannot find translation for sourceMedia Servers May 04 06:47:20 volumio volumio[20090]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 04 06:47:20 volumio volumio[20090]: info: [1746341240388] CoreMusicLibrary::Adding element Webradio May 04 06:47:20 volumio volumio[20090]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 04 06:47:20 volumio volumio[20090]: Cannot find translation for sourceMedia Servers May 04 06:47:20 volumio volumio[20090]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 04 06:47:20 volumio volumio[20090]: info: [squeezelite_mc] Starting proxy server... May 04 06:47:20 volumio volumio[20090]: info: [now-playing-config] Config is up to date. May 04 06:47:20 volumio volumio[20090]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 04 06:47:20 volumio volumio[20090]: info: Discovery: Getting this device information May 04 06:47:20 volumio volumio[20090]: info: CoreCommandRouter::volumioGetState May 04 06:47:20 volumio volumio[20090]: info: CorePlayQueue::getTrack 0 May 04 06:47:20 volumio volumio[20090]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 04 06:47:20 volumio volumio[20090]: info: Loading i18n strings for locale ja May 04 06:47:20 volumio volumio[20090]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. May 04 06:47:20 volumio volumio[20090]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 04 06:47:20 volumio volumio[20090]: info: Volumio Calling Home May 04 06:47:20 volumio sudo[20272]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart logitechmediaserver May 04 06:47:20 volumio sudo[20272]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 06:47:20 volumio systemd[1]: Starting Logitech Media Server Daemon... May 04 06:47:20 volumio volumio[20090]: info: [squeezelite_mc] Proxy server started on port 37209 May 04 06:47:20 volumio systemd[1]: Started Logitech Media Server Daemon. May 04 06:47:20 volumio volumio[20090]: info: [now-playing-app] App is listening on port 4004. May 04 06:47:20 volumio sudo[20272]: pam_unix(sudo:session): session closed for user root May 04 06:47:20 volumio systemd[20278]: logitechmediaserver.service: Failed to execute command: No such file or directory May 04 06:47:20 volumio systemd[20278]: logitechmediaserver.service: Failed at step EXEC spawning /usr/sbin/squeezeboxserver: No such file or directory May 04 06:47:20 volumio systemd[1]: logitechmediaserver.service: Main process exited, code=exited, status=203/EXEC May 04 06:47:20 volumio systemd[1]: logitechmediaserver.service: Failed with result 'exit-code'. May 04 06:47:21 volumio sudo[20281]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload May 04 06:47:21 volumio sudo[20281]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 06:47:21 volumio systemd[1]: Reloading. May 04 06:47:21 volumio volumio[20090]: info: CoreCommandRouter::volumioRetrievevolume May 04 06:47:21 volumio volumio[20090]: info: Cannot mount NAS LS210D11 at system boot, trial number 3 ,retrying in 5 seconds May 04 06:47:21 volumio volumio[20090]: Unable to parse: May 04 06:47:21 volumio volumio[20090]: Simple mixer control 'PCM',0 May 04 06:47:21 volumio volumio[20090]: Capabilities: pswitch pswitch-joined May 04 06:47:21 volumio volumio[20090]: Playback channels: Mono May 04 06:47:21 volumio volumio[20090]: Mono: Playback [on] May 04 06:47:21 volumio volumio[20090]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output May 04 06:47:21 volumio volumio[20090]: info: logitechmediaserver started May 04 06:47:21 volumio volumio[20090]: info: MPD Permissions set May 04 06:47:21 volumio mpd[20264]: May 04 06:47 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 04 06:47:21 volumio volumio[20090]: info: Volumio called home May 04 06:47:21 volumio volumio[20090]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 7 May 04 06:47:21 volumio volumio[20090]: Unable to parse: May 04 06:47:21 volumio volumio[20090]: Simple mixer control 'PCM',0 May 04 06:47:21 volumio volumio[20090]: Capabilities: pswitch pswitch-joined May 04 06:47:21 volumio volumio[20090]: Playback channels: Mono May 04 06:47:21 volumio volumio[20090]: Mono: Playback [on] May 04 06:47:21 volumio volumio[20090]: info: VolumeController:: Volume=undefined Mute =false May 04 06:47:21 volumio volumio[20090]: info: CoreStateMachine::pushState May 04 06:47:21 volumio volumio[20090]: info: CorePlayQueue::getTrack 0 May 04 06:47:21 volumio volumio[20090]: info: CoreCommandRouter::volumioPushState May 04 06:47:22 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. May 04 06:47:22 volumio mpd[20264]: May 04 06:47 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured May 04 06:47:22 volumio mpd[20264]: May 04 06:47 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured May 04 06:47:22 volumio volumio[20090]: info: [squeezelite_mc] Server discovery started May 04 06:47:22 volumio volumio[20090]: info: [squeezelite_mc] Player finder started May 04 06:47:22 volumio volumio[20090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 06:47:22 volumio volumio[20090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 06:47:22 volumio volumio[20090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 06:47:22 volumio volumio[20090]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 04 06:47:22 volumio volumio[20090]: info: [squeezelite_mc] Executing aplay -D hw:5 --nonblock -f MPEG /dev/zero 2>&1 || true May 04 06:47:22 volumio volumio[20090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 06:47:22 volumio volumio[20090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 06:47:22 volumio volumio[20090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 06:47:22 volumio volumio[20090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 06:47:22 volumio volumio[20090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 06:47:22 volumio volumio[20090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 06:47:22 volumio volumio[20090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 06:47:22 volumio volumio[20090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 06:47:22 volumio volumio[20090]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 04 06:47:22 volumio volumio[20090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 06:47:22 volumio volumio[20090]: error: MPD error: The expression evaluated to a falsy value: May 04 06:47:22 volumio volumio[20090]: assert.ok(self.idling) May 04 06:47:22 volumio volumio[20090]: info: touch_display: No Raspberry Pi Foundation touch screen detected. May 04 06:47:22 volumio volumio[20090]: error: updateQueue error: null May 04 06:47:22 volumio volumio[20090]: info: [squeezelite_mc] Card 5 supports the following ALSA formats: ["S16_LE","S24_3LE"] May 04 06:47:22 volumio volumio[20090]: info: [squeezelite_mc] Starting Squeezelite service with params: {"type":"basic","playerName":"volumio","dsdFormat":null,"card":"5","mixerType":"Hardware","mixer":"PCM"} May 04 06:47:22 volumio volumio[20090]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf May 04 06:47:23 volumio systemd[1]: /data/plugins/music_service/lms/unit/logitechmediaserver.service:7: PIDFile= references path below legacy directory /var/run/, updating /var/run/logitechmediaserver.pid → /run/logitechmediaserver.pid; please update the unit file accordingly. May 04 06:47:23 volumio volumio[20090]: info: Starting Shairport Sync May 04 06:47:23 volumio volumio[20090]: info: Starting Shairport Sync May 04 06:47:23 volumio volumio[20090]: info: Starting Shairport Sync May 04 06:47:23 volumio sudo[20312]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 04 06:47:23 volumio sudo[20312]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 06:47:23 volumio sudo[20310]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf May 04 06:47:23 volumio sudo[20310]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 06:47:23 volumio sudo[20310]: pam_unix(sudo:session): session closed for user root May 04 06:47:23 volumio sudo[20314]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 04 06:47:23 volumio sudo[20314]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 06:47:23 volumio sudo[20318]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 04 06:47:23 volumio sudo[20318]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 06:47:23 volumio systemd[1]: Started Music Player Daemon. May 04 06:47:23 volumio sudo[20254]: pam_unix(sudo:session): session closed for user root May 04 06:47:23 volumio sudo[20281]: pam_unix(sudo:session): session closed for user root May 04 06:47:23 volumio volumio[20090]: info: [squeezelite_mc] Executing alsactl -L -R nrestore May 04 06:47:23 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 04 06:47:23 volumio systemd[1]: shairport-sync.service: Succeeded. May 04 06:47:23 volumio volumio[20090]: info: touch_display: systemctl daemon-reload succeeded. May 04 06:47:23 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 04 06:47:23 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 04 06:47:23 volumio sudo[20323]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/alsactl -L -R nrestore May 04 06:47:23 volumio sudo[20323]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 06:47:23 volumio sudo[20318]: pam_unix(sudo:session): session closed for user root May 04 06:47:23 volumio sudo[20314]: pam_unix(sudo:session): session closed for user root May 04 06:47:23 volumio sudo[20312]: pam_unix(sudo:session): session closed for user root May 04 06:47:23 volumio sudo[20323]: pam_unix(sudo:session): session closed for user root May 04 06:47:23 volumio volumio[20090]: info: Shairport-Sync Started May 04 06:47:25 volumio volumio[20090]: Error adding Membership: Error: addMembership EINVAL May 04 06:47:25 volumio volumio[20090]: info: Shairport-Sync Started May 04 06:47:25 volumio sudo[20327]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service May 04 06:47:25 volumio volumio[20090]: info: CoreCommandRouter::volumioGetState May 04 06:47:25 volumio volumio[20090]: info: CorePlayQueue::getTrack 0 May 04 06:47:25 volumio sudo[20327]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 06:47:25 volumio volumio[20090]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/systemd/squeezelite.service.template.out /etc/systemd/system/squeezelite.service May 04 06:47:25 volumio sudo[20327]: pam_unix(sudo:session): session closed for user root May 04 06:47:25 volumio volumio[20090]: error: updateQueue error: null May 04 06:47:25 volumio volumio[20090]: info: touch_display: systemctl start volumio-kiosk.service succeeded. May 04 06:47:25 volumio volumio[20090]: info: touch_display: Volumio Kiosk started May 04 06:47:25 volumio volumio[20090]: info: CoreCommandRouter::volumioGetState May 04 06:47:25 volumio volumio[20090]: info: CorePlayQueue::getTrack 0 May 04 06:47:25 volumio volumio[20090]: info: CoreCommandRouter::volumioGetState May 04 06:47:25 volumio volumio[20090]: info: CorePlayQueue::getTrack 0 May 04 06:47:25 volumio sudo[20335]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/systemd/squeezelite.service.template.out /etc/systemd/system/squeezelite.service May 04 06:47:25 volumio sudo[20335]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 06:47:25 volumio volumio[20090]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 May 04 06:47:25 volumio sudo[20335]: pam_unix(sudo:session): session closed for user root May 04 06:47:25 volumio volumio[20090]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl daemon-reload || true May 04 06:47:25 volumio sudo[20340]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload May 04 06:47:25 volumio sudo[20340]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 06:47:25 volumio systemd[1]: Reloading. May 04 06:47:25 volumio volumio[20090]: info: touch_display: Setting screensaver timeout to 120 seconds. May 04 06:47:25 volumio volumio[20090]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 May 04 06:47:26 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. May 04 06:47:26 volumio volumio[20090]: info: Cannot mount NAS LS210D11 at system boot, trial number 4 ,retrying in 5 seconds May 04 06:47:26 volumio systemd[1]: /data/plugins/music_service/lms/unit/logitechmediaserver.service:7: PIDFile= references path below legacy directory /var/run/, updating /var/run/logitechmediaserver.pid → /run/logitechmediaserver.pid; please update the unit file accordingly. May 04 06:47:27 volumio sudo[20340]: pam_unix(sudo:session): session closed for user root May 04 06:47:27 volumio volumio[20090]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true May 04 06:47:27 volumio sudo[20360]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite May 04 06:47:27 volumio sudo[20360]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 06:47:27 volumio sudo[20360]: pam_unix(sudo:session): session closed for user root May 04 06:47:27 volumio volumio[20090]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl stop squeezelite || true May 04 06:47:27 volumio sudo[20363]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop squeezelite May 04 06:47:27 volumio sudo[20363]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 06:47:27 volumio volumio[20090]: error: updateQueue error: null May 04 06:47:27 volumio systemd[1]: Stopping Squeezelite lightweight headless Squeezebox emulator... May 04 06:47:28 volumio systemd[1]: squeezelite.service: Succeeded. May 04 06:47:28 volumio systemd[1]: Stopped Squeezelite lightweight headless Squeezebox emulator. May 04 06:47:28 volumio sudo[20363]: pam_unix(sudo:session): session closed for user root May 04 06:47:28 volumio volumio[20090]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true May 04 06:47:30 volumio sudo[20366]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite May 04 06:47:30 volumio sudo[20366]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 06:47:30 volumio sudo[20366]: pam_unix(sudo:session): session closed for user root May 04 06:47:30 volumio volumio[20090]: info: [squeezelite_mc] Executing rm /tmp/squeezelite.log May 04 06:47:30 volumio sudo[20370]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm /tmp/squeezelite.log May 04 06:47:30 volumio sudo[20370]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 06:47:30 volumio sudo[20370]: pam_unix(sudo:session): session closed for user root May 04 06:47:30 volumio volumio[20090]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl start squeezelite || true May 04 06:47:30 volumio sudo[20373]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start squeezelite May 04 06:47:30 volumio sudo[20373]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 06:47:30 volumio systemd[1]: Starting Squeezelite lightweight headless Squeezebox emulator... May 04 06:47:30 volumio systemd[1]: Started Squeezelite lightweight headless Squeezebox emulator. May 04 06:47:30 volumio sudo[20373]: pam_unix(sudo:session): session closed for user root May 04 06:47:30 volumio volumio[20090]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true May 04 06:47:31 volumio sudo[20380]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite May 04 06:47:31 volumio sudo[20380]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 06:47:31 volumio sudo[20380]: pam_unix(sudo:session): session closed for user root May 04 06:47:31 volumio volumio[20090]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true May 04 06:47:31 volumio volumio[20090]: info: Cannot mount NAS at system boot, trial number 4 ,stopping May 04 06:47:32 volumio wpa_supplicant[741]: wlan0: CTRL-EVENT-DISCONNECTED bssid=f0:f8:4a:b3:c6:25 reason=0 locally_generated=1 May 04 06:47:32 volumio dhcpcd[755]: wlan0: carrier lost May 04 06:47:32 volumio wpa_supplicant[741]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD May 04 06:47:32 volumio wpa_supplicant[741]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=JP May 04 06:47:32 volumio sudo[20383]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite May 04 06:47:32 volumio sudo[20383]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 06:47:32 volumio sudo[20383]: pam_unix(sudo:session): session closed for user root May 04 06:47:32 volumio avahi-daemon[475]: Withdrawing address record for 192.168.1.18 on wlan0. May 04 06:47:32 volumio avahi-daemon[475]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.18. May 04 06:47:32 volumio dhcpcd[755]: wlan0: deleting route to 192.168.1.0/24 May 04 06:47:32 volumio dhcpcd[755]: wlan0: deleting default route via 192.168.1.1 May 04 06:47:32 volumio avahi-daemon[475]: Interface wlan0.IPv4 no longer relevant for mDNS. May 04 06:47:32 volumio volumio[20090]: info: Discovery: A device disappeared from network May 04 06:47:32 volumio volumio[20090]: info: Discovery: Device volumio disappeared from network May 04 06:47:33 volumio volumio[20090]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true May 04 06:47:33 volumio sudo[20414]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite May 04 06:47:33 volumio sudo[20414]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 06:47:33 volumio wpa_supplicant[741]: wlan0: Trying to associate with SSID 'Buffalo-A-5A90' May 04 06:47:33 volumio sudo[20414]: pam_unix(sudo:session): session closed for user root May 04 06:47:33 volumio volumio[20090]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true May 04 06:47:35 volumio ntpd[646]: Deleting interface #28 wlan0, 192.168.1.18#123, interface stats: received=96, sent=96, dropped=0, active_time=26 secs May 04 06:47:35 volumio ntpd[646]: 139.162.81.45 local addr 192.168.1.18 -> May 04 06:47:35 volumio ntpd[646]: 194.0.5.123 local addr 192.168.1.18 -> May 04 06:47:35 volumio ntpd[646]: 160.16.113.133 local addr 192.168.1.18 -> May 04 06:47:35 volumio ntpd[646]: 172.237.12.44 local addr 192.168.1.18 -> May 04 06:47:35 volumio ntpd[646]: 23.81.40.154 local addr 192.168.1.18 -> May 04 06:47:35 volumio ntpd[646]: 45.76.211.39 local addr 192.168.1.18 -> May 04 06:47:35 volumio ntpd[646]: 133.130.121.141 local addr 192.168.1.18 -> May 04 06:47:35 volumio ntpd[646]: 103.131.151.20 local addr 192.168.1.18 -> May 04 06:47:35 volumio ntpd[646]: 202.181.103.212 local addr 192.168.1.18 -> May 04 06:47:35 volumio ntpd[646]: 47.79.37.210 local addr 192.168.1.18 -> May 04 06:47:35 volumio ntpd[646]: 18.180.64.47 local addr 192.168.1.18 -> May 04 06:47:35 volumio ntpd[646]: 167.179.119.205 local addr 192.168.1.18 -> May 04 06:47:35 volumio ntpd[646]: 162.159.200.123 local addr 192.168.1.18 -> May 04 06:47:35 volumio ntpd[646]: 162.159.200.1 local addr 192.168.1.18 -> May 04 06:47:35 volumio ntpd[646]: 45.77.20.103 local addr 192.168.1.18 -> May 04 06:47:35 volumio ntpd[646]: 103.131.151.30 local addr 192.168.1.18 -> May 04 06:47:35 volumio sudo[20417]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite May 04 06:47:35 volumio sudo[20417]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 06:47:35 volumio sudo[20417]: pam_unix(sudo:session): session closed for user root May 04 06:47:35 volumio volumio[20090]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true May 04 06:47:35 volumio sudo[20420]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite May 04 06:47:35 volumio sudo[20420]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 06:47:35 volumio sudo[20420]: pam_unix(sudo:session): session closed for user root May 04 06:47:35 volumio volumio[20090]: info: Completed starting Core Plugins May 04 06:47:35 volumio volumio[20090]: info: ------------------------------------------- May 04 06:47:35 volumio volumio[20090]: info: ----- MyVolumio plugins startup ---- May 04 06:47:35 volumio volumio[20090]: info: ------------------------------------------- May 04 06:47:35 volumio volumio[20090]: info: [MyVolumio PluginManager] Fetching plans data.... May 04 06:47:37 volumio wpa_supplicant[741]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 May 04 06:47:40 volumio wpa_supplicant[741]: wlan0: Trying to associate with SSID 'Buffalo-A-5A90' May 04 06:47:43 volumio wpa_supplicant[741]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 May 04 06:47:45 volumio wpa_supplicant[741]: wlan0: Trying to associate with SSID 'Buffalo-A-5A90' May 04 06:47:48 volumio wpa_supplicant[741]: wlan0: Associated with f0:f8:4a:b3:c6:25 May 04 06:47:48 volumio wpa_supplicant[741]: wlan0: CTRL-EVENT-CONNECTED - Connection to f0:f8:4a:b3:c6:25 completed [id=0 id_str=] May 04 06:47:48 volumio dhcpcd[755]: wlan0: carrier acquired May 04 06:47:48 volumio wpa_supplicant[741]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 May 04 06:47:48 volumio wpa_supplicant[741]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=JP May 04 06:47:48 volumio dhcpcd[755]: wlan0: IAID eb:0d:71:2a May 04 06:47:48 volumio dhcpcd[755]: wlan0: soliciting an IPv6 router May 04 06:47:49 volumio ntpd[646]: Soliciting pool server 2001:418:3ff::53 May 04 06:47:49 volumio dhcpcd[755]: wlan0: rebinding lease of 192.168.1.18 May 04 06:47:49 volumio dhcpcd[755]: wlan0: probing address 192.168.1.18/24 May 04 06:47:50 volumio volumio[20090]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 04 06:47:50 volumio volumio[20090]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 04 06:47:52 volumio volumio[20090]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 04 06:47:52 volumio volumio[20090]: Error: send ENETUNREACH 255.255.255.255:3483 May 04 06:47:52 volumio volumio[20090]: at doSend (dgram.js:692:16) May 04 06:47:52 volumio volumio[20090]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) May 04 06:47:52 volumio volumio[20090]: at afterDns (dgram.js:638:5) May 04 06:47:52 volumio volumio[20090]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { May 04 06:47:52 volumio volumio[20090]: errno: -101, May 04 06:47:52 volumio volumio[20090]: code: 'ENETUNREACH', May 04 06:47:52 volumio volumio[20090]: syscall: 'send', May 04 06:47:52 volumio volumio[20090]: address: '255.255.255.255', May 04 06:47:52 volumio volumio[20090]: port: 3483 May 04 06:47:52 volumio volumio[20090]: } May 04 06:47:52 volumio volumio[20090]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 04 06:47:54 volumio dhcpcd[755]: wlan0: leased 192.168.1.18 for 86400 seconds May 04 06:47:55 volumio avahi-daemon[475]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.18. May 04 06:47:55 volumio dhcpcd[755]: wlan0: adding route to 192.168.1.0/24 May 04 06:47:55 volumio dhcpcd[755]: wlan0: adding default route via 192.168.1.1 May 04 06:47:55 volumio avahi-daemon[475]: New relevant interface wlan0.IPv4 for mDNS. May 04 06:47:55 volumio avahi-daemon[475]: Registering new address record for 192.168.1.18 on wlan0.IPv4. May 04 06:47:55 volumio sudo[20435]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-04 06:46 May 04 06:47:55 volumio sudo[20435]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="91e88ea6c5c4ec4f00aff63f7c3a2944b14ec939" VOLUMIO_FE_VERSION="c433755e569617abac52264d17ad61be303ef463" VOLUMIO_FE3_VERSION="2419ddd8e895c5bdc456962163c9dc7c0f187aa7" VOLUMIO_BE_VERSION="8e6823482d6af342172ec9fdc1c15b0dd02653d2" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sun 04 Dec 2022 08:41:29 PM CET" VOLUMIO_VERSION="3.396" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d460c75d04818d0f55c67627fa362668"