-- Logs begin at Thu 2019-02-14 10:11:59 GMT, end at Thu 2025-05-01 16:42:07 BST. -- May 01 16:41:00 jimvol wpa_supplicant[862]: RRM: Ignoring radio measurement request: Not RRM network May 01 16:41:36 jimvol wpa_supplicant[862]: RRM: Ignoring radio measurement request: Not RRM network May 01 16:41:41 jimvol wpa_supplicant[862]: RRM: Ignoring radio measurement request: Not RRM network May 01 16:41:55 jimvol wpa_supplicant[862]: wlan0: CTRL-EVENT-DISCONNECTED bssid=dc:39:6f:ee:1b:fa reason=3 May 01 16:41:55 jimvol dhcpcd[873]: wlan0: carrier lost May 01 16:41:55 jimvol wpa_supplicant[862]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD May 01 16:41:55 jimvol wpa_supplicant[862]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=GB May 01 16:41:55 jimvol avahi-daemon[556]: Withdrawing address record for 192.168.178.25 on wlan0. May 01 16:41:55 jimvol avahi-daemon[556]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.178.25. May 01 16:41:55 jimvol avahi-daemon[556]: Interface wlan0.IPv4 no longer relevant for mDNS. May 01 16:41:55 jimvol dhcpcd[873]: wlan0: deleting route to 192.168.178.0/24 May 01 16:41:55 jimvol dhcpcd[873]: wlan0: deleting default route via 192.168.178.1 May 01 16:41:55 jimvol volumio[1488]: info: Discovery: A device disappeared from network May 01 16:41:55 jimvol volumio[1488]: info: Discovery: Device jimvol disappeared from network May 01 16:41:55 jimvol ntpd[1581]: ntpd exiting on signal 15 (Terminated) May 01 16:41:55 jimvol ntpd[1581]: 192.168.178.1 local addr 192.168.178.25 -> May 01 16:41:55 jimvol systemd[1]: Stopping Network Time Service... May 01 16:41:55 jimvol ntpd[1581]: 193.57.144.50 local addr 192.168.178.25 -> May 01 16:41:55 jimvol ntpd[1581]: 131.111.8.61 local addr 192.168.178.25 -> May 01 16:41:55 jimvol systemd[1]: ntp.service: Succeeded. May 01 16:41:55 jimvol ntpd[1581]: 131.111.8.60 local addr 192.168.178.25 -> May 01 16:41:55 jimvol ntpd[1581]: 85.199.214.100 local addr 192.168.178.25 -> May 01 16:41:55 jimvol ntpd[1581]: 194.146.161.122 local addr 192.168.178.25 -> May 01 16:41:55 jimvol systemd[1]: Stopped Network Time Service. May 01 16:41:55 jimvol systemd[1]: Starting Network Time Service... May 01 16:41:55 jimvol ntpd[1066]: ntpd 4.2.8p12@1.3728-o (1): Starting May 01 16:41:55 jimvol systemd[1]: Started Network Time Service. May 01 16:41:55 jimvol ntpd[1066]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -c /run/ntp.conf.dhcp -u 103:103 May 01 16:41:55 jimvol ntpd[1073]: proto: precision = 0.625 usec (-21) May 01 16:41:55 jimvol ntpd[1073]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature May 01 16:41:55 jimvol ntpd[1073]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 May 01 16:41:55 jimvol ntpd[1073]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 125 days ago May 01 16:41:55 jimvol ntpd[1073]: Listen and drop on 0 v6wildcard [::]:123 May 01 16:41:55 jimvol ntpd[1073]: Listen and drop on 1 v4wildcard 0.0.0.0:123 May 01 16:41:55 jimvol ntpd[1073]: Listen normally on 2 lo 127.0.0.1:123 May 01 16:41:55 jimvol ntpd[1073]: Listening on routing socket on fd #19 for interface updates May 01 16:41:55 jimvol ntpd[1073]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized May 01 16:41:55 jimvol ntpd[1073]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized May 01 16:41:56 jimvol ntpd[1073]: error resolving pool 0.debian.pool.ntp.org: System error (-11) May 01 16:41:57 jimvol volumio[1488]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 01 16:41:57 jimvol volumio[1488]: Error: send ENETUNREACH 255.255.255.255:3483 May 01 16:41:57 jimvol volumio[1488]: at doSend (dgram.js:692:16) May 01 16:41:57 jimvol volumio[1488]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) May 01 16:41:57 jimvol volumio[1488]: at afterDns (dgram.js:638:5) May 01 16:41:57 jimvol volumio[1488]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { May 01 16:41:57 jimvol volumio[1488]: errno: -101, May 01 16:41:57 jimvol volumio[1488]: code: 'ENETUNREACH', May 01 16:41:57 jimvol volumio[1488]: syscall: 'send', May 01 16:41:57 jimvol volumio[1488]: address: '255.255.255.255', May 01 16:41:57 jimvol volumio[1488]: port: 3483 May 01 16:41:57 jimvol volumio[1488]: } May 01 16:41:57 jimvol volumio[1488]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 01 16:41:57 jimvol ntpd[1073]: error resolving pool 1.debian.pool.ntp.org: System error (-11) May 01 16:41:57 jimvol sudo[1085]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-01 16:40 May 01 16:41:57 jimvol sudo[1085]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 16:41:57 jimvol sudo[1085]: pam_unix(sudo:session): session closed for user root May 01 16:41:58 jimvol volumio-remote-updater[565]: [2025-05-01 16:41:58] [error] handle_read_frame error: websocketpp.transport:7 (End of File) May 01 16:41:58 jimvol systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE May 01 16:41:58 jimvol volumio-remote-updater[565]: [2025-05-01 16:41:58] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] May 01 16:41:58 jimvol systemd[1]: volumio.service: Failed with result 'exit-code'. May 01 16:41:58 jimvol systemd[1]: Started dynamicswap service. May 01 16:41:58 jimvol systemd[1]: dynamicswap.service: Succeeded. May 01 16:41:58 jimvol systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. May 01 16:41:58 jimvol systemd[1]: volumio.service: Scheduled restart job, restart counter is at 2. May 01 16:41:58 jimvol systemd[1]: Stopped Volumio Backend Module. May 01 16:41:58 jimvol systemd[1]: Started Volumio Backend Module. May 01 16:41:58 jimvol systemd[1]: Started dynamicswap service. May 01 16:41:58 jimvol systemd[1]: dynamicswap.service: Succeeded. May 01 16:41:58 jimvol ntpd[1073]: error resolving pool 2.debian.pool.ntp.org: System error (-11) May 01 16:41:59 jimvol kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 May 01 16:41:59 jimvol kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 May 01 16:41:59 jimvol ntpd[1073]: error resolving pool 3.debian.pool.ntp.org: System error (-11) May 01 16:41:59 jimvol kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 May 01 16:41:59 jimvol kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 May 01 16:41:59 jimvol volumio[1099]: info: ------------------------------------------- May 01 16:41:59 jimvol volumio[1099]: info: ----- Volumio3 ---- May 01 16:41:59 jimvol volumio[1099]: info: ------------------------------------------- May 01 16:41:59 jimvol volumio[1099]: info: ----- System startup ---- May 01 16:41:59 jimvol volumio[1099]: info: ------------------------------------------- May 01 16:42:00 jimvol volumio[1099]: info: MYVOLUMIO Environment detected May 01 16:42:00 jimvol volumio[1099]: info: Plugin folders cleanup May 01 16:42:00 jimvol volumio[1099]: info: Scanning into folder /volumio/app/plugins/ May 01 16:42:00 jimvol volumio[1099]: info: Scanning category audio_interface May 01 16:42:00 jimvol volumio[1099]: info: Scanning category miscellanea May 01 16:42:00 jimvol volumio[1099]: info: Scanning category music_service May 01 16:42:00 jimvol volumio[1099]: info: Scanning category plugins.json May 01 16:42:00 jimvol volumio[1099]: info: Scanning category system_controller May 01 16:42:00 jimvol volumio[1099]: info: Scanning category user_interface May 01 16:42:00 jimvol volumio[1099]: info: Scanning into folder /data/plugins/ May 01 16:42:00 jimvol volumio[1099]: info: Scanning category audio_interface May 01 16:42:00 jimvol volumio[1099]: info: Scanning category music_service May 01 16:42:00 jimvol volumio[1099]: info: Plugin folders cleanup completed May 01 16:42:00 jimvol volumio[1099]: info: ------------------------------------------- May 01 16:42:00 jimvol volumio[1099]: info: ----- Core plugins startup ---- May 01 16:42:00 jimvol volumio[1099]: info: ------------------------------------------- May 01 16:42:00 jimvol volumio[1099]: info: Loading plugins from folder /volumio/app/plugins/ May 01 16:42:00 jimvol volumio[1099]: info: Adding plugin upnp to MyMusic Plugins May 01 16:42:00 jimvol volumio[1099]: info: Adding plugin airplay_emulation to MyMusic Plugins May 01 16:42:00 jimvol volumio[1099]: info: Adding plugin upnp_browser to MyMusic Plugins May 01 16:42:00 jimvol volumio[1099]: info: Loading plugins from folder /data/plugins/ May 01 16:42:00 jimvol volumio[1099]: info: Loading plugin "system"... May 01 16:42:00 jimvol volumio[1099]: info: Loading plugin "appearance"... May 01 16:42:01 jimvol wpa_supplicant[862]: wlan0: Trying to associate with SSID 'FRITZ!Box 7530 CJ' May 01 16:42:01 jimvol kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd090 fail, reason -52 May 01 16:42:01 jimvol kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd095 fail, reason -52 May 01 16:42:01 jimvol kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd099 fail, reason -52 May 01 16:42:01 jimvol kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd09d fail, reason -52 May 01 16:42:01 jimvol kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a1 fail, reason -52 May 01 16:42:01 jimvol kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a5 fail, reason -52 May 01 16:42:01 jimvol volumio[1099]: info: Loading plugin "network"... May 01 16:42:01 jimvol volumio[1099]: info: Refreshing Cached IP Addresses May 01 16:42:01 jimvol sudo[1126]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 01 16:42:01 jimvol sudo[1126]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 16:42:01 jimvol sudo[1126]: pam_unix(sudo:session): session closed for user root May 01 16:42:01 jimvol sudo[1128]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 01 16:42:01 jimvol sudo[1128]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 16:42:01 jimvol sudo[1128]: pam_unix(sudo:session): session closed for user root May 01 16:42:01 jimvol volumio[1099]: info: Loading plugin "services"... May 01 16:42:01 jimvol volumio[1099]: info: Loading plugin "alsa_controller"... May 01 16:42:01 jimvol dhcpcd[873]: wlan0: carrier acquired May 01 16:42:01 jimvol wpa_supplicant[862]: wlan0: Associated with dc:39:6f:ee:1b:f9 May 01 16:42:01 jimvol wpa_supplicant[862]: wlan0: CTRL-EVENT-CONNECTED - Connection to dc:39:6f:ee:1b:f9 completed [id=0 id_str=] May 01 16:42:01 jimvol wpa_supplicant[862]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 May 01 16:42:01 jimvol wpa_supplicant[862]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=GB May 01 16:42:01 jimvol sudo[1138]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 01 16:42:01 jimvol sudo[1138]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 16:42:01 jimvol dhcpcd[873]: wlan0: IAID eb:df:c0:23 May 01 16:42:01 jimvol dhcpcd[873]: wlan0: carrier lost May 01 16:42:01 jimvol wpa_supplicant[862]: RRM: Ignoring radio measurement request: Not RRM network May 01 16:42:01 jimvol dhcpcd[873]: wlan0: carrier acquired May 01 16:42:01 jimvol dhcpcd[873]: wlan0: IAID eb:df:c0:23 May 01 16:42:01 jimvol volumio[1099]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 01 16:42:01 jimvol volumio[1099]: info: Loading plugin "wizard"... May 01 16:42:01 jimvol volumio[1099]: info: Loading plugin "networkfs"... May 01 16:42:01 jimvol volumio[1099]: info: Starting Udev Watcher for removable devices May 01 16:42:01 jimvol volumio[1099]: info: Ignoring mount for partition: boot May 01 16:42:01 jimvol volumio[1099]: info: Ignoring mount for partition: volumio May 01 16:42:01 jimvol volumio[1099]: info: Ignoring mount for partition: volumio_data May 01 16:42:01 jimvol volumio[1099]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 01 16:42:01 jimvol volumio[1099]: info: Loading plugin "volumio_command_line_client"... May 01 16:42:01 jimvol volumio[1099]: info: Loading plugin "upnp"... May 01 16:42:01 jimvol volumio[1099]: info: [1746114121943] Starting Upmpd Daemon May 01 16:42:01 jimvol volumio[1099]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 01 16:42:01 jimvol volumio[1099]: info: Loading plugin "my_music"... May 01 16:42:01 jimvol volumio[1099]: info: Loading plugin "mpd"... May 01 16:42:02 jimvol volumio[1099]: info: Loading plugin "upnp_browser"... May 01 16:42:02 jimvol dhcpcd[873]: wlan0: soliciting an IPv6 router May 01 16:42:02 jimvol volumio[1099]: info: Loading plugin "alarm-clock"... May 01 16:42:02 jimvol dhcpcd[873]: wlan0: rebinding lease of 192.168.178.25 May 01 16:42:02 jimvol dhcpcd[873]: wlan0: probing address 192.168.178.25/24 May 01 16:42:02 jimvol volumio[1099]: info: Plugin airplay_emulation is not enabled May 01 16:42:02 jimvol volumio[1099]: info: Loading plugin "last_100"... May 01 16:42:02 jimvol volumio[1099]: info: Loading plugin "webradio"... May 01 16:42:02 jimvol volumio[1099]: info: Loading plugin "i2s_dacs"... May 01 16:42:02 jimvol volumio[1099]: info: Loading plugin "volumiodiscovery"... May 01 16:42:02 jimvol volumio[1099]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 01 16:42:02 jimvol volumio[1099]: *** WARNING *** Please fix your application to use the native API of Avahi! May 01 16:42:02 jimvol volumio[1099]: *** WARNING *** For more information see May 01 16:42:02 jimvol node[1099]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 01 16:42:02 jimvol volumio[1099]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 01 16:42:02 jimvol volumio[1099]: *** WARNING *** Please fix your application to use the native API of Avahi! May 01 16:42:02 jimvol volumio[1099]: *** WARNING *** For more information see May 01 16:42:02 jimvol node[1099]: *** WARNING *** Please fix your application to use the native API of Avahi! May 01 16:42:02 jimvol node[1099]: *** WARNING *** For more information see May 01 16:42:02 jimvol node[1099]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 01 16:42:02 jimvol node[1099]: *** WARNING *** Please fix your application to use the native API of Avahi! May 01 16:42:02 jimvol node[1099]: *** WARNING *** For more information see May 01 16:42:02 jimvol volumio[1099]: info: Applying required configuration parameters for plugin volumiodiscovery May 01 16:42:02 jimvol volumio[1099]: info: Discovery: Started advertising with name: JimVol May 01 16:42:02 jimvol volumio[1099]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 01 16:42:02 jimvol volumio[1099]: info: Loading plugin "squeezelite_mc"... May 01 16:42:03 jimvol volumio-remote-updater[565]: [2025-05-01 16:42:03] [connect] Successful connection May 01 16:42:03 jimvol volumio[1099]: info: Loading plugin "outputs"... May 01 16:42:03 jimvol volumio[1099]: info: Loading plugin "albumart"... May 01 16:42:03 jimvol volumio[1099]: info: Plugin example_plugin is not enabled May 01 16:42:03 jimvol volumio[1099]: info: Loading plugin "inputs"... May 01 16:42:03 jimvol volumio[1099]: info: Loading plugin "updater_comm"... May 01 16:42:04 jimvol volumio[1099]: info: Plugin mpdemulation is not enabled May 01 16:42:04 jimvol volumio[1099]: info: Loading plugin "rest_api"... May 01 16:42:04 jimvol volumio[1099]: info: Loading plugin "websocket"... May 01 16:42:04 jimvol volumio[1099]: info: Starting Socket.io Server version 2.3.0 May 01 16:42:04 jimvol volumio[1099]: info: Loading plugin "fusiondsp"... May 01 16:42:04 jimvol volumio[1099]: Forking 3 albumart workers May 01 16:42:04 jimvol volumio[1099]: info: Applying required configuration parameters for plugin fusiondsp May 01 16:42:04 jimvol volumio[1099]: info: Loading i18n strings for locale en May 01 16:42:04 jimvol volumio[1099]: Updating browse sources language May 01 16:42:04 jimvol volumio[1099]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 01 16:42:04 jimvol volumio[1099]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 01 16:42:04 jimvol volumio[1099]: info: CoreCommandRouter::initPlayerControls May 01 16:42:04 jimvol volumio[1099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 16:42:04 jimvol volumio[1099]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 01 16:42:04 jimvol volumio[1099]: Express server listening on port 3000 May 01 16:42:04 jimvol volumio[1099]: [Metrics] WebUI: 5s 510.40ms May 01 16:42:04 jimvol volumio[1099]: info: CoreStateMachine::resetVolumioState May 01 16:42:04 jimvol volumio[1099]: info: CoreStateMachine::getcurrentVolume May 01 16:42:04 jimvol volumio[1099]: info: CoreCommandRouter::volumioRetrievevolume May 01 16:42:04 jimvol volumio[1099]: info: Volumio Network Manager: Network status updated: 0 May 01 16:42:04 jimvol volumio-remote-updater[565]: [2025-05-01 16:42:04] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1746114123 101 May 01 16:42:05 jimvol volumio[1099]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 May 01 16:42:05 jimvol volumio[1099]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 01 16:42:05 jimvol volumio[1099]: info: Executing start script for DAC IQaudIO DAC Plus May 01 16:42:05 jimvol volumio[1099]: info: Reloading queue from file May 01 16:42:05 jimvol volumio[1099]: info: CoreStateMachine::setRepeat true single undefined May 01 16:42:05 jimvol volumio[1099]: info: CoreStateMachine::pushState May 01 16:42:05 jimvol volumio[1099]: info: CorePlayQueue::getTrack 0 May 01 16:42:05 jimvol volumio[1099]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 16:42:05 jimvol volumio[1099]: info: CoreCommandRouter::volumioPushState May 01 16:42:05 jimvol volumio[1099]: info: CoreStateMachine::setRandom false May 01 16:42:05 jimvol volumio[1099]: info: CoreStateMachine::pushState May 01 16:42:05 jimvol volumio[1099]: info: CorePlayQueue::getTrack 0 May 01 16:42:05 jimvol volumio[1099]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 16:42:05 jimvol volumio[1099]: info: CoreCommandRouter::volumioPushState May 01 16:42:05 jimvol volumio[1099]: info: Setting Device type: Raspberry PI May 01 16:42:05 jimvol volumio[1099]: info: VolumeController:: Volume=100 Mute =false May 01 16:42:05 jimvol volumio[1099]: info: CoreStateMachine::pushState May 01 16:42:05 jimvol volumio[1099]: info: CorePlayQueue::getTrack 0 May 01 16:42:05 jimvol volumio[1099]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 16:42:05 jimvol volumio[1099]: info: CoreCommandRouter::volumioPushState May 01 16:42:05 jimvol volumio[1099]: info: CoreStateMachine::updateTrackBlock May 01 16:42:05 jimvol volumio[1099]: info: CorePlayQueue::getTrackBlock May 01 16:42:05 jimvol volumio[1099]: info: CoreCommandRouter::volumioRetrievevolume May 01 16:42:05 jimvol volumio[1099]: info: DAC script executed May 01 16:42:05 jimvol volumio[1099]: info: Completed loading Core Plugins May 01 16:42:05 jimvol volumio[1099]: info: Preparing to generate the ALSA configuration file May 01 16:42:05 jimvol volumio[1099]: Starting albumart workers May 01 16:42:05 jimvol volumio[1099]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 01 16:42:05 jimvol volumio[1099]: info: Reading ALSA contributions from plugins. May 01 16:42:05 jimvol volumio[1099]: Starting albumart workers May 01 16:42:05 jimvol volumio[1099]: info: Asound.conf file unchanged, so no further update is needed May 01 16:42:05 jimvol volumio[1099]: info: Output device has changed, restarting MPD May 01 16:42:05 jimvol volumio[1099]: Starting albumart workers May 01 16:42:05 jimvol sudo[1138]: pam_unix(sudo:session): session closed for user root May 01 16:42:05 jimvol sudo[1249]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 01 16:42:05 jimvol sudo[1249]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 16:42:05 jimvol volumio[1099]: info: ___________ START PLUGINS ___________ May 01 16:42:05 jimvol volumio[1099]: info: ControllerMpd::onStart: Initializing MPD May 01 16:42:05 jimvol sudo[1249]: pam_unix(sudo:session): session closed for user root May 01 16:42:05 jimvol volumio[1099]: info: Creating MPD Configuration file May 01 16:42:05 jimvol sudo[1252]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 01 16:42:05 jimvol sudo[1252]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 16:42:05 jimvol volumio[1099]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 01 16:42:05 jimvol volumio[1099]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 01 16:42:05 jimvol volumio[1099]: info: [1746114125481] CoreMusicLibrary::Adding element Media Servers May 01 16:42:05 jimvol volumio[1099]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 01 16:42:05 jimvol sudo[1254]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 01 16:42:05 jimvol sudo[1254]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 16:42:05 jimvol sudo[1254]: pam_unix(sudo:session): session closed for user root May 01 16:42:05 jimvol systemd[1]: Stopping Music Player Daemon... May 01 16:42:05 jimvol sudo[1257]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 01 16:42:05 jimvol volumio[1099]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 01 16:42:05 jimvol volumio[1099]: info: [1746114125526] CoreMusicLibrary::Adding element Last_100 May 01 16:42:05 jimvol volumio[1099]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 01 16:42:05 jimvol volumio[1099]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 01 16:42:05 jimvol volumio[1099]: info: [1746114125531] CoreMusicLibrary::Adding element Webradio May 01 16:42:05 jimvol volumio[1099]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 01 16:42:05 jimvol sudo[1257]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 16:42:05 jimvol volumio[1099]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 01 16:42:05 jimvol volumio[1099]: info: Initializing BBC Radios May 01 16:42:05 jimvol systemd[1]: mpd.service: Succeeded. May 01 16:42:05 jimvol systemd[1]: Stopped Music Player Daemon. May 01 16:42:05 jimvol systemd[1]: Starting Music Player Daemon... May 01 16:42:05 jimvol systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM May 01 16:42:05 jimvol systemd[1]: mpd.service: Succeeded. May 01 16:42:05 jimvol systemd[1]: Stopped Music Player Daemon. May 01 16:42:05 jimvol systemd[1]: Starting Music Player Daemon... May 01 16:42:05 jimvol volumio[1099]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 01 16:42:05 jimvol volumio[1099]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 01 16:42:05 jimvol volumio[1099]: info: [squeezelite_mc] Starting proxy server... May 01 16:42:05 jimvol wpa_supplicant[862]: RRM: Ignoring radio measurement request: Not RRM network May 01 16:42:05 jimvol volumio[1099]: info: Loading i18n strings for locale en May 01 16:42:05 jimvol volumio[1099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile May 01 16:42:05 jimvol volumio[1099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 16:42:05 jimvol volumio[1099]: info: FusionDsp - mixtype--------------------- Hardware May 01 16:42:05 jimvol volumio[1099]: info: Volumio Calling Home May 01 16:42:05 jimvol sudo[1269]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 01 16:42:05 jimvol sudo[1269]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 16:42:05 jimvol sudo[1269]: pam_unix(sudo:session): session closed for user root May 01 16:42:05 jimvol volumio[1099]: info: [squeezelite_mc] Proxy server started on port 45645 May 01 16:42:05 jimvol volumio[1099]: info: Preparing to generate the ALSA configuration file May 01 16:42:06 jimvol volumio[1099]: info: CoreCommandRouter::volumioRetrievevolume May 01 16:42:06 jimvol volumio[1099]: Unhandled rejection Error: No sockets available, cannot start. May 01 16:42:06 jimvol volumio[1099]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) May 01 16:42:06 jimvol volumio[1099]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) May 01 16:42:06 jimvol volumio[1099]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 May 01 16:42:06 jimvol volumio[1099]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) May 01 16:42:06 jimvol volumio[1099]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) May 01 16:42:06 jimvol volumio[1099]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) May 01 16:42:06 jimvol volumio[1099]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) May 01 16:42:06 jimvol volumio[1099]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) May 01 16:42:06 jimvol volumio[1099]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) May 01 16:42:06 jimvol volumio[1099]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) May 01 16:42:06 jimvol volumio[1099]: at HashMap. (/volumio/app/pluginmanager.js:496:31) May 01 16:42:06 jimvol volumio[1099]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) May 01 16:42:06 jimvol volumio[1099]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) May 01 16:42:06 jimvol volumio[1099]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) May 01 16:42:06 jimvol volumio[1099]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) May 01 16:42:06 jimvol volumio[1099]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) May 01 16:42:06 jimvol volumio[1099]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 01 16:42:06 jimvol volumio[1099]: info: Reading ALSA contributions from plugins. May 01 16:42:06 jimvol volumio[1099]: info: MPD Permissions set May 01 16:42:06 jimvol volumio[1099]: info: MPD Permissions set May 01 16:42:06 jimvol volumio[1099]: info: VolumeController:: Volume=100 Mute =false May 01 16:42:06 jimvol volumio[1099]: info: CoreStateMachine::pushState May 01 16:42:06 jimvol volumio[1099]: info: CorePlayQueue::getTrack 0 May 01 16:42:06 jimvol volumio[1099]: info: CoreCommandRouter::volumioPushState May 01 16:42:06 jimvol volumio[1099]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 May 01 16:42:06 jimvol volumio[1099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 16:42:06 jimvol volumio[1099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 16:42:06 jimvol volumio[1099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 16:42:06 jimvol volumio[1099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 16:42:06 jimvol volumio[1099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 16:42:06 jimvol volumio[1099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 16:42:06 jimvol volumio[1099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 16:42:06 jimvol volumio[1099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 16:42:06 jimvol volumio[1099]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 01 16:42:06 jimvol volumio[1099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 16:42:06 jimvol volumio[1099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 16:42:06 jimvol volumio[1099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 16:42:06 jimvol volumio[1099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 16:42:06 jimvol volumio[1099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 16:42:06 jimvol volumio[1099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 16:42:06 jimvol volumio[1099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 16:42:06 jimvol volumio[1099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 16:42:06 jimvol volumio[1099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 16:42:06 jimvol volumio[1099]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 01 16:42:06 jimvol volumio[1099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 16:42:06 jimvol volumio[1099]: info: VolumeController:: Volume=100 Mute =false May 01 16:42:06 jimvol volumio[1099]: info: CoreStateMachine::pushState May 01 16:42:06 jimvol volumio[1099]: info: CorePlayQueue::getTrack 0 May 01 16:42:06 jimvol volumio[1099]: info: CoreCommandRouter::volumioPushState May 01 16:42:06 jimvol volumio[1099]: info: [squeezelite_mc] Server discovery started May 01 16:42:06 jimvol volumio[1099]: info: [squeezelite_mc] Player finder started May 01 16:42:06 jimvol volumio[1099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 16:42:06 jimvol volumio[1099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 16:42:06 jimvol volumio[1099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 16:42:06 jimvol volumio[1099]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 01 16:42:06 jimvol volumio[1099]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true May 01 16:42:06 jimvol volumio[1099]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 01 16:42:06 jimvol volumio[1099]: Error: send ENETUNREACH 255.255.255.255:3483 May 01 16:42:06 jimvol volumio[1099]: at doSend (dgram.js:692:16) May 01 16:42:06 jimvol volumio[1099]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) May 01 16:42:06 jimvol volumio[1099]: at afterDns (dgram.js:638:5) May 01 16:42:06 jimvol volumio[1099]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { May 01 16:42:06 jimvol volumio[1099]: errno: -101, May 01 16:42:06 jimvol volumio[1099]: code: 'ENETUNREACH', May 01 16:42:06 jimvol volumio[1099]: syscall: 'send', May 01 16:42:06 jimvol volumio[1099]: address: '255.255.255.255', May 01 16:42:06 jimvol volumio[1099]: port: 3483 May 01 16:42:06 jimvol volumio[1099]: } May 01 16:42:06 jimvol volumio[1099]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 01 16:42:07 jimvol dhcpcd[873]: wlan0: leased 192.168.178.25 for 864000 seconds May 01 16:42:07 jimvol avahi-daemon[556]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.178.25. May 01 16:42:07 jimvol dhcpcd[873]: wlan0: adding route to 192.168.178.0/24 May 01 16:42:07 jimvol dhcpcd[873]: wlan0: adding default route via 192.168.178.1 May 01 16:42:07 jimvol avahi-daemon[556]: New relevant interface wlan0.IPv4 for mDNS. May 01 16:42:07 jimvol avahi-daemon[556]: Registering new address record for 192.168.178.25 on wlan0.IPv4. May 01 16:42:07 jimvol sudo[1358]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-01 16:41 May 01 16:42:07 jimvol sudo[1358]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 16:42:07 jimvol ntpd[1073]: ntpd exiting on signal 15 (Terminated) May 01 16:42:07 jimvol systemd[1]: Stopping Network Time Service... May 01 16:42:07 jimvol systemd[1]: ntp.service: Succeeded. May 01 16:42:07 jimvol systemd[1]: Stopped Network Time Service. PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 24 Apr 2025 09:33:57 AM CEST" VOLUMIO_VERSION="3.804" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="354f95cf0a6c2cbcd57b1aca3179c81c"