-- Logs begin at Mon 2025-01-13 18:30:48 CET, end at Tue 2025-01-14 12:58:22 CET. -- Jan 14 12:57:23 music-player volumiologrotate[771]: ls: cannot access '/var/log/samba/log.wb-MUSIC': No such file or directory Jan 14 12:57:23 music-player volumiologrotate[771]: ls: cannot access 'PLAYER': No such file or directory Jan 14 12:57:51 music-player volumio[1408]: error: [now-playing] API endpoint weather/fetchInfo returned error: Error: Could not access weather service: missing geographic coordinates. Jan 14 12:57:51 music-player volumio[1408]: at WeatherAPI.fetchInfo (/data/plugins/user_interface/now_playing/dist/lib/api/WeatherAPI.js:95:19) Jan 14 12:57:51 music-player volumio[1408]: at Object.api (/data/plugins/user_interface/now_playing/dist/app/Handler.js:115:37) Jan 14 12:57:51 music-player volumio[1408]: at /data/plugins/user_interface/now_playing/dist/app/Router.js:47:13 Jan 14 12:57:51 music-player volumio[1408]: at Layer.handle [as handle_request] (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/layer.js:95:5) Jan 14 12:57:51 music-player volumio[1408]: at next (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/route.js:149:13) Jan 14 12:57:51 music-player volumio[1408]: at Route.dispatch (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/route.js:119:3) Jan 14 12:57:51 music-player volumio[1408]: at Layer.handle [as handle_request] (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/layer.js:95:5) Jan 14 12:57:51 music-player volumio[1408]: at /data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:284:15 Jan 14 12:57:51 music-player volumio[1408]: at param (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:365:14) Jan 14 12:57:51 music-player volumio[1408]: at param (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:376:14) Jan 14 12:57:51 music-player volumio[1408]: at Function.process_params (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:421:3) Jan 14 12:57:51 music-player volumio[1408]: at next (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:280:10) Jan 14 12:57:51 music-player volumio[1408]: at Function.handle (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:175:3) Jan 14 12:57:51 music-player volumio[1408]: at router (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:47:12) Jan 14 12:57:51 music-player volumio[1408]: at Layer.handle [as handle_request] (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/layer.js:95:5) Jan 14 12:58:08 music-player volumio[1408]: info: Jan 14 12:58:08 music-player volumio[1408]: ---------------------------- MPD announces system playlist update Jan 14 12:58:08 music-player volumio[1408]: info: Ignoring MPD Status Update Jan 14 12:58:08 music-player volumio[1408]: info: Jan 14 12:58:08 music-player volumio[1408]: ---------------------------- MPD announces state update: player Jan 14 12:58:08 music-player volumio[1408]: info: ControllerMpd::getState Jan 14 12:58:08 music-player volumio[1408]: verbose: ControllerMpd::sendMpdCommand status Jan 14 12:58:08 music-player volumio[1408]: info: ------------------------------ 3ms Jan 14 12:58:08 music-player volumio[1408]: info: sendMpdCommand status took 2 milliseconds Jan 14 12:58:08 music-player volumio[1408]: verbose: ControllerMpd::parseState Jan 14 12:58:08 music-player volumio[1408]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 14 12:58:08 music-player volumio[1408]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 14 12:58:08 music-player volumio[1408]: verbose: ControllerMpd::parseTrackInfo Jan 14 12:58:08 music-player volumio[1408]: info: ControllerMpd::pushState Jan 14 12:58:08 music-player volumio[1408]: info: CoreCommandRouter::servicePushState Jan 14 12:58:08 music-player volumio[1408]: info: CorePlayQueue::getTrack 0 Jan 14 12:58:08 music-player volumio[1408]: verbose: STATE SERVICE {"status":"play","position":0,"seek":12493405,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"RADIO10.mp3?dist=tunein&DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTczNjg0MzM5MCwiaXNzIjoidGlzcnYifQ.JEyLU4Q1AhwNlxHrYyQJ-_E5A9hrlH3VioXYyK04ji4","artist":"Radio 10","album":null,"uri":"https://playerservices.streamtheworld.com/api/livestream-redirect/RADIO10.mp3?dist=tunein&DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTczNjg0MzM5MCwiaXNzIjoidGlzcnYifQ.JEyLU4Q1AhwNlxHrYyQJ-_E5A9hrlH3VioXYyK04ji4","trackType":""} Jan 14 12:58:08 music-player volumio[1408]: verbose: CURRENT POSITION 0 Jan 14 12:58:08 music-player volumio[1408]: info: CoreStateMachine::syncState stateService play Jan 14 12:58:08 music-player volumio[1408]: info: CoreStateMachine::syncState currentStatus play Jan 14 12:58:08 music-player volumio[1408]: info: Received an update from plugin. extracting info from payload Jan 14 12:58:08 music-player volumio[1408]: info: CoreStateMachine::pushState Jan 14 12:58:08 music-player volumio[1408]: info: CorePlayQueue::getTrack 0 Jan 14 12:58:08 music-player volumio[1408]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 14 12:58:08 music-player volumio[1408]: info: CoreCommandRouter::volumioPushState Jan 14 12:58:08 music-player volumio[1408]: info: MRS: Pushing multiroomSync output update for this device Jan 14 12:58:08 music-player volumio[1408]: info: MRS: Pushing multiroomSync output Jan 14 12:58:08 music-player volumio[1408]: info: CoreStateMachine::pushState Jan 14 12:58:08 music-player volumio[1408]: info: CoreCommandRouter::volumioPushState Jan 14 12:58:08 music-player volumio[1408]: info: MRS: Pushing multiroomSync output update for this device Jan 14 12:58:08 music-player volumio[1408]: info: MRS: Pushing multiroomSync output Jan 14 12:58:08 music-player volumio[1408]: info: ------------------------------ 8ms Jan 14 12:58:08 music-player volumio[1408]: info: [ASDebug] CurState: play PrevState: play Jan 14 12:58:08 music-player volumio[1408]: info: [ASDebug] CurState: play PrevState: play Jan 14 12:58:08 music-player volumio[1408]: info: [LastFM] Current track has sufficient metadata: title (Radio 10) and artist (Radio 10) passed on explicitly Jan 14 12:58:08 music-player volumio[1408]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 Jan 14 12:58:08 music-player volumio[1408]: SPOTIFY: SPOTIFY VOLUME 90 Jan 14 12:58:08 music-player volumio[1408]: SPOTIFY: VOLUMIO VOLUME 90 Jan 14 12:58:08 music-player volumio[1408]: SPOTIFY: DELTA VOLUME ENOUGH: false Jan 14 12:58:08 music-player volumio[1408]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 Jan 14 12:58:08 music-player volumio[1408]: SPOTIFY: SPOTIFY VOLUME 90 Jan 14 12:58:08 music-player volumio[1408]: SPOTIFY: VOLUMIO VOLUME 90 Jan 14 12:58:08 music-player volumio[1408]: SPOTIFY: DELTA VOLUME ENOUGH: false Jan 14 12:58:08 music-player volumio[1408]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 14 12:58:08 music-player volumio[1408]: info: [now-playing] Fetch metadata (DefaultMetadataProvider): {"type":"song","name":"Radio 10","artist":"Radio 10","album":"null","duration":"0","uri":"https://playerservices.streamtheworld.com/api/livestream-redirect/RADIO10.mp3?dist=tunein&DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTczNjg0MzM5MCwiaXNzIjoidGlzcnYifQ.JEyLU4Q1AhwNlxHrYyQJ-_E5A9hrlH3VioXYyK04ji4","service":""} Jan 14 12:58:08 music-player wpa_supplicant[1093]: wlan0: CTRL-EVENT-DISCONNECTED bssid=3c:7c:3f:b6:d6:64 reason=8 Jan 14 12:58:08 music-player dhcpcd[1228]: wlan0: carrier lost Jan 14 12:58:08 music-player wpa_supplicant[1093]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Jan 14 12:58:08 music-player wpa_supplicant[1093]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=DE Jan 14 12:58:08 music-player avahi-daemon[785]: Withdrawing address record for 192.168.1.189 on wlan0. Jan 14 12:58:08 music-player avahi-daemon[785]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.189. Jan 14 12:58:08 music-player dhcpcd[1228]: wlan0: deleting route to 192.168.1.0/24 Jan 14 12:58:08 music-player dhcpcd[1228]: wlan0: deleting default route via 192.168.1.1 Jan 14 12:58:08 music-player avahi-daemon[785]: Interface wlan0.IPv4 no longer relevant for mDNS. Jan 14 12:58:08 music-player volumio[1408]: info: Discovery: A device disappeared from network Jan 14 12:58:09 music-player kernel: macb 1f00100000.ethernet eth0: Link is Down Jan 14 12:58:09 music-player dhcpcd[1228]: eth0: carrier lost Jan 14 12:58:09 music-player dhcpcd[1182]: eth0: carrier lost Jan 14 12:58:09 music-player avahi-daemon[785]: Withdrawing address record for 192.168.1.188 on eth0. Jan 14 12:58:09 music-player dhcpcd[1228]: eth0: deleting route to 192.168.1.0/24 Jan 14 12:58:09 music-player dhcpcd[1228]: eth0: deleting default route via 192.168.1.1 Jan 14 12:58:09 music-player avahi-daemon[785]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.1.188. Jan 14 12:58:09 music-player avahi-daemon[785]: Interface eth0.IPv4 no longer relevant for mDNS. Jan 14 12:58:09 music-player volumio[1408]: info: Discovery: A device disappeared from network Jan 14 12:58:09 music-player ifplugd(eth0)[1006]: Link beat lost. Jan 14 12:58:12 music-player ntpd[1009]: Deleting interface #3 eth0, 192.168.1.188#123, interface stats: received=1109, sent=1119, dropped=0, active_time=79236 secs Jan 14 12:58:12 music-player ntpd[1009]: 178.215.228.24 local addr 192.168.1.188 -> Jan 14 12:58:12 music-player ntpd[1009]: 80.60.67.39 local addr 192.168.1.188 -> Jan 14 12:58:12 music-player ntpd[1009]: 94.198.159.15 local addr 192.168.1.188 -> Jan 14 12:58:12 music-player ntpd[1009]: 185.51.192.62 local addr 192.168.1.188 -> Jan 14 12:58:12 music-player ntpd[1009]: 178.239.19.57 local addr 192.168.1.188 -> Jan 14 12:58:12 music-player ntpd[1009]: 178.239.19.60 local addr 192.168.1.188 -> Jan 14 12:58:12 music-player ntpd[1009]: 178.239.19.63 local addr 192.168.1.188 -> Jan 14 12:58:12 music-player ntpd[1009]: Deleting interface #4 wlan0, 192.168.1.189#123, interface stats: received=0, sent=0, dropped=0, active_time=79228 secs Jan 14 12:58:12 music-player kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Jan 14 12:58:12 music-player kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Jan 14 12:58:12 music-player kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Jan 14 12:58:12 music-player kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Jan 14 12:58:13 music-player volumio[1408]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 14 12:58:13 music-player volumio[1408]: Error: getaddrinfo ENOTFOUND ws.audioscrobbler.com Jan 14 12:58:13 music-player volumio[1408]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { Jan 14 12:58:13 music-player volumio[1408]: errno: -3007, Jan 14 12:58:13 music-player volumio[1408]: code: 'ENOTFOUND', Jan 14 12:58:13 music-player volumio[1408]: syscall: 'getaddrinfo', Jan 14 12:58:13 music-player volumio[1408]: hostname: 'ws.audioscrobbler.com' Jan 14 12:58:13 music-player volumio[1408]: } Jan 14 12:58:13 music-player volumio[1408]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 14 12:58:13 music-player sudo[22185]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-14 12:57 Jan 14 12:58:13 music-player sudo[22185]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 12:58:13 music-player sudo[22185]: pam_unix(sudo:session): session closed for user root Jan 14 12:58:13 music-player volumio-remote-updater[767]: [2025-01-14 12:58:13] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jan 14 12:58:13 music-player go-librespot[1701]: time="2025-01-14T12:58:13+01:00" level=error msg="websocket connection errored" error="failed to get reader: failed to read frame header: EOF" Jan 14 12:58:13 music-player volumio-remote-updater[767]: [2025-01-14 12:58:13] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jan 14 12:58:13 music-player systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jan 14 12:58:13 music-player systemd[1]: volumio.service: Failed with result 'exit-code'. Jan 14 12:58:13 music-player systemd[1]: Started dynamicswap service. Jan 14 12:58:13 music-player systemd[1]: dynamicswap.service: Succeeded. Jan 14 12:58:13 music-player systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jan 14 12:58:13 music-player systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Jan 14 12:58:13 music-player systemd[1]: Started dynamicswap service. Jan 14 12:58:13 music-player systemd[1]: Stopped Volumio Backend Module. Jan 14 12:58:13 music-player systemd[1]: Started Volumio Backend Module. Jan 14 12:58:13 music-player systemd[1]: dynamicswap.service: Succeeded. Jan 14 12:58:14 music-player volumio[22201]: info: ------------------------------------------- Jan 14 12:58:14 music-player volumio[22201]: info: ----- Volumio3 ---- Jan 14 12:58:14 music-player volumio[22201]: info: ------------------------------------------- Jan 14 12:58:14 music-player volumio[22201]: info: ----- System startup ---- Jan 14 12:58:14 music-player volumio[22201]: info: ------------------------------------------- Jan 14 12:58:14 music-player kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd090 fail, reason -52 Jan 14 12:58:14 music-player kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd095 fail, reason -52 Jan 14 12:58:14 music-player kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd099 fail, reason -52 Jan 14 12:58:14 music-player kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd09d fail, reason -52 Jan 14 12:58:14 music-player kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a1 fail, reason -52 Jan 14 12:58:14 music-player kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a5 fail, reason -52 Jan 14 12:58:14 music-player wpa_supplicant[1093]: wlan0: Trying to associate with SSID 'Sist' Jan 14 12:58:14 music-player volumio[22201]: info: MYVOLUMIO Environment detected Jan 14 12:58:14 music-player volumio[22201]: info: Plugin folders cleanup Jan 14 12:58:14 music-player volumio[22201]: info: Scanning into folder /volumio/app/plugins/ Jan 14 12:58:14 music-player volumio[22201]: info: Scanning category audio_interface Jan 14 12:58:14 music-player volumio[22201]: info: Scanning category miscellanea Jan 14 12:58:14 music-player volumio[22201]: info: Scanning category music_service Jan 14 12:58:14 music-player volumio[22201]: info: Scanning category plugins.json Jan 14 12:58:14 music-player volumio[22201]: info: Scanning category system_controller Jan 14 12:58:14 music-player volumio[22201]: info: Scanning category user_interface Jan 14 12:58:14 music-player volumio[22201]: info: Scanning into folder /data/plugins/ Jan 14 12:58:14 music-player volumio[22201]: info: Scanning category music_service Jan 14 12:58:14 music-player volumio[22201]: info: Scanning category system_controller Jan 14 12:58:14 music-player volumio[22201]: info: Scanning category user_interface Jan 14 12:58:14 music-player volumio[22201]: info: Plugin folders cleanup completed Jan 14 12:58:14 music-player volumio[22201]: info: ------------------------------------------- Jan 14 12:58:14 music-player volumio[22201]: info: ----- Core plugins startup ---- Jan 14 12:58:14 music-player volumio[22201]: info: ------------------------------------------- Jan 14 12:58:14 music-player volumio[22201]: info: Loading plugins from folder /volumio/app/plugins/ Jan 14 12:58:14 music-player volumio[22201]: info: Adding plugin upnp to MyMusic Plugins Jan 14 12:58:14 music-player volumio[22201]: info: Adding plugin airplay_emulation to MyMusic Plugins Jan 14 12:58:14 music-player volumio[22201]: info: Adding plugin upnp_browser to MyMusic Plugins Jan 14 12:58:14 music-player volumio[22201]: info: Loading plugins from folder /data/plugins/ Jan 14 12:58:14 music-player volumio[22201]: info: Loading plugin "system"... Jan 14 12:58:14 music-player wpa_supplicant[1093]: wlan0: Associated with cc:28:aa:40:aa:84 Jan 14 12:58:14 music-player wpa_supplicant[1093]: wlan0: CTRL-EVENT-CONNECTED - Connection to cc:28:aa:40:aa:84 completed [id=0 id_str=] Jan 14 12:58:14 music-player dhcpcd[1228]: wlan0: carrier acquired Jan 14 12:58:14 music-player wpa_supplicant[1093]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jan 14 12:58:14 music-player wpa_supplicant[1093]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=DE Jan 14 12:58:14 music-player dhcpcd[1228]: wlan0: IAID 67:4a:c6:66 Jan 14 12:58:14 music-player volumio[22201]: info: Loading plugin "ampswitch"... Jan 14 12:58:14 music-player volumio[22201]: info: Applying required configuration parameters for plugin ampswitch Jan 14 12:58:14 music-player volumio[22201]: info: Loading plugin "appearance"... Jan 14 12:58:14 music-player volumio[22201]: info: Loading plugin "network"... Jan 14 12:58:14 music-player volumio[22201]: info: Refreshing Cached IP Addresses Jan 14 12:58:14 music-player sudo[22230]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 14 12:58:14 music-player sudo[22230]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 12:58:14 music-player sudo[22230]: pam_unix(sudo:session): session closed for user root Jan 14 12:58:14 music-player sudo[22232]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 14 12:58:14 music-player sudo[22232]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 12:58:14 music-player sudo[22232]: pam_unix(sudo:session): session closed for user root Jan 14 12:58:14 music-player volumio[22201]: info: Loading plugin "services"... Jan 14 12:58:14 music-player volumio[22201]: info: Loading plugin "alsa_controller"... Jan 14 12:58:14 music-player sudo[22241]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jan 14 12:58:14 music-player sudo[22241]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 12:58:14 music-player dhcpcd[1228]: wlan0: rebinding lease of 192.168.1.189 Jan 14 12:58:14 music-player volumio[22201]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 14 12:58:14 music-player volumio[22201]: info: Loading plugin "wizard"... Jan 14 12:58:14 music-player volumio[22201]: info: Loading plugin "networkfs"... Jan 14 12:58:14 music-player volumio[22201]: info: Cannot mount NAS Volumio at system boot, trial number 1 ,retrying in 5 seconds Jan 14 12:58:14 music-player volumio[22201]: info: Cannot mount NAS muziek at system boot, trial number 1 ,retrying in 5 seconds Jan 14 12:58:14 music-player volumio[22201]: info: Starting Udev Watcher for removable devices Jan 14 12:58:14 music-player volumio[22201]: info: Ignoring mount for partition: boot Jan 14 12:58:14 music-player volumio[22201]: info: Ignoring mount for partition: volumio Jan 14 12:58:14 music-player volumio[22201]: info: Ignoring mount for partition: volumio_data Jan 14 12:58:14 music-player volumio[22201]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 14 12:58:14 music-player volumio[22201]: info: Loading plugin "volumio_command_line_client"... Jan 14 12:58:14 music-player volumio[22201]: info: Loading plugin "upnp"... Jan 14 12:58:14 music-player volumio[22201]: info: [1736855894791] Starting Upmpd Daemon Jan 14 12:58:14 music-player volumio[22201]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 14 12:58:14 music-player volumio[22201]: info: Loading plugin "my_music"... Jan 14 12:58:14 music-player volumio[22201]: info: Loading plugin "mpd"... Jan 14 12:58:14 music-player volumio[22201]: info: Loading plugin "upnp_browser"... Jan 14 12:58:14 music-player volumio[22201]: info: Loading plugin "alarm-clock"... Jan 14 12:58:14 music-player volumio[22201]: info: Loading plugin "airplay_emulation"... Jan 14 12:58:15 music-player volumio[22201]: info: Starting Shairport Sync Jan 14 12:58:15 music-player volumio[22201]: info: Loading plugin "last_100"... Jan 14 12:58:15 music-player volumio[22201]: info: Loading plugin "webradio"... Jan 14 12:58:15 music-player volumio[22201]: info: Loading plugin "i2s_dacs"... Jan 14 12:58:15 music-player volumio[22201]: info: Loading plugin "volumiodiscovery"... Jan 14 12:58:15 music-player volumio[22201]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 14 12:58:15 music-player volumio[22201]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 14 12:58:15 music-player volumio[22201]: *** WARNING *** For more information see Jan 14 12:58:15 music-player volumio[22201]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 14 12:58:15 music-player volumio[22201]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 14 12:58:15 music-player volumio[22201]: *** WARNING *** For more information see Jan 14 12:58:15 music-player node[22201]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 14 12:58:15 music-player node[22201]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 14 12:58:15 music-player node[22201]: *** WARNING *** For more information see Jan 14 12:58:15 music-player node[22201]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 14 12:58:15 music-player node[22201]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 14 12:58:15 music-player node[22201]: *** WARNING *** For more information see Jan 14 12:58:15 music-player volumio[22201]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 14 12:58:15 music-player volumio[22201]: info: Discovery: Started advertising with name: Music Player Jan 14 12:58:15 music-player volumio[22201]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 14 12:58:15 music-player volumio[22201]: info: Loading plugin "spop"... Jan 14 12:58:15 music-player volumio[22201]: info: Loading plugin "ytcr"... Jan 14 12:58:15 music-player dhcpcd[1228]: wlan0: soliciting an IPv6 router Jan 14 12:58:15 music-player volumio[22201]: info: Loading plugin "autostart"... Jan 14 12:58:15 music-player volumio[22201]: info: Applying required configuration parameters for plugin autostart Jan 14 12:58:15 music-player volumio[22201]: info: AutoStart - onVolumioStart - read config.json Jan 14 12:58:15 music-player volumio[22201]: info: Loading plugin "now_playing"... Jan 14 12:58:16 music-player volumio[22201]: info: Loading plugin "outputs"... Jan 14 12:58:16 music-player volumio[22201]: info: Loading plugin "albumart"... Jan 14 12:58:16 music-player volumio[22201]: info: Plugin example_plugin is not enabled Jan 14 12:58:16 music-player volumio[22201]: info: Loading plugin "inputs"... Jan 14 12:58:16 music-player volumio[22201]: info: Loading plugin "updater_comm"... Jan 14 12:58:16 music-player volumio[22201]: info: Plugin mpdemulation is not enabled Jan 14 12:58:16 music-player volumio[22201]: info: Loading plugin "rest_api"... Jan 14 12:58:16 music-player volumio[22201]: info: Loading plugin "websocket"... Jan 14 12:58:16 music-player volumio[22201]: info: Starting Socket.io Server version 2.3.0 Jan 14 12:58:16 music-player volumio[22201]: info: Loading plugin "backup_restore"... Jan 14 12:58:16 music-player volumio[22201]: Forking 3 albumart workers Jan 14 12:58:16 music-player volumio[22201]: info: Applying required configuration parameters for plugin backup_restore Jan 14 12:58:16 music-player volumio[22201]: info: Loading plugin "Systeminfo"... Jan 14 12:58:16 music-player volumio[22201]: info: Loading plugin "lastfm"... Jan 14 12:58:16 music-player volumio[22201]: Starting albumart workers Jan 14 12:58:16 music-player volumio[22201]: info: Plugin peppymeterbasic is not enabled Jan 14 12:58:16 music-player volumio[22201]: info: Loading plugin "touch_display"... Jan 14 12:58:16 music-player volumio[22201]: Starting albumart workers Jan 14 12:58:16 music-player volumio[22201]: Starting albumart workers Jan 14 12:58:16 music-player volumio[22201]: info: Applying required configuration parameters for plugin touch_display Jan 14 12:58:16 music-player volumio[22201]: info: Loading i18n strings for locale nl Jan 14 12:58:16 music-player volumio[22201]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Jan 14 12:58:16 music-player volumio[22201]: Updating browse sources language Jan 14 12:58:16 music-player volumio[22201]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 14 12:58:16 music-player volumio[22201]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 14 12:58:16 music-player volumio[22201]: info: CoreCommandRouter::initPlayerControls Jan 14 12:58:16 music-player volumio[22201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 12:58:16 music-player volumio[22201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 12:58:16 music-player volumio[22201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 12:58:16 music-player volumio[22201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 12:58:16 music-player volumio[22201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 12:58:16 music-player volumio[22201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 12:58:16 music-player volumio[22201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 12:58:16 music-player volumio[22201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 12:58:16 music-player volumio[22201]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 14 12:58:16 music-player volumio[22201]: Express server listening on port 3000 Jan 14 12:58:16 music-player volumio[22201]: [Metrics] WebUI: 2s 936.67ms Jan 14 12:58:16 music-player volumio[22201]: info: CoreStateMachine::resetVolumioState Jan 14 12:58:16 music-player volumio[22201]: info: CoreStateMachine::getcurrentVolume Jan 14 12:58:16 music-player volumio[22201]: info: CoreCommandRouter::volumioRetrievevolume Jan 14 12:58:17 music-player volumio[22201]: info: Volumio Network Manager: Network status updated: 0 Jan 14 12:58:17 music-player volumio[22201]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS aarch64 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Jan 14 12:58:17 music-player volumio[22201]: info: CoreStateMachine::pushState Jan 14 12:58:17 music-player volumio[22201]: info: CorePlayQueue::getTrack 0 Jan 14 12:58:17 music-player volumio[22201]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 14 12:58:17 music-player volumio[22201]: info: CoreCommandRouter::volumioPushState Jan 14 12:58:17 music-player volumio[22201]: info: CoreStateMachine::updateTrackBlock Jan 14 12:58:17 music-player volumio[22201]: info: CorePlayQueue::getTrackBlock Jan 14 12:58:17 music-player volumio[22201]: info: CoreCommandRouter::volumioRetrievevolume Jan 14 12:58:17 music-player volumio[22201]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Jan 14 12:58:17 music-player volumio[22201]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Jan 14 12:58:17 music-player volumio[22201]: error: Failed callmethod call: TypeError: Cannot read property 'has' of undefined Jan 14 12:58:17 music-player volumio[22201]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jan 14 12:58:17 music-player volumio[22201]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Jan 14 12:58:17 music-player volumio[22201]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Jan 14 12:58:17 music-player volumio[22201]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 14 12:58:17 music-player volumio[22201]: info: Reloading queue from file Jan 14 12:58:17 music-player volumio[22201]: info: Listing playlists Jan 14 12:58:17 music-player volumio[22201]: info: CoreCommandRouter::volumioGetQueue Jan 14 12:58:17 music-player volumio[22201]: info: CoreStateMachine::getQueue Jan 14 12:58:17 music-player volumio[22201]: info: CorePlayQueue::getQueue Jan 14 12:58:17 music-player volumio[22201]: info: CoreCommandRouter::volumioGetVisibleSources Jan 14 12:58:17 music-player volumio[22201]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 14 12:58:17 music-player volumio[22201]: info: CoreCommandRouter::volumioGetState Jan 14 12:58:17 music-player volumio[22201]: info: CorePlayQueue::getTrack 0 Jan 14 12:58:17 music-player volumio[22201]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Jan 14 12:58:17 music-player volumio[22201]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Jan 14 12:58:17 music-player volumio[22201]: error: Failed callmethod call: TypeError: Cannot read property 'has' of undefined Jan 14 12:58:17 music-player volumio[22201]: info: Listing playlists Jan 14 12:58:17 music-player volumio[22201]: info: CoreCommandRouter::volumioGetQueue Jan 14 12:58:17 music-player volumio[22201]: info: CoreStateMachine::getQueue Jan 14 12:58:17 music-player volumio[22201]: info: CorePlayQueue::getQueue Jan 14 12:58:17 music-player volumio[22201]: info: CoreCommandRouter::volumioGetVisibleSources Jan 14 12:58:17 music-player volumio[22201]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 14 12:58:17 music-player volumio[22201]: info: CoreCommandRouter::volumioGetState Jan 14 12:58:17 music-player volumio[22201]: info: CorePlayQueue::getTrack 0 Jan 14 12:58:17 music-player volumio[22201]: info: CoreStateMachine::pushState Jan 14 12:58:17 music-player volumio[22201]: info: CorePlayQueue::getTrack 0 Jan 14 12:58:17 music-player volumio[22201]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 14 12:58:17 music-player volumio[22201]: info: CoreCommandRouter::volumioPushState Jan 14 12:58:17 music-player volumio[22201]: info: CoreStateMachine::setRepeat null single undefined Jan 14 12:58:17 music-player volumio[22201]: info: CoreStateMachine::pushState Jan 14 12:58:17 music-player volumio[22201]: info: CorePlayQueue::getTrack 0 Jan 14 12:58:17 music-player volumio[22201]: info: CoreCommandRouter::volumioPushState Jan 14 12:58:17 music-player volumio[22201]: info: CoreStateMachine::setRandom null Jan 14 12:58:17 music-player volumio[22201]: info: CoreStateMachine::pushState Jan 14 12:58:17 music-player volumio[22201]: info: CorePlayQueue::getTrack 0 Jan 14 12:58:17 music-player volumio[22201]: info: CoreCommandRouter::volumioPushState Jan 14 12:58:17 music-player volumio[22201]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jan 14 12:58:17 music-player volumio[22201]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d04170 Jan 14 12:58:17 music-player volumio[22201]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jan 14 12:58:17 music-player volumio[22201]: info: Setting Device type: Raspberry PI Jan 14 12:58:17 music-player volumio[22201]: info: Completed loading Core Plugins Jan 14 12:58:17 music-player volumio[22201]: info: Preparing to generate the ALSA configuration file Jan 14 12:58:17 music-player volumio[22201]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jan 14 12:58:17 music-player volumio[22201]: info: Reading ALSA contributions from plugins. Jan 14 12:58:17 music-player volumio[22201]: info: Asound.conf file written Jan 14 12:58:17 music-player sudo[22332]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jan 14 12:58:17 music-player sudo[22332]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 12:58:17 music-player sudo[22332]: pam_unix(sudo:session): session closed for user root Jan 14 12:58:17 music-player volumio[22201]: info: Output device has changed, restarting MPD Jan 14 12:58:17 music-player sudo[22337]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 14 12:58:17 music-player sudo[22337]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 12:58:17 music-player sudo[22337]: pam_unix(sudo:session): session closed for user root Jan 14 12:58:17 music-player volumio[22201]: info: Output device has changed, restarting Shairport Sync Jan 14 12:58:17 music-player volumio[22201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 12:58:17 music-player volumio[22201]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 14 12:58:17 music-player sudo[22340]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 14 12:58:17 music-player sudo[22340]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 12:58:17 music-player systemd[1]: Stopping Music Player Daemon... Jan 14 12:58:17 music-player volumio[22201]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 14 12:58:17 music-player volumio[22201]: info: ___________ START PLUGINS ___________ Jan 14 12:58:17 music-player systemd[1]: mpd.service: Succeeded. Jan 14 12:58:17 music-player systemd[1]: Stopped Music Player Daemon. Jan 14 12:58:17 music-player volumio[22201]: info: ControllerMpd::onStart: Initializing MPD Jan 14 12:58:17 music-player volumio[22201]: info: Creating MPD Configuration file Jan 14 12:58:17 music-player systemd[1]: Starting Music Player Daemon... Jan 14 12:58:17 music-player sudo[22359]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 14 12:58:17 music-player sudo[22359]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 12:58:17 music-player volumio[22201]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 14 12:58:17 music-player volumio[22201]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 14 12:58:17 music-player volumio[22201]: info: [1736855897449] CoreMusicLibrary::Adding element Media Servers Jan 14 12:58:17 music-player volumio[22201]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 14 12:58:17 music-player sudo[22362]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 14 12:58:17 music-player sudo[22362]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 12:58:17 music-player sudo[22359]: pam_unix(sudo:session): session closed for user root Jan 14 12:58:17 music-player sudo[22354]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 14 12:58:17 music-player volumio[22201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 12:58:17 music-player volumio[22201]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 14 12:58:17 music-player sudo[22354]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 12:58:17 music-player sudo[22354]: pam_unix(sudo:session): session closed for user root Jan 14 12:58:17 music-player systemd[1]: mpd.service: Succeeded. Jan 14 12:58:17 music-player systemd[1]: Stopped Music Player Daemon. Jan 14 12:58:17 music-player volumio[22201]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 14 12:58:17 music-player volumio[22201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 12:58:17 music-player volumio[22201]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 14 12:58:17 music-player systemd[1]: Starting Music Player Daemon... Jan 14 12:58:17 music-player volumio[22201]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 14 12:58:17 music-player volumio[22201]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 14 12:58:17 music-player volumio[22201]: info: [1736855897550] CoreMusicLibrary::Adding element Last_100 Jan 14 12:58:17 music-player volumio[22201]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 14 12:58:17 music-player volumio[22201]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 14 12:58:17 music-player volumio[22201]: info: [1736855897551] CoreMusicLibrary::Adding element Webradio Jan 14 12:58:17 music-player volumio[22201]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 14 12:58:17 music-player volumio[22201]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 14 12:58:17 music-player volumio[22201]: info: Initializing BBC Radios Jan 14 12:58:17 music-player sudo[22366]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 14 12:58:17 music-player sudo[22366]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 12:58:17 music-player sudo[22366]: pam_unix(sudo:session): session closed for user root Jan 14 12:58:17 music-player volumio[22201]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 14 12:58:17 music-player volumio[22201]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 14 12:58:17 music-player volumio[22201]: info: Creating Spotify config file Jan 14 12:58:17 music-player volumio[22201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 12:58:17 music-player volumio[22201]: info: AutoStart - onStart Jan 14 12:58:17 music-player volumio[22201]: info: [now-playing] ConfigUpdater: config is up to date. Jan 14 12:58:17 music-player volumio[22201]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 14 12:58:17 music-player volumio[22201]: info: [1736855897710] CoreMusicLibrary::Adding element LastFM Jan 14 12:58:17 music-player volumio[22201]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 14 12:58:17 music-player volumio[22201]: Cannot find translation for source LastFM Jan 14 12:58:17 music-player volumio[22201]: info: [LastFM] scrobbler initiated! Jan 14 12:58:17 music-player volumio[22201]: info: [LastFM] extended logging: false Jan 14 12:58:17 music-player volumio[22201]: info: [LastFM] try scrobble stream/radio plays: true Jan 14 12:58:17 music-player volumio[22201]: info: [LastFM] Left init routine Jan 14 12:58:17 music-player volumio[22201]: info: [LastFM] Socket already connected: true Jan 14 12:58:17 music-player volumio[22201]: info: Loading i18n strings for locale nl Jan 14 12:58:17 music-player volumio[22201]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Jan 14 12:58:17 music-player volumio[22201]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 14 12:58:17 music-player volumio[22201]: info: Volumio Calling Home Jan 14 12:58:17 music-player sudo[22388]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Jan 14 12:58:17 music-player sudo[22388]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 12:58:17 music-player sudo[22391]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Jan 14 12:58:17 music-player sudo[22391]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 12:58:17 music-player sudo[22388]: pam_unix(sudo:session): session closed for user root Jan 14 12:58:17 music-player systemd[1]: Reloading. Jan 14 12:58:17 music-player sudo[22394]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Jan 14 12:58:17 music-player sudo[22394]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 12:58:17 music-player volumio[22201]: info: [now-playing] App is listening on port 4004. Jan 14 12:58:17 music-player volumio[22201]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Jan 14 12:58:17 music-player volumio[22201]: Unhandled rejection Error: No sockets available, cannot start. Jan 14 12:58:17 music-player volumio[22201]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Jan 14 12:58:17 music-player volumio[22201]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Jan 14 12:58:17 music-player volumio[22201]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Jan 14 12:58:17 music-player volumio[22201]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Jan 14 12:58:17 music-player volumio[22201]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Jan 14 12:58:17 music-player volumio[22201]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Jan 14 12:58:17 music-player volumio[22201]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Jan 14 12:58:17 music-player volumio[22201]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Jan 14 12:58:17 music-player volumio[22201]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Jan 14 12:58:17 music-player volumio[22201]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Jan 14 12:58:17 music-player volumio[22201]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Jan 14 12:58:17 music-player volumio[22201]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Jan 14 12:58:17 music-player volumio[22201]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Jan 14 12:58:17 music-player volumio[22201]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Jan 14 12:58:17 music-player volumio[22201]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Jan 14 12:58:17 music-player volumio[22201]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jan 14 12:58:17 music-player volumio[22201]: info: An error occurred while refreshing Spotify Token Error: getaddrinfo EBUSY oauth-performer.prod.vlmapi.io Jan 14 12:58:17 music-player volumio[22201]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 14 12:58:17 music-player volumio[22201]: Error: getaddrinfo EBUSY ws.audioscrobbler.com Jan 14 12:58:17 music-player volumio[22201]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { Jan 14 12:58:17 music-player volumio[22201]: errno: -16, Jan 14 12:58:17 music-player volumio[22201]: code: 'EBUSY', Jan 14 12:58:17 music-player volumio[22201]: syscall: 'getaddrinfo', Jan 14 12:58:17 music-player volumio[22201]: hostname: 'ws.audioscrobbler.com' Jan 14 12:58:17 music-player volumio[22201]: } Jan 14 12:58:17 music-player volumio[22201]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 14 12:58:17 music-player mpd[22374]: Jan 14 12:58 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 14 12:58:17 music-player 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. Jan 14 12:58:18 music-player sudo[22241]: pam_unix(sudo:session): session closed for user root Jan 14 12:58:18 music-player sudo[22420]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-14 12:57 Jan 14 12:58:18 music-player sudo[22420]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 12:58:18 music-player sudo[22420]: pam_unix(sudo:session): session closed for user root Jan 14 12:58:18 music-player systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jan 14 12:58:18 music-player sudo[22391]: pam_unix(sudo:session): session closed for user root Jan 14 12:58:18 music-player sudo[22340]: pam_unix(sudo:session): session closed for user root Jan 14 12:58:18 music-player sudo[22362]: pam_unix(sudo:session): session closed for user root Jan 14 12:58:18 music-player sudo[22394]: pam_unix(sudo:session): session closed for user root Jan 14 12:58:18 music-player systemd[1]: volumio.service: Failed with result 'exit-code'. Jan 14 12:58:18 music-player systemd[1]: Started dynamicswap service. Jan 14 12:58:18 music-player systemd[1]: Starting Daily apt download activities... Jan 14 12:58:18 music-player systemd[1]: dynamicswap.service: Succeeded. Jan 14 12:58:18 music-player systemd[1]: Started Music Player Daemon. Jan 14 12:58:18 music-player volumio-remote-updater[767]: [2025-01-14 12:58:18] [info] asio async_connect error: system:111 (Connection refused) Jan 14 12:58:18 music-player volumio-remote-updater[767]: [2025-01-14 12:58:18] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jan 14 12:58:18 music-player volumio-remote-updater[767]: [2025-01-14 12:58:18] [error] handle_connect error: Underlying Transport Error Jan 14 12:58:18 music-player systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jan 14 12:58:18 music-player systemd[1]: volumio.service: Scheduled restart job, restart counter is at 2. Jan 14 12:58:18 music-player systemd[1]: Stopped Volumio Backend Module. Jan 14 12:58:18 music-player systemd[1]: Started Volumio Backend Module. Jan 14 12:58:18 music-player systemd[1]: Started dynamicswap service. Jan 14 12:58:18 music-player systemd[1]: dynamicswap.service: Succeeded. Jan 14 12:58:18 music-player dhcpcd[1228]: wlan0: probing address 192.168.1.189/24 Jan 14 12:58:19 music-player systemd[1]: apt-daily.service: Succeeded. Jan 14 12:58:19 music-player systemd[1]: Started Daily apt download activities. Jan 14 12:58:19 music-player volumio[22454]: info: ------------------------------------------- Jan 14 12:58:19 music-player volumio[22454]: info: ----- Volumio3 ---- Jan 14 12:58:19 music-player volumio[22454]: info: ------------------------------------------- Jan 14 12:58:19 music-player volumio[22454]: info: ----- System startup ---- Jan 14 12:58:19 music-player volumio[22454]: info: ------------------------------------------- Jan 14 12:58:19 music-player volumio[22454]: info: MYVOLUMIO Environment detected Jan 14 12:58:19 music-player volumio[22454]: info: Plugin folders cleanup Jan 14 12:58:19 music-player volumio[22454]: info: Scanning into folder /volumio/app/plugins/ Jan 14 12:58:19 music-player volumio[22454]: info: Scanning category audio_interface Jan 14 12:58:19 music-player volumio[22454]: info: Scanning category miscellanea Jan 14 12:58:19 music-player volumio[22454]: info: Scanning category music_service Jan 14 12:58:19 music-player volumio[22454]: info: Scanning category plugins.json Jan 14 12:58:19 music-player volumio[22454]: info: Scanning category system_controller Jan 14 12:58:19 music-player volumio[22454]: info: Scanning category user_interface Jan 14 12:58:19 music-player volumio[22454]: info: Scanning into folder /data/plugins/ Jan 14 12:58:19 music-player volumio[22454]: info: Scanning category music_service Jan 14 12:58:19 music-player volumio[22454]: info: Scanning category system_controller Jan 14 12:58:19 music-player volumio[22454]: info: Scanning category user_interface Jan 14 12:58:19 music-player volumio[22454]: info: Plugin folders cleanup completed Jan 14 12:58:19 music-player volumio[22454]: info: ------------------------------------------- Jan 14 12:58:19 music-player volumio[22454]: info: ----- Core plugins startup ---- Jan 14 12:58:19 music-player volumio[22454]: info: ------------------------------------------- Jan 14 12:58:19 music-player volumio[22454]: info: Loading plugins from folder /volumio/app/plugins/ Jan 14 12:58:19 music-player volumio[22454]: info: Adding plugin upnp to MyMusic Plugins Jan 14 12:58:19 music-player volumio[22454]: info: Adding plugin airplay_emulation to MyMusic Plugins Jan 14 12:58:19 music-player volumio[22454]: info: Adding plugin upnp_browser to MyMusic Plugins Jan 14 12:58:19 music-player volumio[22454]: info: Loading plugins from folder /data/plugins/ Jan 14 12:58:19 music-player volumio[22454]: info: Loading plugin "system"... Jan 14 12:58:19 music-player volumio[22454]: info: Loading plugin "ampswitch"... Jan 14 12:58:19 music-player volumio[22454]: info: Applying required configuration parameters for plugin ampswitch Jan 14 12:58:19 music-player volumio[22454]: info: Loading plugin "appearance"... Jan 14 12:58:19 music-player volumio[22454]: info: Loading plugin "network"... Jan 14 12:58:19 music-player volumio[22454]: info: Refreshing Cached IP Addresses Jan 14 12:58:19 music-player sudo[22511]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 14 12:58:19 music-player sudo[22511]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 12:58:19 music-player sudo[22511]: pam_unix(sudo:session): session closed for user root Jan 14 12:58:19 music-player sudo[22513]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 14 12:58:19 music-player sudo[22513]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 12:58:19 music-player volumio[22454]: info: Loading plugin "services"... Jan 14 12:58:19 music-player volumio[22454]: info: Loading plugin "alsa_controller"... Jan 14 12:58:19 music-player sudo[22522]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jan 14 12:58:19 music-player sudo[22522]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 12:58:19 music-player sudo[22513]: pam_unix(sudo:session): session closed for user root Jan 14 12:58:19 music-player volumio[22454]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 14 12:58:19 music-player volumio[22454]: info: Loading plugin "wizard"... Jan 14 12:58:19 music-player volumio[22454]: info: Loading plugin "networkfs"... Jan 14 12:58:19 music-player volumio[22454]: info: Cannot mount NAS Volumio at system boot, trial number 1 ,retrying in 5 seconds Jan 14 12:58:19 music-player volumio[22454]: info: Cannot mount NAS muziek at system boot, trial number 1 ,retrying in 5 seconds Jan 14 12:58:19 music-player volumio[22454]: info: Starting Udev Watcher for removable devices Jan 14 12:58:19 music-player volumio[22454]: info: Ignoring mount for partition: boot Jan 14 12:58:19 music-player volumio[22454]: info: Ignoring mount for partition: volumio Jan 14 12:58:19 music-player volumio[22454]: info: Ignoring mount for partition: volumio_data Jan 14 12:58:19 music-player volumio[22454]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 14 12:58:19 music-player volumio[22454]: info: Loading plugin "volumio_command_line_client"... Jan 14 12:58:19 music-player volumio[22454]: info: Loading plugin "upnp"... Jan 14 12:58:19 music-player volumio[22454]: info: [1736855899647] Starting Upmpd Daemon Jan 14 12:58:19 music-player volumio[22454]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 14 12:58:19 music-player volumio[22454]: info: Loading plugin "my_music"... Jan 14 12:58:19 music-player volumio[22454]: info: Loading plugin "mpd"... Jan 14 12:58:19 music-player ifplugd(eth0)[1006]: Executing '/etc/ifplugd/ifplugd.action eth0 down'. Jan 14 12:58:19 music-player dhcpcd[22544]: sending commands to master dhcpcd process Jan 14 12:58:19 music-player dhcpcd[1228]: control command: /sbin/dhcpcd -k eth0 Jan 14 12:58:19 music-player dhcpcd[1228]: eth0: removing interface Jan 14 12:58:19 music-player ifplugd(eth0)[1006]: client: sending commands to master dhcpcd process Jan 14 12:58:19 music-player kernel: macb 1f00100000.ethernet: gem-ptp-timer ptp clock unregistered. Jan 14 12:58:19 music-player ifplugd(eth0)[1006]: Program executed successfully. Jan 14 12:58:19 music-player volumio[22454]: info: Loading plugin "upnp_browser"... Jan 14 12:58:19 music-player kernel: macb 1f00100000.ethernet eth0: PHY [1f00100000.ethernet-ffffffff:01] driver [Broadcom BCM54213PE] (irq=POLL) Jan 14 12:58:19 music-player kernel: macb 1f00100000.ethernet eth0: configuring for phy/rgmii-id link mode Jan 14 12:58:19 music-player kernel: pps pps0: new PPS source ptp0 Jan 14 12:58:19 music-player kernel: macb 1f00100000.ethernet: gem-ptp-timer ptp clock registered. Jan 14 12:58:19 music-player volumio[22454]: info: Loading plugin "alarm-clock"... Jan 14 12:58:19 music-player volumio[22454]: info: Loading plugin "airplay_emulation"... Jan 14 12:58:19 music-player volumio[22454]: info: Starting Shairport Sync Jan 14 12:58:19 music-player volumio[22454]: info: Loading plugin "last_100"... Jan 14 12:58:19 music-player volumio[22454]: info: Loading plugin "webradio"... Jan 14 12:58:19 music-player volumio[22454]: info: Loading plugin "i2s_dacs"... Jan 14 12:58:19 music-player volumio[22454]: info: Loading plugin "volumiodiscovery"... Jan 14 12:58:19 music-player volumio[22454]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 14 12:58:19 music-player volumio[22454]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 14 12:58:19 music-player volumio[22454]: *** WARNING *** For more information see Jan 14 12:58:19 music-player volumio[22454]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 14 12:58:19 music-player volumio[22454]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 14 12:58:19 music-player volumio[22454]: *** WARNING *** For more information see Jan 14 12:58:19 music-player node[22454]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 14 12:58:19 music-player node[22454]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 14 12:58:19 music-player node[22454]: *** WARNING *** For more information see Jan 14 12:58:19 music-player node[22454]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 14 12:58:19 music-player node[22454]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 14 12:58:19 music-player node[22454]: *** WARNING *** For more information see Jan 14 12:58:19 music-player volumio[22454]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 14 12:58:19 music-player volumio[22454]: info: Discovery: Started advertising with name: Music Player Jan 14 12:58:19 music-player volumio[22454]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 14 12:58:19 music-player volumio[22454]: info: Loading plugin "spop"... Jan 14 12:58:20 music-player volumio[22454]: info: Loading plugin "ytcr"... Jan 14 12:58:20 music-player volumio[22454]: info: Loading plugin "autostart"... Jan 14 12:58:20 music-player volumio[22454]: info: Applying required configuration parameters for plugin autostart Jan 14 12:58:20 music-player volumio[22454]: info: AutoStart - onVolumioStart - read config.json Jan 14 12:58:20 music-player volumio[22454]: info: Loading plugin "now_playing"... Jan 14 12:58:21 music-player volumio[22454]: info: Loading plugin "outputs"... Jan 14 12:58:21 music-player volumio[22454]: info: Loading plugin "albumart"... Jan 14 12:58:21 music-player volumio[22454]: info: Plugin example_plugin is not enabled Jan 14 12:58:21 music-player volumio[22454]: info: Loading plugin "inputs"... Jan 14 12:58:21 music-player volumio[22454]: info: Loading plugin "updater_comm"... Jan 14 12:58:21 music-player volumio[22454]: info: Plugin mpdemulation is not enabled Jan 14 12:58:21 music-player volumio[22454]: info: Loading plugin "rest_api"... Jan 14 12:58:21 music-player volumio[22454]: info: Loading plugin "websocket"... Jan 14 12:58:21 music-player volumio[22454]: info: Starting Socket.io Server version 2.3.0 Jan 14 12:58:21 music-player volumio[22454]: info: Loading plugin "backup_restore"... Jan 14 12:58:21 music-player volumio[22454]: Forking 3 albumart workers Jan 14 12:58:21 music-player volumio[22454]: info: Applying required configuration parameters for plugin backup_restore Jan 14 12:58:21 music-player volumio[22454]: info: Loading plugin "Systeminfo"... Jan 14 12:58:21 music-player volumio[22454]: info: Loading plugin "lastfm"... Jan 14 12:58:21 music-player volumio[22454]: Starting albumart workers Jan 14 12:58:21 music-player volumio[22454]: Starting albumart workers Jan 14 12:58:21 music-player volumio[22454]: info: Plugin peppymeterbasic is not enabled Jan 14 12:58:21 music-player volumio[22454]: info: Loading plugin "touch_display"... Jan 14 12:58:21 music-player volumio[22454]: Starting albumart workers Jan 14 12:58:21 music-player volumio[22454]: info: Applying required configuration parameters for plugin touch_display Jan 14 12:58:21 music-player volumio[22454]: info: Loading i18n strings for locale nl Jan 14 12:58:21 music-player volumio[22454]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Jan 14 12:58:21 music-player volumio[22454]: Updating browse sources language Jan 14 12:58:21 music-player volumio[22454]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 14 12:58:21 music-player volumio[22454]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 14 12:58:21 music-player volumio[22454]: info: CoreCommandRouter::initPlayerControls Jan 14 12:58:21 music-player volumio[22454]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 12:58:21 music-player volumio[22454]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 12:58:21 music-player volumio[22454]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 12:58:21 music-player volumio[22454]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 12:58:21 music-player volumio[22454]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 12:58:21 music-player volumio[22454]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 12:58:21 music-player volumio[22454]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 12:58:21 music-player volumio[22454]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 12:58:21 music-player volumio[22454]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 14 12:58:21 music-player volumio[22454]: Express server listening on port 3000 Jan 14 12:58:21 music-player volumio[22454]: [Metrics] WebUI: 3s 17.77ms Jan 14 12:58:21 music-player volumio[22454]: info: CoreStateMachine::resetVolumioState Jan 14 12:58:21 music-player volumio[22454]: info: CoreStateMachine::getcurrentVolume Jan 14 12:58:21 music-player volumio[22454]: info: CoreCommandRouter::volumioRetrievevolume Jan 14 12:58:21 music-player volumio[22454]: info: Volumio Network Manager: Network status updated: 0 Jan 14 12:58:21 music-player volumio[22454]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS aarch64 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Jan 14 12:58:21 music-player volumio[22454]: info: CoreStateMachine::pushState Jan 14 12:58:21 music-player volumio[22454]: info: CorePlayQueue::getTrack 0 Jan 14 12:58:21 music-player volumio[22454]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 14 12:58:21 music-player volumio[22454]: info: CoreCommandRouter::volumioPushState Jan 14 12:58:21 music-player volumio[22454]: info: CoreStateMachine::updateTrackBlock Jan 14 12:58:21 music-player volumio[22454]: info: CorePlayQueue::getTrackBlock Jan 14 12:58:21 music-player volumio[22454]: info: CoreCommandRouter::volumioRetrievevolume Jan 14 12:58:21 music-player volumio[22454]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Jan 14 12:58:21 music-player volumio[22454]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Jan 14 12:58:21 music-player volumio[22454]: error: Failed callmethod call: TypeError: Cannot read property 'has' of undefined Jan 14 12:58:21 music-player volumio[22454]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jan 14 12:58:21 music-player volumio[22454]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Jan 14 12:58:21 music-player volumio[22454]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Jan 14 12:58:22 music-player volumio[22454]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 14 12:58:22 music-player volumio[22454]: info: Reloading queue from file Jan 14 12:58:22 music-player volumio[22454]: info: Listing playlists Jan 14 12:58:22 music-player volumio[22454]: info: CoreCommandRouter::volumioGetQueue Jan 14 12:58:22 music-player volumio[22454]: info: CoreStateMachine::getQueue Jan 14 12:58:22 music-player volumio[22454]: info: CorePlayQueue::getQueue Jan 14 12:58:22 music-player volumio[22454]: info: CoreCommandRouter::volumioGetVisibleSources Jan 14 12:58:22 music-player volumio[22454]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 14 12:58:22 music-player volumio[22454]: info: CoreCommandRouter::volumioGetState Jan 14 12:58:22 music-player volumio[22454]: info: CorePlayQueue::getTrack 0 Jan 14 12:58:22 music-player volumio[22454]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Jan 14 12:58:22 music-player volumio[22454]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Jan 14 12:58:22 music-player volumio[22454]: error: Failed callmethod call: TypeError: Cannot read property 'has' of undefined Jan 14 12:58:22 music-player volumio[22454]: info: Listing playlists Jan 14 12:58:22 music-player volumio[22454]: info: CoreCommandRouter::volumioGetQueue Jan 14 12:58:22 music-player volumio[22454]: info: CoreStateMachine::getQueue Jan 14 12:58:22 music-player volumio[22454]: info: CorePlayQueue::getQueue Jan 14 12:58:22 music-player volumio[22454]: info: CoreCommandRouter::volumioGetVisibleSources Jan 14 12:58:22 music-player volumio[22454]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 14 12:58:22 music-player volumio[22454]: info: CoreCommandRouter::volumioGetState Jan 14 12:58:22 music-player volumio[22454]: info: CorePlayQueue::getTrack 0 Jan 14 12:58:22 music-player volumio[22454]: info: CoreStateMachine::pushState Jan 14 12:58:22 music-player volumio[22454]: info: CorePlayQueue::getTrack 0 Jan 14 12:58:22 music-player volumio[22454]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 14 12:58:22 music-player volumio[22454]: info: CoreCommandRouter::volumioPushState Jan 14 12:58:22 music-player volumio[22454]: info: CoreStateMachine::setRepeat null single undefined Jan 14 12:58:22 music-player volumio[22454]: info: CoreStateMachine::pushState Jan 14 12:58:22 music-player volumio[22454]: info: CorePlayQueue::getTrack 0 Jan 14 12:58:22 music-player volumio[22454]: info: CoreCommandRouter::volumioPushState Jan 14 12:58:22 music-player volumio[22454]: info: CoreStateMachine::setRandom null Jan 14 12:58:22 music-player volumio[22454]: info: CoreStateMachine::pushState Jan 14 12:58:22 music-player volumio[22454]: info: CorePlayQueue::getTrack 0 Jan 14 12:58:22 music-player volumio[22454]: info: CoreCommandRouter::volumioPushState Jan 14 12:58:22 music-player volumio[22454]: info: Setting Device type: Raspberry PI Jan 14 12:58:22 music-player volumio[22454]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jan 14 12:58:22 music-player volumio[22454]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d04170 Jan 14 12:58:22 music-player volumio[22454]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jan 14 12:58:22 music-player volumio[22454]: info: Completed loading Core Plugins Jan 14 12:58:22 music-player volumio[22454]: info: Preparing to generate the ALSA configuration file Jan 14 12:58:22 music-player volumio[22454]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jan 14 12:58:22 music-player volumio[22454]: info: Reading ALSA contributions from plugins. Jan 14 12:58:22 music-player volumio[22454]: info: Asound.conf file unchanged, so no further update is needed Jan 14 12:58:22 music-player volumio[22454]: info: Output device has changed, restarting MPD Jan 14 12:58:22 music-player sudo[22646]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 14 12:58:22 music-player sudo[22646]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 12:58:22 music-player sudo[22646]: pam_unix(sudo:session): session closed for user root Jan 14 12:58:22 music-player volumio[22454]: info: Output device has changed, restarting Shairport Sync Jan 14 12:58:22 music-player volumio[22454]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 12:58:22 music-player volumio[22454]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 14 12:58:22 music-player sudo[22649]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 14 12:58:22 music-player sudo[22649]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 12:58:22 music-player systemd[1]: Stopping Music Player Daemon... Jan 14 12:58:22 music-player volumio[22454]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 14 12:58:22 music-player volumio[22454]: info: ___________ START PLUGINS ___________ Jan 14 12:58:22 music-player systemd[1]: mpd.service: Succeeded. Jan 14 12:58:22 music-player systemd[1]: Stopped Music Player Daemon. Jan 14 12:58:22 music-player volumio[22454]: info: ControllerMpd::onStart: Initializing MPD Jan 14 12:58:22 music-player volumio[22454]: info: Creating MPD Configuration file Jan 14 12:58:22 music-player systemd[1]: Starting Music Player Daemon... Jan 14 12:58:22 music-player sudo[22661]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 14 12:58:22 music-player sudo[22661]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 12:58:22 music-player sudo[22661]: pam_unix(sudo:session): session closed for user root Jan 14 12:58:22 music-player volumio[22454]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 14 12:58:22 music-player volumio[22454]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 14 12:58:22 music-player volumio[22454]: info: [1736855902297] CoreMusicLibrary::Adding element Media Servers Jan 14 12:58:22 music-player volumio[22454]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 14 12:58:22 music-player sudo[22656]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 14 12:58:22 music-player sudo[22656]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 12:58:22 music-player sudo[22656]: pam_unix(sudo:session): session closed for user root Jan 14 12:58:22 music-player sudo[22664]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 14 12:58:22 music-player sudo[22664]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 12:58:22 music-player volumio[22454]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 12:58:22 music-player volumio[22454]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 14 12:58:22 music-player systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jan 14 12:58:22 music-player systemd[1]: mpd.service: Succeeded. Jan 14 12:58:22 music-player systemd[1]: Stopped Music Player Daemon. Jan 14 12:58:22 music-player systemd[1]: Starting Music Player Daemon... Jan 14 12:58:22 music-player volumio[22454]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 14 12:58:22 music-player volumio[22454]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 12:58:22 music-player volumio[22454]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 14 12:58:22 music-player sudo[22669]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 14 12:58:22 music-player sudo[22669]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 12:58:22 music-player sudo[22669]: pam_unix(sudo:session): session closed for user root Jan 14 12:58:22 music-player volumio[22454]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 14 12:58:22 music-player volumio[22454]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 14 12:58:22 music-player volumio[22454]: info: [1736855902378] CoreMusicLibrary::Adding element Last_100 Jan 14 12:58:22 music-player volumio[22454]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 14 12:58:22 music-player volumio[22454]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 14 12:58:22 music-player volumio[22454]: info: [1736855902379] CoreMusicLibrary::Adding element Webradio Jan 14 12:58:22 music-player volumio[22454]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 14 12:58:22 music-player volumio[22454]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 14 12:58:22 music-player volumio[22454]: info: Initializing BBC Radios Jan 14 12:58:22 music-player volumio[22454]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 14 12:58:22 music-player volumio[22454]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 14 12:58:22 music-player volumio[22454]: info: Creating Spotify config file Jan 14 12:58:22 music-player volumio[22454]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 12:58:22 music-player volumio[22454]: info: AutoStart - onStart Jan 14 12:58:22 music-player volumio[22454]: info: [now-playing] ConfigUpdater: config is up to date. Jan 14 12:58:22 music-player volumio[22454]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 14 12:58:22 music-player volumio[22454]: info: [1736855902479] CoreMusicLibrary::Adding element LastFM Jan 14 12:58:22 music-player volumio[22454]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 14 12:58:22 music-player volumio[22454]: Cannot find translation for source LastFM Jan 14 12:58:22 music-player volumio[22454]: info: [LastFM] scrobbler initiated! Jan 14 12:58:22 music-player volumio[22454]: info: [LastFM] extended logging: false Jan 14 12:58:22 music-player volumio[22454]: info: [LastFM] try scrobble stream/radio plays: true Jan 14 12:58:22 music-player volumio[22454]: info: [LastFM] Left init routine Jan 14 12:58:22 music-player volumio[22454]: info: [LastFM] Socket already connected: true Jan 14 12:58:22 music-player volumio[22454]: info: Loading i18n strings for locale nl Jan 14 12:58:22 music-player volumio[22454]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Jan 14 12:58:22 music-player volumio[22454]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 14 12:58:22 music-player volumio[22454]: info: Volumio Calling Home Jan 14 12:58:22 music-player sudo[22694]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Jan 14 12:58:22 music-player sudo[22694]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 12:58:22 music-player sudo[22695]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Jan 14 12:58:22 music-player sudo[22695]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 12:58:22 music-player systemd[1]: Reloading. Jan 14 12:58:22 music-player sudo[22699]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Jan 14 12:58:22 music-player sudo[22699]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 12:58:22 music-player volumio[22454]: info: [now-playing] App is listening on port 4004. Jan 14 12:58:22 music-player volumio[22454]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Jan 14 12:58:22 music-player volumio[22454]: Unhandled rejection Error: No sockets available, cannot start. Jan 14 12:58:22 music-player volumio[22454]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Jan 14 12:58:22 music-player volumio[22454]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Jan 14 12:58:22 music-player volumio[22454]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Jan 14 12:58:22 music-player volumio[22454]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Jan 14 12:58:22 music-player volumio[22454]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Jan 14 12:58:22 music-player volumio[22454]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Jan 14 12:58:22 music-player volumio[22454]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Jan 14 12:58:22 music-player volumio[22454]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Jan 14 12:58:22 music-player volumio[22454]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Jan 14 12:58:22 music-player volumio[22454]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Jan 14 12:58:22 music-player volumio[22454]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Jan 14 12:58:22 music-player volumio[22454]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Jan 14 12:58:22 music-player volumio[22454]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Jan 14 12:58:22 music-player volumio[22454]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Jan 14 12:58:22 music-player volumio[22454]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Jan 14 12:58:22 music-player volumio[22454]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jan 14 12:58:22 music-player volumio[22454]: info: An error occurred while refreshing Spotify Token Error: getaddrinfo EBUSY oauth-performer.prod.vlmapi.io Jan 14 12:58:22 music-player volumio[22454]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 14 12:58:22 music-player volumio[22454]: Error: getaddrinfo EBUSY ws.audioscrobbler.com Jan 14 12:58:22 music-player volumio[22454]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { Jan 14 12:58:22 music-player volumio[22454]: errno: -16, Jan 14 12:58:22 music-player volumio[22454]: code: 'EBUSY', Jan 14 12:58:22 music-player volumio[22454]: syscall: 'getaddrinfo', Jan 14 12:58:22 music-player volumio[22454]: hostname: 'ws.audioscrobbler.com' Jan 14 12:58:22 music-player volumio[22454]: } Jan 14 12:58:22 music-player volumio[22454]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 14 12:58:22 music-player mpd[22676]: Jan 14 12:58 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 14 12:58:22 music-player 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. Jan 14 12:58:22 music-player sudo[22522]: pam_unix(sudo:session): session closed for user root Jan 14 12:58:22 music-player sudo[22725]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-14 12:57 Jan 14 12:58:22 music-player sudo[22725]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 12:58:22 music-player sudo[22695]: pam_unix(sudo:session): session closed for user root 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="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"